builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0051 starttime: 1468610009.96 results: success (0) buildid: 20160715071711 builduid: 5bb232df08fb4db89da60c78a430f9fa revision: 2f9e69c982f1e67887a1834b36ff0af4ababb3af ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-15 12:13:29.965110) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-15 12:13:29.965570) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-15 12:13:29.965871) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-15 12:13:30.193463) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 12:13:30.279241) ========= 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.8mJZMheXSL/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.Arn37w5ps1/Listeners TMPDIR=/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/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-07-15 12:13:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 12.9M=0.001s 2016-07-15 12:13:30 (12.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.106653 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 12:13:30.409727) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 12:13:30.410047) ========= 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.8mJZMheXSL/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.Arn37w5ps1/Listeners TMPDIR=/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/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.034183 ========= master_lag: 0.17 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 12:13:30.609573) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 12:13:30.613956) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2f9e69c982f1e67887a1834b36ff0af4ababb3af --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 2f9e69c982f1e67887a1834b36ff0af4ababb3af --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8mJZMheXSL/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.Arn37w5ps1/Listeners TMPDIR=/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/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-07-15 12:13:30,813 truncating revision to first 12 chars 2016-07-15 12:13:30,813 Setting DEBUG logging. 2016-07-15 12:13:30,813 attempt 1/10 2016-07-15 12:13:30,813 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2f9e69c982f1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-15 12:13:31,126 unpacking tar archive at: mozilla-central-2f9e69c982f1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.562346 ========= master_lag: 0.30 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 12:13:31.477739) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-15 12:13:31.478044) ========= script_repo_revision: 2f9e69c982f1e67887a1834b36ff0af4ababb3af ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-15 12:13:31.478395) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-15 12:13:31.478667) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-15 12:13:31.525236) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-07-15 12:13:31.525530) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8mJZMheXSL/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.Arn37w5ps1/Listeners TMPDIR=/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/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 12:13:31 INFO - MultiFileLogger online at 20160715 12:13:31 in /builds/slave/test 12:13:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 12:13:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:13:31 INFO - {'append_to_log': False, 12:13:31 INFO - 'base_work_dir': '/builds/slave/test', 12:13:31 INFO - 'blob_upload_branch': 'Firefox', 12:13:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:13:31 INFO - 'branch': 'Firefox', 12:13:31 INFO - 'buildbot_json_path': 'buildprops.json', 12:13:31 INFO - 'config_files': ('talos/mac_config.py',), 12:13:31 INFO - 'default_actions': ('clobber', 12:13:31 INFO - 'read-buildbot-config', 12:13:31 INFO - 'download-and-extract', 12:13:31 INFO - 'populate-webroot', 12:13:31 INFO - 'create-virtualenv', 12:13:31 INFO - 'install', 12:13:31 INFO - 'run-tests'), 12:13:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:13:31 INFO - 'download_minidump_stackwalk': True, 12:13:31 INFO - 'download_symbols': 'ondemand', 12:13:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:13:31 INFO - 'tooltool.py': '/tools/tooltool.py', 12:13:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:13:31 INFO - '/tools/misc-python/virtualenv.py')}, 12:13:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:13:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:13:31 INFO - 'installer_path': 'installer.exe', 12:13:31 INFO - 'log_level': 'info', 12:13:31 INFO - 'log_name': 'talos', 12:13:31 INFO - 'log_to_console': True, 12:13:31 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:13:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:13:31 INFO - 'opt_config_files': (), 12:13:31 INFO - 'pip_index': False, 12:13:31 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:13:31 INFO - 'cmd': ('bash', 12:13:31 INFO - '-c', 12:13:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:13:31 INFO - 'enabled': True, 12:13:31 INFO - 'halt_on_failure': False, 12:13:31 INFO - 'name': 'check_screen_resolution'},), 12:13:31 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:13:31 INFO - 'cmd': ('bash', 12:13:31 INFO - '-c', 12:13:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:13:31 INFO - 'enabled': True, 12:13:31 INFO - 'halt_on_failure': False, 12:13:31 INFO - 'name': 'check_screen_resolution'},), 12:13:31 INFO - 'run_cmd_checks_enabled': True, 12:13:31 INFO - 'sps_profile': False, 12:13:31 INFO - 'sps_profile_interval': 0, 12:13:31 INFO - 'suite': 'dromaeojs-e10s', 12:13:31 INFO - 'system_bits': '32', 12:13:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:13:31 INFO - 'title': 't-yosemite-r7-0051', 12:13:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:13:31 INFO - 'use_talos_json': True, 12:13:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:13:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:13:31 INFO - 'work_dir': 'build'} 12:13:31 INFO - ##### 12:13:31 INFO - ##### Running clobber step. 12:13:31 INFO - ##### 12:13:31 INFO - Running pre-action listener: _resource_record_pre_action 12:13:31 INFO - Running main action method: clobber 12:13:31 INFO - rmtree: /builds/slave/test/build 12:13:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:13:33 INFO - Running post-action listener: _resource_record_post_action 12:13:33 INFO - ##### 12:13:33 INFO - ##### Finished clobber step (success) 12:13:33 INFO - ##### 12:13:33 INFO - ##### 12:13:33 INFO - ##### Running read-buildbot-config step. 12:13:33 INFO - ##### 12:13:33 INFO - Running pre-action listener: _resource_record_pre_action 12:13:33 INFO - Running main action method: read_buildbot_config 12:13:33 INFO - Using buildbot properties: 12:13:33 INFO - { 12:13:33 INFO - "project": "", 12:13:33 INFO - "product": "firefox", 12:13:33 INFO - "script_repo_revision": "production", 12:13:33 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 12:13:33 INFO - "repository": "", 12:13:33 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 12:13:33 INFO - "buildid": "20160715071711", 12:13:33 INFO - "pgo_build": "False", 12:13:33 INFO - "basedir": "/builds/slave/test", 12:13:33 INFO - "buildnumber": 50, 12:13:33 INFO - "slavename": "t-yosemite-r7-0051", 12:13:33 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 12:13:33 INFO - "platform": "yosemite_r7", 12:13:33 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 12:13:33 INFO - "branch": "mozilla-central", 12:13:33 INFO - "repo_path": "mozilla-central", 12:13:33 INFO - "revision": "2f9e69c982f1e67887a1834b36ff0af4ababb3af", 12:13:33 INFO - "stage_platform": "macosx64", 12:13:33 INFO - "builduid": "5bb232df08fb4db89da60c78a430f9fa", 12:13:33 INFO - "slavebuilddir": "test" 12:13:33 INFO - } 12:13:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/RLV4jmnVQ6OhdEMMsDO4nw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 12:13:33 INFO - Running post-action listener: _resource_record_post_action 12:13:33 INFO - ##### 12:13:33 INFO - ##### Finished read-buildbot-config step (success) 12:13:33 INFO - ##### 12:13:33 INFO - ##### 12:13:33 INFO - ##### Running download-and-extract step. 12:13:33 INFO - ##### 12:13:33 INFO - Running pre-action listener: _resource_record_pre_action 12:13:33 INFO - Running main action method: download_and_extract 12:13:33 INFO - mkdir: /builds/slave/test/build/tests 12:13:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:13:33 INFO - https://queue.taskcluster.net/v1/task/RLV4jmnVQ6OhdEMMsDO4nw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 12:13:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RLV4jmnVQ6OhdEMMsDO4nw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 12:13:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RLV4jmnVQ6OhdEMMsDO4nw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 12:13:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RLV4jmnVQ6OhdEMMsDO4nw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 12:13:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RLV4jmnVQ6OhdEMMsDO4nw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 12:13:34 INFO - Downloaded 1199 bytes. 12:13:34 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 12:13:34 INFO - Using the following test package requirements: 12:13:34 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 12:13:34 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:13:34 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 12:13:34 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:13:34 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 12:13:34 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:13:34 INFO - u'jsshell-mac.zip'], 12:13:34 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:13:34 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 12:13:34 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 12:13:34 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:13:34 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 12:13:34 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:13:34 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 12:13:34 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:13:34 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 12:13:34 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:13:34 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 12:13:34 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:13:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:13:34 INFO - https://queue.taskcluster.net/v1/task/RLV4jmnVQ6OhdEMMsDO4nw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:13:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RLV4jmnVQ6OhdEMMsDO4nw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:13:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RLV4jmnVQ6OhdEMMsDO4nw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:13:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RLV4jmnVQ6OhdEMMsDO4nw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:13:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RLV4jmnVQ6OhdEMMsDO4nw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:13:35 INFO - Downloaded 18829159 bytes. 12:13:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:13:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:13:38 INFO - Return code: 0 12:13:38 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 12:13: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')]} 12:13:38 INFO - https://queue.taskcluster.net/v1/task/RLV4jmnVQ6OhdEMMsDO4nw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:13:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RLV4jmnVQ6OhdEMMsDO4nw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:13:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RLV4jmnVQ6OhdEMMsDO4nw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:13:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RLV4jmnVQ6OhdEMMsDO4nw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:13:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RLV4jmnVQ6OhdEMMsDO4nw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:13:40 INFO - Downloaded 18829159 bytes. 12:13:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:13:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:13:44 INFO - Return code: 0 12:13: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')]} 12:13:44 INFO - https://queue.taskcluster.net/v1/task/RLV4jmnVQ6OhdEMMsDO4nw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 12:13:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RLV4jmnVQ6OhdEMMsDO4nw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 12:13:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RLV4jmnVQ6OhdEMMsDO4nw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 12:13:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RLV4jmnVQ6OhdEMMsDO4nw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 12:13:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RLV4jmnVQ6OhdEMMsDO4nw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:13:45 INFO - Downloaded 14403317 bytes. 12:13:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:13:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 12:13:45 INFO - Return code: 0 12:13:45 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')]} 12:13:45 INFO - https://queue.taskcluster.net/v1/task/RLV4jmnVQ6OhdEMMsDO4nw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 12:13:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RLV4jmnVQ6OhdEMMsDO4nw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 12:13:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RLV4jmnVQ6OhdEMMsDO4nw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 12:13:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RLV4jmnVQ6OhdEMMsDO4nw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 12:13:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RLV4jmnVQ6OhdEMMsDO4nw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 12:13:48 INFO - Downloaded 112789995 bytes. 12:13:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RLV4jmnVQ6OhdEMMsDO4nw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 12:13:48 INFO - mkdir: /builds/slave/test/properties 12:13:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:13:48 INFO - Writing to file /builds/slave/test/properties/build_url 12:13:48 INFO - Contents: 12:13:48 INFO - build_url:https://queue.taskcluster.net/v1/task/RLV4jmnVQ6OhdEMMsDO4nw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 12:13:49 INFO - Running post-action listener: _resource_record_post_action 12:13:49 INFO - Running post-action listener: set_extra_try_arguments 12:13:49 INFO - ##### 12:13:49 INFO - ##### Finished download-and-extract step (success) 12:13:49 INFO - ##### 12:13:49 INFO - ##### 12:13:49 INFO - ##### Running populate-webroot step. 12:13:49 INFO - ##### 12:13:49 INFO - Running pre-action listener: _resource_record_pre_action 12:13:49 INFO - Running main action method: populate_webroot 12:13:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:13:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:13:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:13:49 INFO - '--tppagecycles', 12:13:49 INFO - '7'], 12:13:49 INFO - 'tests': ['tsvgm']}}, 12:13:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:13:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:13:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:13:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:13:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:13:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:13:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:49 INFO - 'talos_options': ['--disable-e10s'], 12:13:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:13:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:13:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:49 INFO - 'talos_options': ['--disable-e10s'], 12:13:49 INFO - 'tests': ['damp', 'tps']}, 12:13:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:49 INFO - 'tests': ['damp', 'tps']}, 12:13:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:13:49 INFO - 'tests': ['dromaeo_dom']}, 12:13:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:13:49 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:13:49 INFO - 'tests': ['basic_compositor_video']}, 12:13:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:13:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:13:49 INFO - 'tests': ['a11yr', 12:13:49 INFO - 'ts_paint', 12:13:49 INFO - 'tpaint', 12:13:49 INFO - 'sessionrestore', 12:13:49 INFO - 'sessionrestore_no_auto_restore', 12:13:49 INFO - 'tabpaint']}, 12:13:49 INFO - 'other-e10s': {'tests': ['a11yr', 12:13:49 INFO - 'ts_paint', 12:13:49 INFO - 'tpaint', 12:13:49 INFO - 'sessionrestore', 12:13:49 INFO - 'sessionrestore_no_auto_restore', 12:13:49 INFO - 'tabpaint']}, 12:13:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:13:49 INFO - 'ts_paint', 12:13:49 INFO - 'tpaint', 12:13:49 INFO - 'sessionrestore', 12:13:49 INFO - 'sessionrestore_no_auto_restore', 12:13:49 INFO - 'tabpaint']}, 12:13:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:13:49 INFO - 'ts_paint', 12:13:49 INFO - 'tpaint', 12:13:49 INFO - 'sessionrestore', 12:13:49 INFO - 'sessionrestore_no_auto_restore', 12:13:49 INFO - 'tabpaint']}, 12:13:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:13:49 INFO - 'tests': ['a11yr', 12:13:49 INFO - 'ts_paint', 12:13:49 INFO - 'tpaint', 12:13:49 INFO - 'sessionrestore', 12:13:49 INFO - 'sessionrestore_no_auto_restore', 12:13:49 INFO - 'tabpaint']}, 12:13:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:13:49 INFO - 'tests': ['a11yr', 12:13:49 INFO - 'ts_paint', 12:13:49 INFO - 'tpaint', 12:13:49 INFO - 'sessionrestore', 12:13:49 INFO - 'sessionrestore_no_auto_restore', 12:13:49 INFO - 'tabpaint']}, 12:13:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:13:49 INFO - 'tests': ['tsvgx', 12:13:49 INFO - 'tsvgr_opacity', 12:13:49 INFO - 'tart', 12:13:49 INFO - 'tscrollx', 12:13:49 INFO - 'cart']}, 12:13:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:13:49 INFO - 'tsvgr_opacity', 12:13:49 INFO - 'tart', 12:13:49 INFO - 'tscrollx', 12:13:49 INFO - 'cart']}, 12:13:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:49 INFO - 'talos_options': ['--disable-e10s'], 12:13:49 INFO - 'tests': ['tp5o']}, 12:13:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:49 INFO - 'tests': ['tp5o']}, 12:13:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:49 INFO - 'talos_options': ['--disable-e10s', 12:13:49 INFO - '--xperf_path', 12:13:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:13:49 INFO - 'tests': ['tp5n']}, 12:13:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:49 INFO - 'talos_options': ['--xperf_path', 12:13:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:13:49 INFO - 'tests': ['tp5n']}}, 12:13:49 INFO - 'talos.zip': {'path': '', 12:13:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:13:49 INFO - Running post-action listener: _resource_record_post_action 12:13:49 INFO - ##### 12:13:49 INFO - ##### Finished populate-webroot step (success) 12:13:49 INFO - ##### 12:13:49 INFO - ##### 12:13:49 INFO - ##### Running create-virtualenv step. 12:13:49 INFO - ##### 12:13:49 INFO - Running pre-action listener: _resource_record_pre_action 12:13:49 INFO - Running main action method: create_virtualenv 12:13:49 INFO - Creating virtualenv /builds/slave/test/build/venv 12:13:49 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 12:13:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:13:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:13:50 INFO - Using real prefix '/tools/python27' 12:13:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:13:50 INFO - Installing distribute.............................................................................................................................................................................................done. 12:13:53 INFO - Installing pip.................done. 12:13:53 INFO - Return code: 0 12:13:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:13:53 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')]} 12:13:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:53 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')]} 12:13:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:53 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 0x10d134a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c9618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc68b4deb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d098d30>, '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 0x10cdfddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d090b20>, '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.8mJZMheXSL/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.Arn37w5ps1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/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 12:13:53 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 12:13:53 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 12:13:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8mJZMheXSL/Render', 12:13:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:53 INFO - 'HOME': '/Users/cltbld', 12:13:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:53 INFO - 'LOGNAME': 'cltbld', 12:13:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:53 INFO - 'MOZ_NO_REMOTE': '1', 12:13:53 INFO - 'NO_EM_RESTART': '1', 12:13:53 INFO - 'PAGER': '/bin/cat', 12:13:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:53 INFO - 'PWD': '/builds/slave/test', 12:13:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:53 INFO - 'SHELL': '/bin/bash', 12:13:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Arn37w5ps1/Listeners', 12:13:53 INFO - 'TMPDIR': '/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/', 12:13:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:53 INFO - 'USER': 'cltbld', 12:13:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:53 INFO - 'XPC_FLAGS': '0x0', 12:13:53 INFO - 'XPC_SERVICE_NAME': '0', 12:13:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:53 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 12:13:53 INFO - Cleaning up... 12:13:53 INFO - Return code: 0 12:13:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:13:53 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')]} 12:13:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:53 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')]} 12:13:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:53 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>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d134a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c9618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc68b4deb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d098d30>, '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 0x10cdfddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d090b20>, '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.8mJZMheXSL/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.Arn37w5ps1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/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 12:13:53 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>=3.1.1'] in /builds/slave/test/build 12:13:53 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>=3.1.1 12:13:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8mJZMheXSL/Render', 12:13:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:53 INFO - 'HOME': '/Users/cltbld', 12:13:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:53 INFO - 'LOGNAME': 'cltbld', 12:13:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:53 INFO - 'MOZ_NO_REMOTE': '1', 12:13:53 INFO - 'NO_EM_RESTART': '1', 12:13:53 INFO - 'PAGER': '/bin/cat', 12:13:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:53 INFO - 'PWD': '/builds/slave/test', 12:13:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:53 INFO - 'SHELL': '/bin/bash', 12:13:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Arn37w5ps1/Listeners', 12:13:53 INFO - 'TMPDIR': '/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/', 12:13:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:53 INFO - 'USER': 'cltbld', 12:13:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:53 INFO - 'XPC_FLAGS': '0x0', 12:13:53 INFO - 'XPC_SERVICE_NAME': '0', 12:13:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:54 INFO - Downloading/unpacking psutil>=3.1.1 12:13:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:13:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:13:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:13:56 INFO - Installing collected packages: psutil 12:13:56 INFO - Running setup.py install for psutil 12:13:56 INFO - building 'psutil._psutil_osx' extension 12:13:56 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 12:13:57 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 12:13:57 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 12:13:57 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 12:13:57 INFO - building 'psutil._psutil_posix' extension 12:13:57 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 12:13:57 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:13:57 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:13:57 INFO - ^ 12:13:57 INFO - 1 warning generated. 12:13:57 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 12:13:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:13:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:13:57 INFO - Successfully installed psutil 12:13:57 INFO - Cleaning up... 12:13:58 INFO - Return code: 0 12:13:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:13:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:13:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:13:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:58 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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d134a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c9618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc68b4deb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d098d30>, '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 0x10cdfddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d090b20>, '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.8mJZMheXSL/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.Arn37w5ps1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/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 12:13:58 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.3'] in /builds/slave/test/build 12:13:58 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.3 12:13:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8mJZMheXSL/Render', 12:13:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:58 INFO - 'HOME': '/Users/cltbld', 12:13:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:58 INFO - 'LOGNAME': 'cltbld', 12:13:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:58 INFO - 'MOZ_NO_REMOTE': '1', 12:13:58 INFO - 'NO_EM_RESTART': '1', 12:13:58 INFO - 'PAGER': '/bin/cat', 12:13:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:58 INFO - 'PWD': '/builds/slave/test', 12:13:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:58 INFO - 'SHELL': '/bin/bash', 12:13:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Arn37w5ps1/Listeners', 12:13:58 INFO - 'TMPDIR': '/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/', 12:13:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:58 INFO - 'USER': 'cltbld', 12:13:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:58 INFO - 'XPC_FLAGS': '0x0', 12:13:58 INFO - 'XPC_SERVICE_NAME': '0', 12:13:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:13:58 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 12:13:58 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 12:13:58 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 12:13:58 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 12:13:58 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 12:13:58 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 12:14:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:14:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:14:00 INFO - Installing collected packages: mozsystemmonitor 12:14:00 INFO - Running setup.py install for mozsystemmonitor 12:14:00 INFO - Successfully installed mozsystemmonitor 12:14:00 INFO - Cleaning up... 12:14:01 INFO - Return code: 0 12:14:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:14:01 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')]} 12:14:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:01 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')]} 12:14:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:01 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d134a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c9618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc68b4deb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d098d30>, '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 0x10cdfddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d090b20>, '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.8mJZMheXSL/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.Arn37w5ps1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/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 12:14:01 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==2.5.1'] in /builds/slave/test/build 12:14:01 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==2.5.1 12:14:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8mJZMheXSL/Render', 12:14:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:14:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:14:01 INFO - 'HOME': '/Users/cltbld', 12:14:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:14:01 INFO - 'LOGNAME': 'cltbld', 12:14:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:01 INFO - 'MOZ_NO_REMOTE': '1', 12:14:01 INFO - 'NO_EM_RESTART': '1', 12:14:01 INFO - 'PAGER': '/bin/cat', 12:14:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:14:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:01 INFO - 'PWD': '/builds/slave/test', 12:14:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:14:01 INFO - 'SHELL': '/bin/bash', 12:14:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Arn37w5ps1/Listeners', 12:14:01 INFO - 'TMPDIR': '/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/', 12:14:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:14:01 INFO - 'USER': 'cltbld', 12:14:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:14:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:14:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:01 INFO - 'XPC_FLAGS': '0x0', 12:14:01 INFO - 'XPC_SERVICE_NAME': '0', 12:14:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:14:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:01 INFO - Downloading/unpacking jsonschema==2.5.1 12:14:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:14:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:14:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:14:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:14:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:14:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:14:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:14:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:14:03 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 12:14:03 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 12:14:03 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 12:14:03 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 12:14:03 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 12:14:03 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 12:14:04 INFO - Downloading functools32-3.2.3-2.tar.gz 12:14:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:14:04 INFO - warning: no files found matching '*.txt' 12:14:04 INFO - no previously-included directories found matching 'build' 12:14:04 INFO - no previously-included directories found matching 'dist' 12:14:04 INFO - no previously-included directories found matching '.git*' 12:14:04 INFO - Installing collected packages: jsonschema, functools32 12:14:04 INFO - Running setup.py install for functools32 12:14:04 INFO - warning: no files found matching '*.txt' 12:14:04 INFO - no previously-included directories found matching 'build' 12:14:04 INFO - no previously-included directories found matching 'dist' 12:14:04 INFO - no previously-included directories found matching '.git*' 12:14:04 INFO - Successfully installed jsonschema functools32 12:14:04 INFO - Cleaning up... 12:14:04 INFO - Return code: 0 12:14:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:14:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:14:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:14:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d134a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c9618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc68b4deb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d098d30>, '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 0x10cdfddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d090b20>, '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.8mJZMheXSL/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.Arn37w5ps1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/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 12:14:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:14:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:14:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8mJZMheXSL/Render', 12:14:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:14:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:14:04 INFO - 'HOME': '/Users/cltbld', 12:14:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:14:04 INFO - 'LOGNAME': 'cltbld', 12:14:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:04 INFO - 'MOZ_NO_REMOTE': '1', 12:14:04 INFO - 'NO_EM_RESTART': '1', 12:14:04 INFO - 'PAGER': '/bin/cat', 12:14:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:14:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:04 INFO - 'PWD': '/builds/slave/test', 12:14:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:14:04 INFO - 'SHELL': '/bin/bash', 12:14:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Arn37w5ps1/Listeners', 12:14:04 INFO - 'TMPDIR': '/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/', 12:14:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:14:04 INFO - 'USER': 'cltbld', 12:14:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:14:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:14:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:04 INFO - 'XPC_FLAGS': '0x0', 12:14:04 INFO - 'XPC_SERVICE_NAME': '0', 12:14:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:14:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:04 INFO - Downloading/unpacking blobuploader==1.2.4 12:14:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:14:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:14:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:14:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:14:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:14:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:14:07 INFO - Downloading blobuploader-1.2.4.tar.gz 12:14:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:14:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:14: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 12:14: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 12:14: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 12:14: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 12:14: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 12:14: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 12:14:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:14:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:14: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 12:14: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 12:14: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 12:14: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 12:14: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 12:14: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 12:14:08 INFO - Downloading docopt-0.6.1.tar.gz 12:14:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:14:08 INFO - Installing collected packages: blobuploader, requests, docopt 12:14:08 INFO - Running setup.py install for blobuploader 12:14:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:14:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:14:08 INFO - Running setup.py install for requests 12:14:09 INFO - Running setup.py install for docopt 12:14:09 INFO - Successfully installed blobuploader requests docopt 12:14:09 INFO - Cleaning up... 12:14:09 INFO - Return code: 0 12:14:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:14: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')]} 12:14:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14: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')]} 12:14:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14: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 0x10d134a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c9618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc68b4deb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d098d30>, '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 0x10cdfddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d090b20>, '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.8mJZMheXSL/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.Arn37w5ps1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/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 12:14: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 12:14: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 12:14:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8mJZMheXSL/Render', 12:14:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:14:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:14:09 INFO - 'HOME': '/Users/cltbld', 12:14:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:14:09 INFO - 'LOGNAME': 'cltbld', 12:14:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:09 INFO - 'MOZ_NO_REMOTE': '1', 12:14:09 INFO - 'NO_EM_RESTART': '1', 12:14:09 INFO - 'PAGER': '/bin/cat', 12:14:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:14:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:09 INFO - 'PWD': '/builds/slave/test', 12:14:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:14:09 INFO - 'SHELL': '/bin/bash', 12:14:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Arn37w5ps1/Listeners', 12:14:09 INFO - 'TMPDIR': '/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/', 12:14:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:14:09 INFO - 'USER': 'cltbld', 12:14:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:14:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:14:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:09 INFO - 'XPC_FLAGS': '0x0', 12:14:09 INFO - 'XPC_SERVICE_NAME': '0', 12:14:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:14:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:14:09 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-gb20tK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:14:09 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-YOkgwA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:14:09 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-Nps91I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:14:09 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-9IovBg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:14:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:14:10 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-1MjtFU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:14:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:14:10 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-jM4ZoX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:14:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:14:10 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-l73Gkg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:14:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:14:10 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-cfKjUL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:14:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:14:10 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-eXt5wS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:14:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:14:10 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-3z3q34-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:14:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:14:10 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-eW0SAi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:14:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:14:10 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-6TboHO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:14:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:14:10 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-5S6faK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:14:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:14:10 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-7_PDzu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:14:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:14:11 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-Ktcqa1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:14:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:14:11 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-Dq8GkY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:14:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:14:11 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-EoCIqs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:14:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:14:11 INFO - Running setup.py install for manifestparser 12:14:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:14:11 INFO - Running setup.py install for mozcrash 12:14:11 INFO - Running setup.py install for mozdebug 12:14:11 INFO - Running setup.py install for mozdevice 12:14:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:14:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:14:11 INFO - Running setup.py install for mozfile 12:14:12 INFO - Running setup.py install for mozhttpd 12:14:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:14:12 INFO - Running setup.py install for mozinfo 12:14:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:14:12 INFO - Running setup.py install for mozInstall 12:14:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:14:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:14:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:14:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:14:12 INFO - Running setup.py install for mozleak 12:14:12 INFO - Running setup.py install for mozlog 12:14:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:14:12 INFO - Running setup.py install for moznetwork 12:14:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:14:13 INFO - Running setup.py install for mozprocess 12:14:13 INFO - Running setup.py install for mozprofile 12:14:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:14:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:14:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:14:13 INFO - Running setup.py install for mozrunner 12:14:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:14:13 INFO - Running setup.py install for mozscreenshot 12:14:13 INFO - Running setup.py install for moztest 12:14:13 INFO - Running setup.py install for mozversion 12:14:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:14:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:14:13 INFO - Cleaning up... 12:14:14 INFO - Return code: 0 12:14:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:14: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')]} 12:14:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14: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')]} 12:14:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14: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 0x10d134a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c9618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc68b4deb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d098d30>, '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 0x10cdfddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d090b20>, '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.8mJZMheXSL/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.Arn37w5ps1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/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 12:14: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 12:14: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 12:14:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8mJZMheXSL/Render', 12:14:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:14:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:14:14 INFO - 'HOME': '/Users/cltbld', 12:14:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:14:14 INFO - 'LOGNAME': 'cltbld', 12:14:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:14 INFO - 'MOZ_NO_REMOTE': '1', 12:14:14 INFO - 'NO_EM_RESTART': '1', 12:14:14 INFO - 'PAGER': '/bin/cat', 12:14:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:14:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:14 INFO - 'PWD': '/builds/slave/test', 12:14:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:14:14 INFO - 'SHELL': '/bin/bash', 12:14:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Arn37w5ps1/Listeners', 12:14:14 INFO - 'TMPDIR': '/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/', 12:14:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:14:14 INFO - 'USER': 'cltbld', 12:14:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:14:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:14:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:14 INFO - 'XPC_FLAGS': '0x0', 12:14:14 INFO - 'XPC_SERVICE_NAME': '0', 12:14:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:14:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:14:14 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-cKK14m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:14: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)) 12:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:14:14 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-7wW96z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:14:14 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)) 12:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:14:14 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-ATb1Lc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:14:14 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)) 12:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:14:14 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-oXf6hZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:14:14 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)) 12:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:14:14 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-AGSWDa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:14:14 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)) 12:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:14:14 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-iuvf5Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:14:14 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)) 12:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:14:14 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-eFUBvk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:14:14 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)) 12:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:14:15 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-6Hu27O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:14: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)) 12:14:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:14:15 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-1TB_iy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:14: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)) 12:14:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:14:15 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-4v8qQF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:14:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 12:14:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:14:15 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-nEDL4S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:14: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)) 12:14:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:14:15 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-wmF06y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:14:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 12:14:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:14:15 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-m6acmW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:14:15 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)) 12:14:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:14:15 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-Z_eBBM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:14:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 12:14:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:14:15 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-cxVV2I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:14:15 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)) 12:14:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:14:15 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-PvkRO4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:14:15 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)) 12:14:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:14:15 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-mtBQOT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:14: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)) 12:14: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)) 12:14: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)) 12:14: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)) 12:14: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)) 12:14: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)) 12:14:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:14: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 12:14: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 12:14: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 12:14: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 12:14: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 12:14: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 12:14:18 INFO - Downloading blessings-1.6.tar.gz 12:14:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:14:18 INFO - Installing collected packages: blessings 12:14:18 INFO - Running setup.py install for blessings 12:14:18 INFO - Successfully installed blessings 12:14:18 INFO - Cleaning up... 12:14:18 INFO - Return code: 0 12:14:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:14:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:14:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:14:19 INFO - Reading from file tmpfile_stdout 12:14:19 INFO - Current package versions: 12:14:19 INFO - blessings == 1.6 12:14:19 INFO - blobuploader == 1.2.4 12:14:19 INFO - docopt == 0.6.1 12:14:19 INFO - functools32 == 3.2.3-2 12:14:19 INFO - jsonschema == 2.5.1 12:14:19 INFO - manifestparser == 1.1 12:14:19 INFO - mozInstall == 1.12 12:14:19 INFO - mozcrash == 0.17 12:14:19 INFO - mozdebug == 0.1 12:14:19 INFO - mozdevice == 0.48 12:14:19 INFO - mozfile == 1.2 12:14:19 INFO - mozhttpd == 0.7 12:14:19 INFO - mozinfo == 0.9 12:14:19 INFO - mozleak == 0.1 12:14:19 INFO - mozlog == 3.2 12:14:19 INFO - moznetwork == 0.27 12:14:19 INFO - mozprocess == 0.23 12:14:19 INFO - mozprofile == 0.28 12:14:19 INFO - mozrunner == 6.12 12:14:19 INFO - mozscreenshot == 0.1 12:14:19 INFO - mozsystemmonitor == 0.3 12:14:19 INFO - moztest == 0.7 12:14:19 INFO - mozversion == 1.4 12:14:19 INFO - psutil == 3.1.1 12:14:19 INFO - requests == 1.2.3 12:14:19 INFO - wsgiref == 0.1.2 12:14:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:14: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')]} 12:14:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14: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')]} 12:14:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14: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 0x10d134a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c9618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc68b4deb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d098d30>, '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 0x10cdfddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d090b20>, '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.8mJZMheXSL/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.Arn37w5ps1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/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 12:14: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 12:14: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 12:14:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8mJZMheXSL/Render', 12:14:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:14:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:14:19 INFO - 'HOME': '/Users/cltbld', 12:14:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:14:19 INFO - 'LOGNAME': 'cltbld', 12:14:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:19 INFO - 'MOZ_NO_REMOTE': '1', 12:14:19 INFO - 'NO_EM_RESTART': '1', 12:14:19 INFO - 'PAGER': '/bin/cat', 12:14:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:14:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:19 INFO - 'PWD': '/builds/slave/test', 12:14:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:14:19 INFO - 'SHELL': '/bin/bash', 12:14:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Arn37w5ps1/Listeners', 12:14:19 INFO - 'TMPDIR': '/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/', 12:14:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:14:19 INFO - 'USER': 'cltbld', 12:14:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:14:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:14:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:19 INFO - 'XPC_FLAGS': '0x0', 12:14:19 INFO - 'XPC_SERVICE_NAME': '0', 12:14:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:14:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14: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)) 12:14: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)) 12:14: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)) 12:14: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)) 12:14: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)) 12:14: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)) 12:14: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)) 12:14: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)) 12:14: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)) 12:14: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)) 12:14: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)) 12:14:19 INFO - Cleaning up... 12:14:19 INFO - Return code: 0 12:14:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:14: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')]} 12:14:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14: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')]} 12:14:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14: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 0x10d134a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c9618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc68b4deb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d098d30>, '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 0x10cdfddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d090b20>, '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.8mJZMheXSL/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.Arn37w5ps1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/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 12:14: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 12:14: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 12:14:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8mJZMheXSL/Render', 12:14:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:14:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:14:19 INFO - 'HOME': '/Users/cltbld', 12:14:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:14:19 INFO - 'LOGNAME': 'cltbld', 12:14:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:19 INFO - 'MOZ_NO_REMOTE': '1', 12:14:19 INFO - 'NO_EM_RESTART': '1', 12:14:19 INFO - 'PAGER': '/bin/cat', 12:14:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:14:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:19 INFO - 'PWD': '/builds/slave/test', 12:14:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:14:19 INFO - 'SHELL': '/bin/bash', 12:14:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Arn37w5ps1/Listeners', 12:14:19 INFO - 'TMPDIR': '/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/', 12:14:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:14:19 INFO - 'USER': 'cltbld', 12:14:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:14:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:14:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:19 INFO - 'XPC_FLAGS': '0x0', 12:14:19 INFO - 'XPC_SERVICE_NAME': '0', 12:14:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:14:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:14:19 INFO - Cleaning up... 12:14:19 INFO - Return code: 0 12:14:19 INFO - Running post-action listener: _resource_record_post_action 12:14:19 INFO - Running post-action listener: _start_resource_monitoring 12:14:19 INFO - Starting resource monitoring. 12:14:19 INFO - ##### 12:14:19 INFO - ##### Finished create-virtualenv step (success) 12:14:19 INFO - ##### 12:14:19 INFO - ##### 12:14:19 INFO - ##### Running install step. 12:14:19 INFO - ##### 12:14:19 INFO - Running pre-action listener: _resource_record_pre_action 12:14:19 INFO - Running main action method: install 12:14:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:14:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:14:19 INFO - Reading from file tmpfile_stdout 12:14:19 INFO - Detecting whether we're running mozinstall >=1.0... 12:14:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:14:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:14:20 INFO - Reading from file tmpfile_stdout 12:14:20 INFO - Output received: 12:14:20 INFO - Usage: mozinstall [options] installer 12:14:20 INFO - Options: 12:14:20 INFO - -h, --help show this help message and exit 12:14:20 INFO - -d DEST, --destination=DEST 12:14:20 INFO - Directory to install application into. [default: 12:14:20 INFO - "/builds/slave/test"] 12:14:20 INFO - --app=APP Application being installed. [default: firefox] 12:14:20 INFO - mkdir: /builds/slave/test/build/application 12:14:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:14:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 12:14:54 INFO - Reading from file tmpfile_stdout 12:14:54 INFO - Output received: 12:14:54 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:14:54 INFO - Running post-action listener: _resource_record_post_action 12:14:54 INFO - ##### 12:14:54 INFO - ##### Finished install step (success) 12:14:54 INFO - ##### 12:14:54 INFO - ##### 12:14:54 INFO - ##### Running run-tests step. 12:14:54 INFO - ##### 12:14:54 INFO - Running pre-action listener: _resource_record_pre_action 12:14:54 INFO - Running main action method: run_tests 12:14:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:14:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:14:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:14:54 INFO - 2016-07-15 12:14:54.146 screenresolution[1870:8410] starting screenresolution argv=screenresolution get 12:14:54 INFO - 2016-07-15 12:14:54.149 screenresolution[1870:8410] Display 0: 1600x1200x32@60 12:14:54 INFO - 2016-07-15 12:14:54.154 screenresolution[1871:8412] starting screenresolution argv=screenresolution list 12:14:54 INFO - Available Modes on Display 0 12:14:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:14:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:14:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:14:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:14:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:14:54 INFO - Intel Iris: 12:14:54 INFO - Chipset Model: Intel Iris 12:14:54 INFO - Type: GPU 12:14:54 INFO - Bus: Built-In 12:14:54 INFO - VRAM (Dynamic, Max): 1536 MB 12:14:54 INFO - Vendor: Intel (0x8086) 12:14:54 INFO - Device ID: 0x0a2e 12:14:54 INFO - Revision ID: 0x0009 12:14:54 INFO - Displays: 12:14:54 INFO - Display: 12:14:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:14:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:14:54 INFO - Display Serial Number: 1600x1200 60 12:14:54 INFO - Main Display: Yes 12:14:54 INFO - Mirror: Off 12:14:54 INFO - Online: Yes 12:14:54 INFO - Rotation: Supported 12:14:54 INFO - Return code: 0 12:14:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:14:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:14:54 INFO - Python 2.7.3 12:14:54 INFO - Return code: 0 12:14:54 INFO - grabbing minidump binary from tooltool 12:14:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:14:54 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d098d30>, '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 0x10cdfddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d090b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:14:54 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 12:14:54 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 12:14:54 INFO - Calling ['/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'] with output_timeout 600 12:14:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:14:54 INFO - Return code: 0 12:14:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:14:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:14:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:14:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:14:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:14:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RLV4jmnVQ6OhdEMMsDO4nw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0051', '--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 12:14:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RLV4jmnVQ6OhdEMMsDO4nw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0051 --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 12:14:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8mJZMheXSL/Render', 12:14:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:14:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:14:54 INFO - 'HOME': '/Users/cltbld', 12:14:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:14:54 INFO - 'LOGNAME': 'cltbld', 12:14:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:14:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:14:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:54 INFO - 'MOZ_NO_REMOTE': '1', 12:14:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:14:54 INFO - 'NO_EM_RESTART': '1', 12:14:54 INFO - 'PAGER': '/bin/cat', 12:14:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:14:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:54 INFO - 'PWD': '/builds/slave/test', 12:14:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:14:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:14:54 INFO - 'SHELL': '/bin/bash', 12:14:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Arn37w5ps1/Listeners', 12:14:54 INFO - 'TMPDIR': '/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/', 12:14:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:14:54 INFO - 'USER': 'cltbld', 12:14:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:14:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:14:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:54 INFO - 'XPC_FLAGS': '0x0', 12:14:54 INFO - 'XPC_SERVICE_NAME': '0', 12:14:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:14:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RLV4jmnVQ6OhdEMMsDO4nw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0051', '--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 12:14:54 INFO - mozversion application_buildid: 20160715071711 12:14:54 INFO - mozversion application_changeset: 2f9e69c982f1e67887a1834b36ff0af4ababb3af 12:14:54 INFO - mozversion application_display_name: Nightly 12:14:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:14:54 INFO - mozversion application_name: Firefox 12:14:54 INFO - mozversion application_remotingname: firefox 12:14:54 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:14:54 INFO - mozversion application_vendor: Mozilla 12:14:54 INFO - mozversion application_version: 50.0a1 12:14:54 INFO - mozversion platform_buildid: 20160715071711 12:14:54 INFO - mozversion platform_changeset: 2f9e69c982f1e67887a1834b36ff0af4ababb3af 12:14:54 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:14:54 INFO - mozversion platform_version: 50.0a1 12:14:54 INFO - using testdate: 1468610094 12:14:54 INFO - actual date: 1468610094 12:14:54 INFO - starting webserver on 'localhost:49276' 12:14:54 INFO - SUITE-START | Running 2 tests 12:14:54 INFO - TEST-START | dromaeo_css 12:14:54 INFO - operating with platform_type : mac_ 12:14:54 INFO - Initialising browser for dromaeo_css test... 12:14:54 INFO - TEST-INFO | started process 1881 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/tmpZcHz9W/profile http://localhost:49276/getInfo.html) 12:14:57 INFO - PROCESS | 1881 | __metrics Screen width/height:1600/1200 12:14:57 INFO - PROCESS | 1881 | colorDepth:24 12:14:57 INFO - PROCESS | 1881 | Browser inner width/height: 1024/689 12:14:57 INFO - PROCESS | 1881 | __metrics 12:14:57 ERROR - PROCESS | 1881 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2964: TypeError: this.tabs is undefined 12:14:58 INFO - TEST-INFO | 1881: exit 0 12:14:58 INFO - Browser initialized. 12:14:58 INFO - Running cycle 1/1 for dromaeo_css test... 12:14:58 INFO - TEST-INFO | started process 1885 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/tmpZcHz9W/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:15:10 INFO - PROCESS | 1885 | RSS: Main: 254300160 12:15:10 INFO - PROCESS | 1885 | 12:15:59 INFO - PROCESS | 1885 | 1468610159977 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] 12:18:11 INFO - PROCESS | 1885 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 12:18:13 INFO - PROCESS | 1885 | RSS: Main: 267116544 12:18:13 INFO - PROCESS | 1885 | 12:21:15 INFO - PROCESS | 1885 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 12:21:15 INFO - PROCESS | 1885 | RSS: Main: 275783680 12:21:15 INFO - PROCESS | 1885 | 12:24:17 INFO - PROCESS | 1885 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 12:24:18 INFO - PROCESS | 1885 | RSS: Main: 280084480 12:24:18 INFO - PROCESS | 1885 | 12:27:19 INFO - PROCESS | 1885 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 12:27:20 INFO - PROCESS | 1885 | RSS: Main: 279519232 12:27:20 INFO - PROCESS | 1885 | 12:30:23 INFO - PROCESS | 1885 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 12:30:23 INFO - PROCESS | 1885 | RSS: Main: 282664960 12:30:23 INFO - PROCESS | 1885 | 12:33:26 INFO - PROCESS | 1885 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 12:33:27 INFO - PROCESS | 1885 | RSS: Main: 282820608 12:33:27 INFO - PROCESS | 1885 | 12:33:27 INFO - PROCESS | 1885 | __start_tp_report 12:33:27 INFO - PROCESS | 1885 | _x_x_mozilla_page_load 12:33:27 INFO - PROCESS | 1885 | _x_x_mozilla_page_load_details 12:33:27 INFO - PROCESS | 1885 | |i|pagename|runs| 12:33:27 INFO - PROCESS | 1885 | |0;dojo.html;3363.3441478911623;3383.560137182166;3431.50243214734;3434.5363375944257;3439.0714507083103;5649.096144616862;5729.197912292282;5741.913871291928;5786.739596718147;5847.1521629363715;4773.284007398892;5712.542996560275;5778.768849246036;5824.213731146305;5850.619709718865;5246.685198888079;5286.471352864702;5319.707416092133;5376.892462150755;5401;4168.5414604393645;5357.4374690657505;5411.945880541203;5458.563314934816;5459.563234941195;5080.491950804927;5108.335916330853;5188.558972487343;5201.323827902386;5267.130923397634;5201.063808514466;5206.114960456732;5234.110201265794;5279.102552566034;5283.471652834724;4319;5216.452272511369;5249.396319423277;5273.9736301318635;5305.098133317345;4532.818687252506;5140.460251673558;5575.637583557056;5758.251427314464;5782.942170578324;6153.292371377304;6237.563370564062;6265.373462653698;6282.811515654537;6283.246010478726;6081.787137450212;6241.563090583661;6315.652639104847;6338.778142765027;6345.9365406345805;5851.736671849777;5897.587168898179;5902.380250073767;5903.645781253139;5951.345352011275;6297.011183189387;6319.873602527924;6323.336049714714;6330.683465826783;6340.461060809791;5995.430434108753;6129.847850912876;6164.722587483574;6238.656873871981;6239.06414037898;6121.117656725397;6321.968390158112;6324.209473815773;6343.651099189589;6350;6175.84560385994;6241.282252540925;6259.155014073133;6290.874182516324;6329.3354197809485;6112.235970503687;6135.417135372132;6245.843853903689;6254.34329395416;6353.936460635335;4303.1828628852045;4992.90156165643;5061;5080.644354895122;5116.30929824469;4308.569143085666;4357.758038958945;4415.448068991376;4491.663125265618;4492.528284530143;5573.27547418833;5780.566457515703;5809.041508151192;5840.649561026267;5889.558283128783;6240.687965601702;6258.475018253918;6293.685315734195;6294.118823364733;6334.113224148623;5490.846922146314;5627.493525582717;5894.2926848778425;5895.056790913433;5917.704114794243;4688.203005489075;5330.866728331745;5444.1017232156255;5482.122860342326;5516.641418307797;6372.649504277217;6386.074019267087;6391.488680905423;6393.29673735898;6412.5190610704385;6153.938460615515;6237.781677641261;6244.875102497834;6256.718447669957;6268.4671802897265;5314.335708036496;6159.106929495288;6202.875942481215;6249.562530622813;6306.342321453454;6143.354947730424;6182.783602574068;6192.721327540181;6195.4424101829245;6208.0998255253635;6116.305337585956;6239.804444887916;6240.219972503437;6243.937560624336;6287.056941458818;6067.757289708362;6129.633877546871;6147.754089836535;6186.072089186658;6211.9068213977735;6079.513638908788;6109.083637454418;6225.906611400923;6243.500519958483;6247.250329960434;5716.142578613241;6018.307894592178;6035.818925432244;6068.514518838394;6153.5692501526255;5324.148136298174;6123.142760013513;6149.72326245329;6227.813165605039;6239.532035097295;6042.546808989431;6082.817515474543;6101.633901965896;6105.542084343603;6108.725107370268 12:33:27 INFO - PROCESS | 1885 | |1;ext.html;493.26257245418105;522.5975998481172;530.4562823106317;543.2259030880994;574.6638216643262;38746.61253387465;41541.792291038575;41638.58361416389;41733.58266417335;41785.79107104468;42195;42300.57699423005;42648.786756066256;42696.14607707844;42878.928026799265;9655.806883862319;9761.560729767178;9762.365446246007;9783.41299522031;9787.951060244688;9703.029697030312;9745.853812192823;9817.901820981806;9855.507224638775;9860.654877079303;9777;9838.704838854846;9844.114029737322;9854.310198286123;9882.407055576688;9780.804383912353;9789.510524473784;9822;9822.361546499491;9839.80320393588;9665.951670241604;9689.660861869801;9722.611095556169;9746.951265243628;9774.706758797247;33399.3320133596;40214.59785402138;40244;40459.7977010116;40628.59371406307;3213.53403756454;3371.7808342457906;3557.6620221078956;3566.3937130687846;3647.0354158376285;32087;32361.190970225696;32712.345753084806;32908.341833163446;33279.50080748791;2922.8976985805393;3279.262166012628;3312.5362449257123;3394.2362968332172;3419.042668052949;456727;525378;533091;534898;535656.3217183928;34214.48678269828;35201.94394168179;63738.72522549523;64588.35411645917;64721;29597.520123993716;31768;34369.82815085909;35687.28625427529;35906.8204658975;14837.183954882585;14918.328675209425;15044.473443429646;15073.773893391608;15109.168995705122;12651.0511711622;12700.031495118272;12817.35913204336;12852.293123878277;12903.612891613267;7728.343090837229;7754.3796496280165;7759.447151873447;7762.262585054411;7833.2166783322355;3220.259340351732;3253.2680146966745;3257.5439438478634;3261.184703824044;3281.6882396172327;949.6676163342775;975.4244995452717;999.4802702594756;1006.3106771861299;1090.111559079348;31195.376092478476;31251.84374078115;31300.217494562363;31804;31985.680143198737;1037.1702637889657;1116.6929094498914;1149.3448734221413;1213.2174747288127;1234.1361047266948;15614.08982187171;16060.67878642444;16143.919280403523;16282.615977642034;16336;720802;731763;735239;735723;741062;61760.38239617546;61831;62129.68935155295;62190.37809621846;62292;1359.8776110150134;1383.9930800345933;1424.6295963049474;1425.5580769961139;1431.9212443315719;28559.28601784972;28846.855765721037;28859.56710649384;29108;29548.556771648873;53813;54038.6490337729;54135;54166.729166353914;54440.45559544512;614766;636074;637105;637830;639188;27323.590146147428;27428.588571171847;27635.58546621762;27666.170014899584;27692.86153569219;50209.497905020486;50243.74878125732;50614;51063.97872042612;51733.22400163925;41272.587274126876;41469.79265103655;41521.79239103785;42135;42155.57844421639;40604.79697601493;41505.37741933813;41636.79181604073;41856.19846247405;42376;38614.613853862225;38798.80600597092;39752.40371394489;39922.401163983144;39957.001074973356;36088.917332480065;36137.46384305258;36146.27707445784;36447;36681.63318366782;22974.892707071587;26185.476290474464;26458;26497.205083847515;26953 12:33:27 INFO - PROCESS | 1885 | |2;jquery.html;385.09310573599174;390.74992005116724;392.7231301932138;397.2254104496232;414.8983499042735;20545.280915167972;21990.340289791333;22016.88991555042;22076.558468830615;22387.880605969716;20039.298624548108;21157.259495917646;21327.25354612589;22122.44693882654;22137.114515419406;5767.769289228426;6202.100695399165;6229.127922090911;6230.5638605297645;6250.343713910042;5156.200788877712;5333.54664853486;5993.550483713717;6136.171616831715;6154.630722156685;5980.192673989;6106.328303886569;6139.293981192175;6143.539234557404;6146.07808828677;5537.0863807471715;5742.655440673552;6020.367861374536;6099.634021958653;6116.541259405541;5227.3988491323225;5298.549623282026;5308.522232999048;5312.548433383167;5318.441563635801;25039.374015649755;26148.477030459286;26210.475790484084;26280.73719262821;27159.45681086387;2212.9114835406563;2676.5048466033845;2705.905293314744;2881.034853324139;3204.9198770030703;15307.617309567238;15795.21023948798;15816.52550423489;16029;16194.514164574965;1819.1086367679875;1862.1620270878159;1873.4379686094117;1882.1718443884783;1926.5087402712359;99864.50203246884;122488.16267755991;125885;126739.36630316882;126893;10877.129829613612;10951;11305.886941130648;11336.773264534662;11494.42527873603;14001.1098501703;14010;14028.158310501403;14055.508057217949;14057.297135143202;8655.350848686248;8772.561371931379;8792.889706470529;8864.955675221583;8958.68644597436;8136.959315203386;8770.298376129895;8886.822263554694;8918.420302680304;9055.59249833759;5880.26496687914;6330.037688316636;6902.102726645504;6925.272846351161;7023.403010744144;1904.9142788574547;1935.167877812554;1949.7465329507356;1972.6350625134396;1978.4460351101714;829.0010537302536;886.9445360021554;941.9105185007413;954.737447202025;1029.7734498410487;19587.216511339386;21039.474013149793;23313.7668623315;23359.416014599432;23498.64752028721;951.3293128344638;962.0908241711516;981.5681100315838;1001.8597396364515;1043.9373637581618;8937.910620893837;12011.579594714143;12233.816492752616;12381.947534459672;12429.440675169639;509926;607501;620058;624721;628181.8590907108;62480.375196248366;63917.36082639207;92869.14261714728;120482;130625;980.4460479828874;981.7202097402206;1002.9247806414548;1017.5624481473037;1023.3552861697083;17113.572160695585;20645.79354206499;20951.952402379517;20981.580368392853;21079.367618971457;51815.74092129515;52987.470125298256;54430.45569544412;55405.44594554164;55446;63748.68125659342;66784.99822502569;77844.05389865299;78832.60583697046;79529;5980.282366116094;7129.6810090133495;7233.855322893618;7345.70617175307;7729.652165652672;12672;13772.31138443094;13867.73706778507;14023.439062437388;14040.648983775487;11712.238704484182;11948.820767688318;11987.880121198676;12043.879561204276;12059.457324420247;9913.206943444364;11002.889971100507;11319.981201691888;11756.529738810352;12020.939895300467;9533.761655958378;10196.4391958443;11365.977062064454;11938.22401543897;12189.939050304693;7692.307692307576;7716.344110750649;7725.111612164448;7852.13626501073;7969.681212751657;8027.1972802719265;8976.506292153994;10655.786884262427;11849.703757405789;11896.464659090188 12:33:27 INFO - PROCESS | 1885 | |3;mootools.html;1437.2957250947036;1547.5589457004753;1787.383352743304;1928.373278684428;1948.4739120437482;8022.237887400687;8571.699990500334;8721.607527661256;9126.58930348135;9185.724428267155;8519.87220191696;8676;8688.565571721405;8732;8815.779605509866;2304.2626359564947;2347.2606129069372;2350.5533948549805;2352.9411764705847;2359.1978727232736;2352.7411984681758;2371.288613415977;2396.8442051266697;2398.772116648924;2407.8194135439826;2359.834811563191;2375.3349062262596;2385.6183010718287;2390.3068110248096;2394.736578976311;2333.3116730564525;2334.743178250391;2344.78896899279;2359;2363.6690863279155;1956.3739603326942;2328.93013209604;2340.3330050935574;2343.133040774924;2365.1249037856105;8822.647094116228;8928.553572321358;8944.329175311812;8958;8989.37074404792;5263.368395792491;5333.57331413486;5373.946260537384;5380.031594313022;5380.139177731544;3544.946825797589;3637.963620363789;3703.8888833335045;3709.55485341758;3729.4778730977687;1058.5924419098626;1066.8826429092794;1096.9012788849043;1113.799516087104;1116.2353787655402;11444.484998175101;11509;11623.59317423886;11645.534178632844;11672.416379181008;4623;4634.513376095529;4813.807447702053;4821.108095002436;4845.733484658307;10170.847437288447;10180.083792458567;10189.082982531607;10204.23468239885;10250.487475626189;5321.467853214647;5356.625036247426;5362.678239305654;5384.623076384615;5394.838154855361;4336.111097225062;4754.096721622881;4806.327114204014;4900.509949005071;4917.237828136645;3927.430522574211;3952.3873799561115;3983.661388781986;4042.676585873182;4104.712670113123;1257.8427696537933;1290.6128161551496;1293.5989843148654;1302.4139137388213;1302.6092172348258;825.8389614025327;957.5643082397493;961.245422343471;991.4646091110864;992.667456402099;5572.777088916398;5719.714014299351;6008.489278411384;6096.268447786294;6153.876922461615;380.26418879468315;449.8537975158021;452.7170518425984;454.12807409773313;979.5747187841673;3397.371486275047;3528.40017197072;3604.9639503604626;4141.233871733739;4209.789510524462;10362.430066346273;10736.838947415796;10886.40124793144;10889.673309800719;10949.178811589163;10033.899661003297;10734;10827.07969822574;10837.18721095921;10840.349579025284;6788;8250.257476827115;8396.202360775716;8439.70461033861;8451.957740211259;5143.434222235476;5152.690838549699;6058.90911636317;6062.484688801501;6167.167432396569;10151.289409741246;10167.339122956984;10180.79638407213;10184.796304073729;10244.334118282288;10049.19606431469;10131;10175.491225438847;10324.122449591569;10455.215858810467;6409.102725618324;6908.5509441886115;7008.579485230903;7064.82337941535;7117.7152913882865;8202.630881610461;8207.056188538394;8381.790455238668;8414.579271036302;8429.789255268668;7448.478606497493;7614.619269036637;7653.387728981779;7689.730859419893;7710.460267781202;7465.514741541782;7528.397728181622;7528.661210245662;7605.125410577854;7652.846943061219;7133.464990125865;7171.748988785576;7184.245654206442;7185.856282874418;7188.101487314086;5003.224500202475;5113.181890897438;5137.332146821039;5191;5204.245384419314;4393.406890069799;4862.173430516891;5170.741462926914;5247.685138891678;5251.921221181609 12:33:27 INFO - PROCESS | 1885 | |4;prototype.html;312.1602888230661;314.45442157856115;322.9660885607011;326.31799538963577;328.62864962592266;4818.036392721457;5436.619436639437;5688.800891968782;5745.2818397700285;5889.232060931361;4769.855234743663;5764.769409223636;5767.625104368223;5778.0466223073145;5785.421457854212;3637.9272414551824;4331.263685173512;4354.97822510887;4384.7149935254265;4395.802188901491;3496.8251587420655;4318.2227199104145;4320.265554855667;4430.712003719748;4455.952851079994;4234.364845273219;4258.659307255412;4272.615464608185;4299.978500107495;4323.913521729563;3600.5859326177565;4306.406629547072;4331.913361732748;4338;4345.543717909605;2829.575563665446;3228.886988955399;3608.1881576645305;3647.58052823926;3715;5018.698878067327;5525.806596769094;5603.579731520112;5604.327480702342;5653.23681303027;4564.589186973155;4826.758662066883;4950.232713929347;5004.874878128039;5007.424146223195;5139.7687104080405;5770.480656740913;5869.882602347971;6181.805459062058;6199.442050215457;4060.8578699745367;4087.6306437343524;4095.1195492968855;4098.344264917629;4108.876733697994;5115.897682046339;5956.583039187259;6120.571559990803;6496.382843629847;6501.772437964649;3918.9804050979565;3995.760254384746;4149.688773342012;4169.791510424467;4186.769727665008;3855.3445914194654;3974.787689754595;4163.250614889349;4177.749335039907;4220.957790422057;3972.324704800191;4971.428285747185;4973.229149481764;4979;5001.099802035668;4629.86110416688;5624.268845050199;5996.940030599725;6023.789167379121;6255.343188965184;1404.2206575350779;1418.7800890861934;1425.3443416028535;1428.9285535723172;1431.2271373458216;841.6323474354175;863.3481721300378;874.5714599846029;875.7766769473743;880.7621942075732;4060.5382062457493;4703.2004559225015;4749.738764367993;4785.458760517192;4887.413510378778;4980.900381992412;5155.510226528474;5386.572558272061;5402.135658294733;5440.3135028141405;3908.022994251437;4463.397441345442;4578.931316030262;4613.677042606985;4618.67669263155;2456.2140115162974;2473.8515689058713;2485.515324511703;2492.95014099717;2803.313188268887;5498.120300751861;5528.66000189849;5559.416261292587;5605.635633683797;5625.915611265835;4845.3834929138575;5196.532312091855;5331.760070796823;5859.44335288147;6546.378094081055;5773.942260577509;5879.941610510064;6440.388163124496;6645.933540664628;6650.933490665128;5650.378458369658;5864.736086876186;5951.411136155786;6194.81415557534;6419.607529675095;5144.279800827962;5362.865928351666;5505.091659876076;6129.804688085959;6340.524460665376;5551.333839939071;6641.468682505291;6682.396224906085;6701.497387696039;6751.831204219934;3943.507061617298;4129.91740165201;4154.792260386908;4178.24791537514;4180.456540649696;5016.749162541932;6263.4362907338555;6417.422431981144;6484.416402523795;6511.413972742476;5440.836774896759;6064.241969753781;6242.282137554058;6243.470349764337;6267.498600111889;6187.597806142587;6237.345078766664;6260;6265.404786545271;6266.310705822447;4512.368268442355;5327.813526526553;5577.398646117051;6104.6642434664745;6109.389061093855;5436.075867102523;6159.6612186330185;6255.593386429868;6256.467165544471;6316.399942005502;5539.086050801573;5754.9424505754405;6055.212822333068;6057.363976782374;6060.242589621624 12:33:27 INFO - PROCESS | 1885 | |5;yui.html;752.3078767533868;787.8109253779095;799.5402643479997;863.749512641334;873.4148120759093;15042.623934401645;15090.849091509082;15630.9839860409;15755.369785208577;15786.921065394672;14399.856001439983;14473.927630361834;14474.927625361885;14487.420503179887;14489.927550362234;660.4518249852634;892.2638822971043;894.575076838503;911.6717981526646;918.0681608167682;793.2345286798238;904.4482865452069;910.2444970674358;915.7619019055027;917.9127982841653;936.3913456253456;969.5055521683921;971.0144203633317;971.5676523946846;972.868662730533;934.4019827310493;963.4700914496999;963.4845357733614;964.5804075227298;966.9323147379686;670.8691805098008;705.4673721340406;709.1348554763181;721.9675114619855;724.6123324021623;12966.351682415841;14912.776308355384;15024.09855408679;15149.697006059927;15171.696566068727;14835.629109272246;14868.62828429287;14897.255137243095;14913.925430372887;15059.698806023927;12054.578089766905;12276.693082672915;12448.377581120907;12455.190412623151;12509.06182036351;3848.1534062506203;3886.203328317688;4048.210598933209;4070.633642972147;4071.633552980246;111485.88514114836;112572;112892.22646641228;113487.73024539463;121284;11350.886491135148;11386.715332116763;11408.429578521042;11425.942870285595;12029.09781766371;22684.546309073725;23203.651945220834;23404.414889627555;23450.413739656644;23484.295471135552;7937.523748575104;7941.395158513548;7969.203079691984;8011.559364234907;8060.435769496079;3888.202918401721;4465.397171381886;4504.909901801946;4532.206863798822;4749.810007599657;3548.8757893473603;3661.707063434919;3665.523481947383;3671.0455281626437;3950.7827069511454;730.9415246780245;732.8607564562716;737.0602481835703;745.4669911013543;753.4838635534567;527.7888844462245;636.9968150159219;680.7141000779684;700.9509334346649;705.7318219076718;9690.854637180448;9724.562394692255;9785.07041831025;9843.114119729258;9854.21166306708;571.5370549854631;667.5894324990096;669.4811521071111;687.6664817563478;690.4145463202615;4410.051838854662;4588.426446694163;4707.95292047082;4868.561829435368;5062.82280120194;94643.58034629487;114266;148929;165250;177316;142743.28628356793;142830.28584857009;143261;143682;144043;116936;119838.40080799541;124134;128471.99999999814;128988.35505822599;8518.78703032429;8756.78108047304;9925.3548519346;10193.490325483845;10228.897711023092;44467.555324446344;59695.80608387721;63996.68001659962;65030.674846625465;66006.33993660002;89336;93597.59603605815;100416;101097;104140;14219.573412797632;14286.785698214328;14377;14633.414663413345;14972.47596334123;15372.308246129174;15373;15569.844301557292;15588.610284742519;15944.282507286967;14238.572842814317;14295.857041429452;14324;14359.353829077507;14742.088064578651;13292.601221963356;13569.253691047088;13599.52401665937;13856;14034.438622454987;13801.654958626115;13952.930235348758;14012.509562165274;14172.929135354258;14256.857431425968;11347.602833900773;11742.88257117418;11855.34795586251;11911.285322880654;11933.572477742486;10675.06588930604;11313.264637798517;11772.293662380285;11880.82178767302;11928.940355298168 12:33:27 INFO - PROCESS | 1885 | __end_tp_report 12:33:27 INFO - PROCESS | 1885 | __start_cc_report 12:33:27 INFO - PROCESS | 1885 | _x_x_mozilla_cycle_collect,439 12:33:27 INFO - PROCESS | 1885 | __end_cc_report 12:33:27 INFO - PROCESS | 1885 | __startTimestamp1468611207016__endTimestamp 12:33:27 INFO - PROCESS | 1885 | 12:33:27 INFO - PROCESS | 1885 | ------- Summary: start ------- 12:33:27 INFO - PROCESS | 1885 | Number of tests: 6 12:33:27 INFO - PROCESS | 1885 | 12:33:27 INFO - PROCESS | 1885 | [#0] dojo.html Cycles:165 Average:5748.45 Median:6067.76 stddev:678.84 (11.2%) stddev-sans-first:654.63 12:33:27 INFO - PROCESS | 1885 | Values: 3363.3 3383.6 3431.5 3434.5 3439.1 5649.1 5729.2 5741.9 5786.7 5847.2 4773.3 5712.5 5778.8 5824.2 5850.6 5246.7 5286.5 5319.7 5376.9 5401.0 4168.5 5357.4 5411.9 5458.6 5459.6 5080.5 5108.3 5188.6 5201.3 5267.1 5201.1 5206.1 5234.1 5279.1 5283.5 4319.0 5216.5 5249.4 5274.0 5305.1 4532.8 5140.5 5575.6 5758.3 5782.9 6153.3 6237.6 6265.4 6282.8 6283.2 6081.8 6241.6 6315.7 6338.8 6345.9 5851.7 5897.6 5902.4 5903.6 5951.3 6297.0 6319.9 6323.3 6330.7 6340.5 5995.4 6129.8 6164.7 6238.7 6239.1 6121.1 6322.0 6324.2 6343.7 6350.0 6175.8 6241.3 6259.2 6290.9 6329.3 6112.2 6135.4 6245.8 6254.3 6353.9 4303.2 4992.9 5061.0 5080.6 5116.3 4308.6 4357.8 4415.4 4491.7 4492.5 5573.3 5780.6 5809.0 5840.6 5889.6 6240.7 6258.5 6293.7 6294.1 6334.1 5490.8 5627.5 5894.3 5895.1 5917.7 4688.2 5330.9 5444.1 5482.1 5516.6 6372.6 6386.1 6391.5 6393.3 6412.5 6153.9 6237.8 6244.9 6256.7 6268.5 5314.3 6159.1 6202.9 6249.6 6306.3 6143.4 6182.8 6192.7 6195.4 6208.1 6116.3 6239.8 6240.2 6243.9 6287.1 6067.8 6129.6 6147.8 6186.1 6211.9 6079.5 6109.1 6225.9 6243.5 6247.3 5716.1 6018.3 6035.8 6068.5 6153.6 5324.1 6123.1 6149.7 6227.8 6239.5 6042.5 6082.8 6101.6 6105.5 6108.7 12:33:27 INFO - PROCESS | 1885 | 12:33:27 INFO - PROCESS | 1885 | [#1] ext.html Cycles:180 Average:74409.78 Median:40950.59 stddev:170227.64 (415.7%) stddev-sans-first:170614.71 12:33:27 INFO - PROCESS | 1885 | Values: 493.3 522.6 530.5 543.2 574.7 38746.6 41541.8 41638.6 41733.6 41785.8 42195.0 42300.6 42648.8 42696.1 42878.9 9655.8 9761.6 9762.4 9783.4 9788.0 9703.0 9745.9 9817.9 9855.5 9860.7 9777.0 9838.7 9844.1 9854.3 9882.4 9780.8 9789.5 9822.0 9822.4 9839.8 9666.0 9689.7 9722.6 9747.0 9774.7 33399.3 40214.6 40244.0 40459.8 40628.6 3213.5 3371.8 3557.7 3566.4 3647.0 32087.0 32361.2 32712.3 32908.3 33279.5 2922.9 3279.3 3312.5 3394.2 3419.0 456727.0 525378.0 533091.0 534898.0 535656.3 34214.5 35201.9 63738.7 64588.4 64721.0 29597.5 31768.0 34369.8 35687.3 35906.8 14837.2 14918.3 15044.5 15073.8 15109.2 12651.1 12700.0 12817.4 12852.3 12903.6 7728.3 7754.4 7759.4 7762.3 7833.2 3220.3 3253.3 3257.5 3261.2 3281.7 949.7 975.4 999.5 1006.3 1090.1 31195.4 31251.8 31300.2 31804.0 31985.7 1037.2 1116.7 1149.3 1213.2 1234.1 15614.1 16060.7 16143.9 16282.6 16336.0 720802.0 731763.0 735239.0 735723.0 741062.0 61760.4 61831.0 62129.7 62190.4 62292.0 1359.9 1384.0 1424.6 1425.6 1431.9 28559.3 28846.9 28859.6 29108.0 29548.6 53813.0 54038.6 54135.0 54166.7 54440.5 614766.0 636074.0 637105.0 637830.0 639188.0 27323.6 27428.6 27635.6 27666.2 27692.9 50209.5 50243.7 50614.0 51064.0 51733.2 41272.6 41469.8 41521.8 42135.0 42155.6 40604.8 41505.4 41636.8 41856.2 42376.0 38614.6 38798.8 39752.4 39922.4 39957.0 36088.9 36137.5 36146.3 36447.0 36681.6 22974.9 26185.5 26458.0 26497.2 26953.0 12:33:27 INFO - PROCESS | 1885 | 12:33:27 INFO - PROCESS | 1885 | [#2] jquery.html Cycles:180 Average:34456.93 Median:3042.98 stddev:99431.60 (3267.6%) stddev-sans-first:99677.62 12:33:27 INFO - PROCESS | 1885 | Values: 385.1 390.7 392.7 397.2 414.9 20545.3 21990.3 22016.9 22076.6 22387.9 20039.3 21157.3 21327.3 22122.4 22137.1 5767.8 6202.1 6229.1 6230.6 6250.3 5156.2 5333.5 5993.6 6136.2 6154.6 5980.2 6106.3 6139.3 6143.5 6146.1 5537.1 5742.7 6020.4 6099.6 6116.5 5227.4 5298.5 5308.5 5312.5 5318.4 25039.4 26148.5 26210.5 26280.7 27159.5 2212.9 2676.5 2705.9 2881.0 3204.9 15307.6 15795.2 15816.5 16029.0 16194.5 1819.1 1862.2 1873.4 1882.2 1926.5 99864.5 122488.2 125885.0 126739.4 126893.0 10877.1 10951.0 11305.9 11336.8 11494.4 14001.1 14010.0 14028.2 14055.5 14057.3 8655.4 8772.6 8792.9 8865.0 8958.7 8137.0 8770.3 8886.8 8918.4 9055.6 5880.3 6330.0 6902.1 6925.3 7023.4 1904.9 1935.2 1949.7 1972.6 1978.4 829.0 886.9 941.9 954.7 1029.8 19587.2 21039.5 23313.8 23359.4 23498.6 951.3 962.1 981.6 1001.9 1043.9 8937.9 12011.6 12233.8 12381.9 12429.4 509926.0 607501.0 620058.0 624721.0 628181.9 62480.4 63917.4 92869.1 120482.0 130625.0 980.4 981.7 1002.9 1017.6 1023.4 17113.6 20645.8 20952.0 20981.6 21079.4 51815.7 52987.5 54430.5 55405.4 55446.0 63748.7 66785.0 77844.1 78832.6 79529.0 5980.3 7129.7 7233.9 7345.7 7729.7 12672.0 13772.3 13867.7 14023.4 14040.6 11712.2 11948.8 11987.9 12043.9 12059.5 9913.2 11002.9 11320.0 11756.5 12020.9 9533.8 10196.4 11366.0 11938.2 12189.9 7692.3 7716.3 7725.1 7852.1 7969.7 8027.2 8976.5 10655.8 11849.7 11896.5 12:33:27 INFO - PROCESS | 1885 | 12:33:27 INFO - PROCESS | 1885 | [#3] mootools.html Cycles:180 Average:5730.71 Median:2538.56 stddev:3234.34 (127.4%) stddev-sans-first:3227.32 12:33:27 INFO - PROCESS | 1885 | Values: 1437.3 1547.6 1787.4 1928.4 1948.5 8022.2 8571.7 8721.6 9126.6 9185.7 8519.9 8676.0 8688.6 8732.0 8815.8 2304.3 2347.3 2350.6 2352.9 2359.2 2352.7 2371.3 2396.8 2398.8 2407.8 2359.8 2375.3 2385.6 2390.3 2394.7 2333.3 2334.7 2344.8 2359.0 2363.7 1956.4 2328.9 2340.3 2343.1 2365.1 8822.6 8928.6 8944.3 8958.0 8989.4 5263.4 5333.6 5373.9 5380.0 5380.1 3544.9 3638.0 3703.9 3709.6 3729.5 1058.6 1066.9 1096.9 1113.8 1116.2 11444.5 11509.0 11623.6 11645.5 11672.4 4623.0 4634.5 4813.8 4821.1 4845.7 10170.8 10180.1 10189.1 10204.2 10250.5 5321.5 5356.6 5362.7 5384.6 5394.8 4336.1 4754.1 4806.3 4900.5 4917.2 3927.4 3952.4 3983.7 4042.7 4104.7 1257.8 1290.6 1293.6 1302.4 1302.6 825.8 957.6 961.2 991.5 992.7 5572.8 5719.7 6008.5 6096.3 6153.9 380.3 449.9 452.7 454.1 979.6 3397.4 3528.4 3605.0 4141.2 4209.8 10362.4 10736.8 10886.4 10889.7 10949.2 10033.9 10734.0 10827.1 10837.2 10840.3 6788.0 8250.3 8396.2 8439.7 8452.0 5143.4 5152.7 6058.9 6062.5 6167.2 10151.3 10167.3 10180.8 10184.8 10244.3 10049.2 10131.0 10175.5 10324.1 10455.2 6409.1 6908.6 7008.6 7064.8 7117.7 8202.6 8207.1 8381.8 8414.6 8429.8 7448.5 7614.6 7653.4 7689.7 7710.5 7465.5 7528.4 7528.7 7605.1 7652.8 7133.5 7171.7 7184.2 7185.9 7188.1 5003.2 5113.2 5137.3 5191.0 5204.2 4393.4 4862.2 5170.7 5247.7 5251.9 12:33:27 INFO - PROCESS | 1885 | 12:33:27 INFO - PROCESS | 1885 | [#4] prototype.html Cycles:180 Average:4763.16 Median:5149.90 stddev:1529.33 (29.7%) stddev-sans-first:1496.69 12:33:27 INFO - PROCESS | 1885 | Values: 312.2 314.5 323.0 326.3 328.6 4818.0 5436.6 5688.8 5745.3 5889.2 4769.9 5764.8 5767.6 5778.0 5785.4 3637.9 4331.3 4355.0 4384.7 4395.8 3496.8 4318.2 4320.3 4430.7 4456.0 4234.4 4258.7 4272.6 4300.0 4323.9 3600.6 4306.4 4331.9 4338.0 4345.5 2829.6 3228.9 3608.2 3647.6 3715.0 5018.7 5525.8 5603.6 5604.3 5653.2 4564.6 4826.8 4950.2 5004.9 5007.4 5139.8 5770.5 5869.9 6181.8 6199.4 4060.9 4087.6 4095.1 4098.3 4108.9 5115.9 5956.6 6120.6 6496.4 6501.8 3919.0 3995.8 4149.7 4169.8 4186.8 3855.3 3974.8 4163.3 4177.7 4221.0 3972.3 4971.4 4973.2 4979.0 5001.1 4629.9 5624.3 5996.9 6023.8 6255.3 1404.2 1418.8 1425.3 1428.9 1431.2 841.6 863.3 874.6 875.8 880.8 4060.5 4703.2 4749.7 4785.5 4887.4 4980.9 5155.5 5386.6 5402.1 5440.3 3908.0 4463.4 4578.9 4613.7 4618.7 2456.2 2473.9 2485.5 2493.0 2803.3 5498.1 5528.7 5559.4 5605.6 5625.9 4845.4 5196.5 5331.8 5859.4 6546.4 5773.9 5879.9 6440.4 6645.9 6650.9 5650.4 5864.7 5951.4 6194.8 6419.6 5144.3 5362.9 5505.1 6129.8 6340.5 5551.3 6641.5 6682.4 6701.5 6751.8 3943.5 4129.9 4154.8 4178.2 4180.5 5016.7 6263.4 6417.4 6484.4 6511.4 5440.8 6064.2 6242.3 6243.5 6267.5 6187.6 6237.3 6260.0 6265.4 6266.3 4512.4 5327.8 5577.4 6104.7 6109.4 5436.1 6159.7 6255.6 6256.5 6316.4 5539.1 5754.9 6055.2 6057.4 6060.2 12:33:27 INFO - PROCESS | 1885 | 12:33:27 INFO - PROCESS | 1885 | [#5] yui.html Cycles:180 Average:25899.29 Median:23304.03 stddev:41602.52 (178.5%) stddev-sans-first:41676.37 12:33:27 INFO - PROCESS | 1885 | Values: 752.3 787.8 799.5 863.7 873.4 15042.6 15090.8 15631.0 15755.4 15786.9 14399.9 14473.9 14474.9 14487.4 14489.9 660.5 892.3 894.6 911.7 918.1 793.2 904.4 910.2 915.8 917.9 936.4 969.5 971.0 971.6 972.9 934.4 963.5 963.5 964.6 966.9 670.9 705.5 709.1 722.0 724.6 12966.4 14912.8 15024.1 15149.7 15171.7 14835.6 14868.6 14897.3 14913.9 15059.7 12054.6 12276.7 12448.4 12455.2 12509.1 3848.2 3886.2 4048.2 4070.6 4071.6 111485.9 112572.0 112892.2 113487.7 121284.0 11350.9 11386.7 11408.4 11425.9 12029.1 22684.5 23203.7 23404.4 23450.4 23484.3 7937.5 7941.4 7969.2 8011.6 8060.4 3888.2 4465.4 4504.9 4532.2 4749.8 3548.9 3661.7 3665.5 3671.0 3950.8 730.9 732.9 737.1 745.5 753.5 527.8 637.0 680.7 701.0 705.7 9690.9 9724.6 9785.1 9843.1 9854.2 571.5 667.6 669.5 687.7 690.4 4410.1 4588.4 4708.0 4868.6 5062.8 94643.6 114266.0 148929.0 165250.0 177316.0 142743.3 142830.3 143261.0 143682.0 144043.0 116936.0 119838.4 124134.0 128472.0 128988.4 8518.8 8756.8 9925.4 10193.5 10228.9 44467.6 59695.8 63996.7 65030.7 66006.3 89336.0 93597.6 100416.0 101097.0 104140.0 14219.6 14286.8 14377.0 14633.4 14972.5 15372.3 15373.0 15569.8 15588.6 15944.3 14238.6 14295.9 14324.0 14359.4 14742.1 13292.6 13569.3 13599.5 13856.0 14034.4 13801.7 13952.9 14012.5 14172.9 14256.9 11347.6 11742.9 11855.3 11911.3 11933.6 10675.1 11313.3 11772.3 11880.8 11928.9 12:33:27 INFO - PROCESS | 1885 | -------- Summary: end -------- 12:33:27 INFO - PROCESS | 1885 | 12:33:27 INFO - TEST-INFO | 1885: exit 0 12:33:27 INFO - TEST-OK | dromaeo_css | took 1112885ms 12:33:27 INFO - TEST-START | kraken 12:33:27 INFO - operating with platform_type : mac_ 12:33:27 INFO - Initialising browser for kraken test... 12:33:27 INFO - TEST-INFO | started process 1893 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/tmpXcS25o/profile http://localhost:49276/getInfo.html) 12:33:30 INFO - PROCESS | 1893 | __metrics Screen width/height:1600/1200 12:33:30 INFO - PROCESS | 1893 | colorDepth:24 12:33:30 INFO - PROCESS | 1893 | Browser inner width/height: 1024/689 12:33:30 INFO - PROCESS | 1893 | __metrics 12:33:30 ERROR - PROCESS | 1893 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2964: TypeError: this.tabs is undefined 12:33:31 INFO - TEST-INFO | 1893: exit 0 12:33:31 INFO - Browser initialized. 12:33:31 INFO - Running cycle 1/1 for kraken test... 12:33:31 INFO - TEST-INFO | started process 1895 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/tmpXcS25o/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:33:43 INFO - PROCESS | 1895 | RSS: Main: 254345216 12:33:43 INFO - PROCESS | 1895 | 12:33:45 INFO - PROCESS | 1895 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 12:33:46 INFO - PROCESS | 1895 | RSS: Main: 267128832 12:33:46 INFO - PROCESS | 1895 | 12:33:49 INFO - PROCESS | 1895 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 12:33:49 INFO - PROCESS | 1895 | RSS: Main: 271163392 12:33:49 INFO - PROCESS | 1895 | 12:33:53 INFO - PROCESS | 1895 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 12:33:54 INFO - PROCESS | 1895 | RSS: Main: 271417344 12:33:54 INFO - PROCESS | 1895 | 12:33:56 INFO - PROCESS | 1895 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 12:33:56 INFO - PROCESS | 1895 | RSS: Main: 272330752 12:33:56 INFO - PROCESS | 1895 | 12:33:58 INFO - PROCESS | 1895 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:33:59 INFO - PROCESS | 1895 | RSS: Main: 272961536 12:33:59 INFO - PROCESS | 1895 | 12:34:02 INFO - PROCESS | 1895 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 12:34:03 INFO - PROCESS | 1895 | RSS: Main: 273580032 12:34:03 INFO - PROCESS | 1895 | 12:34:06 INFO - PROCESS | 1895 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 12:34:06 INFO - PROCESS | 1895 | RSS: Main: 273797120 12:34:06 INFO - PROCESS | 1895 | 12:34:09 INFO - PROCESS | 1895 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 12:34:10 INFO - PROCESS | 1895 | RSS: Main: 273911808 12:34:10 INFO - PROCESS | 1895 | 12:34:11 INFO - PROCESS | 1895 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:34:12 INFO - PROCESS | 1895 | RSS: Main: 273678336 12:34:12 INFO - PROCESS | 1895 | 12:34:14 INFO - PROCESS | 1895 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:34:15 INFO - PROCESS | 1895 | RSS: Main: 273993728 12:34:15 INFO - PROCESS | 1895 | 12:34:17 INFO - PROCESS | 1895 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:34:18 INFO - PROCESS | 1895 | RSS: Main: 273924096 12:34:18 INFO - PROCESS | 1895 | 12:34:20 INFO - PROCESS | 1895 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:34:21 INFO - PROCESS | 1895 | RSS: Main: 273805312 12:34:21 INFO - PROCESS | 1895 | 12:34:24 INFO - PROCESS | 1895 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:34:25 INFO - PROCESS | 1895 | RSS: Main: 273969152 12:34:25 INFO - PROCESS | 1895 | 12:34:26 INFO - PROCESS | 1895 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 12:34:27 INFO - PROCESS | 1895 | RSS: Main: 274636800 12:34:27 INFO - PROCESS | 1895 | 12:34:27 INFO - PROCESS | 1895 | __start_tp_report 12:34:27 INFO - PROCESS | 1895 | _x_x_mozilla_page_load 12:34:27 INFO - PROCESS | 1895 | _x_x_mozilla_page_load_details 12:34:27 INFO - PROCESS | 1895 | |i|pagename|runs| 12:34:27 INFO - PROCESS | 1895 | |0;ai-astar;103;117;107;117;116;118;115;117;117;153 12:34:27 INFO - PROCESS | 1895 | |1;audio-beat-detection;140;111;134;168;136;159;128;108;159;106 12:34:27 INFO - PROCESS | 1895 | |2;audio-dft;193;202;193;216;273;271;267;201;197;204 12:34:27 INFO - PROCESS | 1895 | |3;audio-fft;70;82;85;178;82;51;71;78;157;83 12:34:27 INFO - PROCESS | 1895 | |4;audio-oscillator;70;78;132;70;76;102;148;61;85;109 12:34:27 INFO - PROCESS | 1895 | |5;imaging-gaussian-blur;112;130;110;85;109;129;82;132;132;85 12:34:27 INFO - PROCESS | 1895 | |6;imaging-darkroom;123;94;94;133;95;95;95;98;94;95 12:34:27 INFO - PROCESS | 1895 | |7;imaging-desaturate;101;93;89;92;93;90;90;93;91;92 12:34:27 INFO - PROCESS | 1895 | |8;json-parse-financial;56;57;56;57;57;58;58;58;57;55 12:34:27 INFO - PROCESS | 1895 | |9;json-stringify-tinderbox;66;99;98;109;83;76;75;70;79;101 12:34:27 INFO - PROCESS | 1895 | |10;stanford-crypto-aes;94;100;104;103;85;89;86;87;85;83 12:34:27 INFO - PROCESS | 1895 | |11;stanford-crypto-ccm;134;147;154;129;146;154;149;134;167;141 12:34:27 INFO - PROCESS | 1895 | |12;stanford-crypto-pbkdf2;168;172;170;173;165;164;164;167;165;165 12:34:27 INFO - PROCESS | 1895 | |13;stanford-crypto-sha256-iterative;67;67;68;64;67;69;80;88;81;73 12:34:27 INFO - PROCESS | 1895 | __end_tp_report 12:34:27 INFO - PROCESS | 1895 | __start_cc_report 12:34:27 INFO - PROCESS | 1895 | _x_x_mozilla_cycle_collect,1131 12:34:27 INFO - PROCESS | 1895 | __end_cc_report 12:34:27 INFO - PROCESS | 1895 | __startTimestamp1468611267047__endTimestamp 12:34:27 INFO - PROCESS | 1895 | 12:34:27 INFO - PROCESS | 1895 | ------- Summary: start ------- 12:34:27 INFO - PROCESS | 1895 | Number of tests: 14 12:34:27 INFO - PROCESS | 1895 | 12:34:27 INFO - PROCESS | 1895 | [#0] ai-astar Cycles:10 Average:118.00 Median:117.00 stddev:13.28 (11.4%) stddev-sans-first:12.93 12:34:27 INFO - PROCESS | 1895 | Values: 103.0 117.0 107.0 117.0 116.0 118.0 115.0 117.0 117.0 153.0 12:34:27 INFO - PROCESS | 1895 | 12:34:27 INFO - PROCESS | 1895 | [#1] audio-beat-detection Cycles:10 Average:134.90 Median:138.00 stddev:22.27 (16.1%) stddev-sans-first:23.54 12:34:27 INFO - PROCESS | 1895 | Values: 140.0 111.0 134.0 168.0 136.0 159.0 128.0 108.0 159.0 106.0 12:34:27 INFO - PROCESS | 1895 | 12:34:27 INFO - PROCESS | 1895 | [#2] audio-dft Cycles:10 Average:221.70 Median:210.00 stddev:34.21 (16.3%) stddev-sans-first:34.68 12:34:27 INFO - PROCESS | 1895 | Values: 193.0 202.0 193.0 216.0 273.0 271.0 267.0 201.0 197.0 204.0 12:34:27 INFO - PROCESS | 1895 | 12:34:27 INFO - PROCESS | 1895 | [#3] audio-fft Cycles:10 Average:93.70 Median:80.00 stddev:40.45 (50.6%) stddev-sans-first:41.98 12:34:27 INFO - PROCESS | 1895 | Values: 70.0 82.0 85.0 178.0 82.0 51.0 71.0 78.0 157.0 83.0 12:34:27 INFO - PROCESS | 1895 | 12:34:27 INFO - PROCESS | 1895 | [#4] audio-oscillator Cycles:10 Average:93.10 Median:70.00 stddev:28.95 (41.4%) stddev-sans-first:29.47 12:34:27 INFO - PROCESS | 1895 | Values: 70.0 78.0 132.0 70.0 76.0 102.0 148.0 61.0 85.0 109.0 12:34:27 INFO - PROCESS | 1895 | 12:34:27 INFO - PROCESS | 1895 | [#5] imaging-gaussian-blur Cycles:10 Average:110.60 Median:132.00 stddev:20.45 (15.5%) stddev-sans-first:21.69 12:34:27 INFO - PROCESS | 1895 | Values: 112.0 130.0 110.0 85.0 109.0 129.0 82.0 132.0 132.0 85.0 12:34:27 INFO - PROCESS | 1895 | 12:34:27 INFO - PROCESS | 1895 | [#6] imaging-darkroom Cycles:10 Average:101.60 Median:95.00 stddev:14.16 (14.9%) stddev-sans-first:12.73 12:34:27 INFO - PROCESS | 1895 | Values: 123.0 94.0 94.0 133.0 95.0 95.0 95.0 98.0 94.0 95.0 12:34:27 INFO - PROCESS | 1895 | 12:34:27 INFO - PROCESS | 1895 | [#7] imaging-desaturate Cycles:10 Average:92.40 Median:92.00 stddev:3.34 (3.6%) stddev-sans-first:1.51 12:34:27 INFO - PROCESS | 1895 | Values: 101.0 93.0 89.0 92.0 93.0 90.0 90.0 93.0 91.0 92.0 12:34:27 INFO - PROCESS | 1895 | 12:34:27 INFO - PROCESS | 1895 | [#8] json-parse-financial Cycles:10 Average:56.90 Median:57.00 stddev:0.99 (1.7%) stddev-sans-first:1.00 12:34:27 INFO - PROCESS | 1895 | Values: 56.0 57.0 56.0 57.0 57.0 58.0 58.0 58.0 57.0 55.0 12:34:27 INFO - PROCESS | 1895 | 12:34:27 INFO - PROCESS | 1895 | [#9] json-stringify-tinderbox Cycles:10 Average:85.60 Median:77.50 stddev:14.91 (19.2%) stddev-sans-first:14.02 12:34:27 INFO - PROCESS | 1895 | Values: 66.0 99.0 98.0 109.0 83.0 76.0 75.0 70.0 79.0 101.0 12:34:27 INFO - PROCESS | 1895 | 12:34:27 INFO - PROCESS | 1895 | [#10] stanford-crypto-aes Cycles:10 Average:91.60 Median:85.50 stddev:8.03 (9.4%) stddev-sans-first:8.47 12:34:27 INFO - PROCESS | 1895 | Values: 94.0 100.0 104.0 103.0 85.0 89.0 86.0 87.0 85.0 83.0 12:34:27 INFO - PROCESS | 1895 | 12:34:27 INFO - PROCESS | 1895 | [#11] stanford-crypto-ccm Cycles:10 Average:145.50 Median:148.00 stddev:11.44 (7.7%) stddev-sans-first:11.36 12:34:27 INFO - PROCESS | 1895 | Values: 134.0 147.0 154.0 129.0 146.0 154.0 149.0 134.0 167.0 141.0 12:34:27 INFO - PROCESS | 1895 | 12:34:27 INFO - PROCESS | 1895 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.30 Median:167.50 stddev:3.33 (2.0%) stddev-sans-first:3.53 12:34:27 INFO - PROCESS | 1895 | Values: 168.0 172.0 170.0 173.0 165.0 164.0 164.0 167.0 165.0 165.0 12:34:27 INFO - PROCESS | 1895 | 12:34:27 INFO - PROCESS | 1895 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.40 Median:71.00 stddev:7.92 (11.2%) stddev-sans-first:8.15 12:34:27 INFO - PROCESS | 1895 | Values: 67.0 67.0 68.0 64.0 67.0 69.0 80.0 88.0 81.0 73.0 12:34:27 INFO - PROCESS | 1895 | -------- Summary: end -------- 12:34:27 INFO - PROCESS | 1895 | 12:34:27 INFO - TEST-INFO | 1895: exit 0 12:34:27 INFO - TEST-OK | kraken | took 60232ms 12:34:27 INFO - SUITE-END | took 1173s 12:34:28 INFO - Completed test suite (00:19:33) 12:34:28 INFO - javascript benchmark 12:34:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7459.259521433402, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3363.3441478911623, 3383.560137182166, 3431.50243214734, 3434.5363375944257, 3439.0714507083103, 5649.096144616862, 5729.197912292282, 5741.913871291928, 5786.739596718147, 5847.1521629363715, 4773.284007398892, 5712.542996560275, 5778.768849246036, 5824.213731146305, 5850.619709718865, 5246.685198888079, 5286.471352864702, 5319.707416092133, 5376.892462150755, 5401.0, 4168.5414604393645, 5357.4374690657505, 5411.945880541203, 5458.563314934816, 5459.563234941195, 5080.491950804927, 5108.335916330853, 5188.558972487343, 5201.323827902386, 5267.130923397634, 5201.063808514466, 5206.114960456732, 5234.110201265794, 5279.102552566034, 5283.471652834724, 4319.0, 5216.452272511369, 5249.396319423277, 5273.9736301318635, 5305.098133317345, 4532.818687252506, 5140.460251673558, 5575.637583557056, 5758.251427314464, 5782.942170578324, 6153.292371377304, 6237.563370564062, 6265.373462653698, 6282.811515654537, 6283.246010478726, 6081.787137450212, 6241.563090583661, 6315.652639104847, 6338.778142765027, 6345.9365406345805, 5851.736671849777, 5897.587168898179, 5902.380250073767, 5903.645781253139, 5951.345352011275, 6297.011183189387, 6319.873602527924, 6323.336049714714, 6330.683465826783, 6340.461060809791, 5995.430434108753, 6129.847850912876, 6164.722587483574, 6238.656873871981, 6239.06414037898, 6121.117656725397, 6321.968390158112, 6324.209473815773, 6343.651099189589, 6350.0, 6175.84560385994, 6241.282252540925, 6259.155014073133, 6290.874182516324, 6329.3354197809485, 6112.235970503687, 6135.417135372132, 6245.843853903689, 6254.34329395416, 6353.936460635335, 4303.1828628852045, 4992.90156165643, 5061.0, 5080.644354895122, 5116.30929824469, 4308.569143085666, 4357.758038958945, 4415.448068991376, 4491.663125265618, 4492.528284530143, 5573.27547418833, 5780.566457515703, 5809.041508151192, 5840.649561026267, 5889.558283128783, 6240.687965601702, 6258.475018253918, 6293.685315734195, 6294.118823364733, 6334.113224148623, 5490.846922146314, 5627.493525582717, 5894.2926848778425, 5895.056790913433, 5917.704114794243, 4688.203005489075, 5330.866728331745, 5444.1017232156255, 5482.122860342326, 5516.641418307797, 6372.649504277217, 6386.074019267087, 6391.488680905423, 6393.29673735898, 6412.5190610704385, 6153.938460615515, 6237.781677641261, 6244.875102497834, 6256.718447669957, 6268.4671802897265, 5314.335708036496, 6159.106929495288, 6202.875942481215, 6249.562530622813, 6306.342321453454, 6143.354947730424, 6182.783602574068, 6192.721327540181, 6195.4424101829245, 6208.0998255253635, 6116.305337585956, 6239.804444887916, 6240.219972503437, 6243.937560624336, 6287.056941458818, 6067.757289708362, 6129.633877546871, 6147.754089836535, 6186.072089186658, 6211.9068213977735, 6079.513638908788, 6109.083637454418, 6225.906611400923, 6243.500519958483, 6247.250329960434, 5716.142578613241, 6018.307894592178, 6035.818925432244, 6068.514518838394, 6153.5692501526255, 5324.148136298174, 6123.142760013513, 6149.72326245329, 6227.813165605039, 6239.532035097295, 6042.546808989431, 6082.817515474543, 6101.633901965896, 6105.542084343603, 6108.725107370268], "value": 5705.810145572544, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [493.26257245418105, 522.5975998481172, 530.4562823106317, 543.2259030880994, 574.6638216643262, 38746.61253387465, 41541.792291038575, 41638.58361416389, 41733.58266417335, 41785.79107104468, 42195.0, 42300.57699423005, 42648.786756066256, 42696.14607707844, 42878.928026799265, 9655.806883862319, 9761.560729767178, 9762.365446246007, 9783.41299522031, 9787.951060244688, 9703.029697030312, 9745.853812192823, 9817.901820981806, 9855.507224638775, 9860.654877079303, 9777.0, 9838.704838854846, 9844.114029737322, 9854.310198286123, 9882.407055576688, 9780.804383912353, 9789.510524473784, 9822.0, 9822.361546499491, 9839.80320393588, 9665.951670241604, 9689.660861869801, 9722.611095556169, 9746.951265243628, 9774.706758797247, 33399.3320133596, 40214.59785402138, 40244.0, 40459.7977010116, 40628.59371406307, 3213.53403756454, 3371.7808342457906, 3557.6620221078956, 3566.3937130687846, 3647.0354158376285, 32087.0, 32361.190970225696, 32712.345753084806, 32908.341833163446, 33279.50080748791, 2922.8976985805393, 3279.262166012628, 3312.5362449257123, 3394.2362968332172, 3419.042668052949, 456727.0, 525378.0, 533091.0, 534898.0, 535656.3217183928, 34214.48678269828, 35201.94394168179, 63738.72522549523, 64588.35411645917, 64721.0, 29597.520123993716, 31768.0, 34369.82815085909, 35687.28625427529, 35906.8204658975, 14837.183954882585, 14918.328675209425, 15044.473443429646, 15073.773893391608, 15109.168995705122, 12651.0511711622, 12700.031495118272, 12817.35913204336, 12852.293123878277, 12903.612891613267, 7728.343090837229, 7754.3796496280165, 7759.447151873447, 7762.262585054411, 7833.2166783322355, 3220.259340351732, 3253.2680146966745, 3257.5439438478634, 3261.184703824044, 3281.6882396172327, 949.6676163342775, 975.4244995452717, 999.4802702594756, 1006.3106771861299, 1090.111559079348, 31195.376092478476, 31251.84374078115, 31300.217494562363, 31804.0, 31985.680143198737, 1037.1702637889657, 1116.6929094498914, 1149.3448734221413, 1213.2174747288127, 1234.1361047266948, 15614.08982187171, 16060.67878642444, 16143.919280403523, 16282.615977642034, 16336.0, 720802.0, 731763.0, 735239.0, 735723.0, 741062.0, 61760.38239617546, 61831.0, 62129.68935155295, 62190.37809621846, 62292.0, 1359.8776110150134, 1383.9930800345933, 1424.6295963049474, 1425.5580769961139, 1431.9212443315719, 28559.28601784972, 28846.855765721037, 28859.56710649384, 29108.0, 29548.556771648873, 53813.0, 54038.6490337729, 54135.0, 54166.729166353914, 54440.45559544512, 614766.0, 636074.0, 637105.0, 637830.0, 639188.0, 27323.590146147428, 27428.588571171847, 27635.58546621762, 27666.170014899584, 27692.86153569219, 50209.497905020486, 50243.74878125732, 50614.0, 51063.97872042612, 51733.22400163925, 41272.587274126876, 41469.79265103655, 41521.79239103785, 42135.0, 42155.57844421639, 40604.79697601493, 41505.37741933813, 41636.79181604073, 41856.19846247405, 42376.0, 38614.613853862225, 38798.80600597092, 39752.40371394489, 39922.401163983144, 39957.001074973356, 36088.917332480065, 36137.46384305258, 36146.27707445784, 36447.0, 36681.63318366782, 22974.892707071587, 26185.476290474464, 26458.0, 26497.205083847515, 26953.0], "value": 19262.01926862281, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.09310573599174, 390.74992005116724, 392.7231301932138, 397.2254104496232, 414.8983499042735, 20545.280915167972, 21990.340289791333, 22016.88991555042, 22076.558468830615, 22387.880605969716, 20039.298624548108, 21157.259495917646, 21327.25354612589, 22122.44693882654, 22137.114515419406, 5767.769289228426, 6202.100695399165, 6229.127922090911, 6230.5638605297645, 6250.343713910042, 5156.200788877712, 5333.54664853486, 5993.550483713717, 6136.171616831715, 6154.630722156685, 5980.192673989, 6106.328303886569, 6139.293981192175, 6143.539234557404, 6146.07808828677, 5537.0863807471715, 5742.655440673552, 6020.367861374536, 6099.634021958653, 6116.541259405541, 5227.3988491323225, 5298.549623282026, 5308.522232999048, 5312.548433383167, 5318.441563635801, 25039.374015649755, 26148.477030459286, 26210.475790484084, 26280.73719262821, 27159.45681086387, 2212.9114835406563, 2676.5048466033845, 2705.905293314744, 2881.034853324139, 3204.9198770030703, 15307.617309567238, 15795.21023948798, 15816.52550423489, 16029.0, 16194.514164574965, 1819.1086367679875, 1862.1620270878159, 1873.4379686094117, 1882.1718443884783, 1926.5087402712359, 99864.50203246884, 122488.16267755991, 125885.0, 126739.36630316882, 126893.0, 10877.129829613612, 10951.0, 11305.886941130648, 11336.773264534662, 11494.42527873603, 14001.1098501703, 14010.0, 14028.158310501403, 14055.508057217949, 14057.297135143202, 8655.350848686248, 8772.561371931379, 8792.889706470529, 8864.955675221583, 8958.68644597436, 8136.959315203386, 8770.298376129895, 8886.822263554694, 8918.420302680304, 9055.59249833759, 5880.26496687914, 6330.037688316636, 6902.102726645504, 6925.272846351161, 7023.403010744144, 1904.9142788574547, 1935.167877812554, 1949.7465329507356, 1972.6350625134396, 1978.4460351101714, 829.0010537302536, 886.9445360021554, 941.9105185007413, 954.737447202025, 1029.7734498410487, 19587.216511339386, 21039.474013149793, 23313.7668623315, 23359.416014599432, 23498.64752028721, 951.3293128344638, 962.0908241711516, 981.5681100315838, 1001.8597396364515, 1043.9373637581618, 8937.910620893837, 12011.579594714143, 12233.816492752616, 12381.947534459672, 12429.440675169639, 509926.0, 607501.0, 620058.0, 624721.0, 628181.8590907108, 62480.375196248366, 63917.36082639207, 92869.14261714728, 120482.0, 130625.0, 980.4460479828874, 981.7202097402206, 1002.9247806414548, 1017.5624481473037, 1023.3552861697083, 17113.572160695585, 20645.79354206499, 20951.952402379517, 20981.580368392853, 21079.367618971457, 51815.74092129515, 52987.470125298256, 54430.45569544412, 55405.44594554164, 55446.0, 63748.68125659342, 66784.99822502569, 77844.05389865299, 78832.60583697046, 79529.0, 5980.282366116094, 7129.6810090133495, 7233.855322893618, 7345.70617175307, 7729.652165652672, 12672.0, 13772.31138443094, 13867.73706778507, 14023.439062437388, 14040.648983775487, 11712.238704484182, 11948.820767688318, 11987.880121198676, 12043.879561204276, 12059.457324420247, 9913.206943444364, 11002.889971100507, 11319.981201691888, 11756.529738810352, 12020.939895300467, 9533.761655958378, 10196.4391958443, 11365.977062064454, 11938.22401543897, 12189.939050304693, 7692.307692307576, 7716.344110750649, 7725.111612164448, 7852.13626501073, 7969.681212751657, 8027.1972802719265, 8976.506292153994, 10655.786884262427, 11849.703757405789, 11896.464659090188], "value": 9861.865781968627, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1437.2957250947036, 1547.5589457004753, 1787.383352743304, 1928.373278684428, 1948.4739120437482, 8022.237887400687, 8571.699990500334, 8721.607527661256, 9126.58930348135, 9185.724428267155, 8519.87220191696, 8676.0, 8688.565571721405, 8732.0, 8815.779605509866, 2304.2626359564947, 2347.2606129069372, 2350.5533948549805, 2352.9411764705847, 2359.1978727232736, 2352.7411984681758, 2371.288613415977, 2396.8442051266697, 2398.772116648924, 2407.8194135439826, 2359.834811563191, 2375.3349062262596, 2385.6183010718287, 2390.3068110248096, 2394.736578976311, 2333.3116730564525, 2334.743178250391, 2344.78896899279, 2359.0, 2363.6690863279155, 1956.3739603326942, 2328.93013209604, 2340.3330050935574, 2343.133040774924, 2365.1249037856105, 8822.647094116228, 8928.553572321358, 8944.329175311812, 8958.0, 8989.37074404792, 5263.368395792491, 5333.57331413486, 5373.946260537384, 5380.031594313022, 5380.139177731544, 3544.946825797589, 3637.963620363789, 3703.8888833335045, 3709.55485341758, 3729.4778730977687, 1058.5924419098626, 1066.8826429092794, 1096.9012788849043, 1113.799516087104, 1116.2353787655402, 11444.484998175101, 11509.0, 11623.59317423886, 11645.534178632844, 11672.416379181008, 4623.0, 4634.513376095529, 4813.807447702053, 4821.108095002436, 4845.733484658307, 10170.847437288447, 10180.083792458567, 10189.082982531607, 10204.23468239885, 10250.487475626189, 5321.467853214647, 5356.625036247426, 5362.678239305654, 5384.623076384615, 5394.838154855361, 4336.111097225062, 4754.096721622881, 4806.327114204014, 4900.509949005071, 4917.237828136645, 3927.430522574211, 3952.3873799561115, 3983.661388781986, 4042.676585873182, 4104.712670113123, 1257.8427696537933, 1290.6128161551496, 1293.5989843148654, 1302.4139137388213, 1302.6092172348258, 825.8389614025327, 957.5643082397493, 961.245422343471, 991.4646091110864, 992.667456402099, 5572.777088916398, 5719.714014299351, 6008.489278411384, 6096.268447786294, 6153.876922461615, 380.26418879468315, 449.8537975158021, 452.7170518425984, 454.12807409773313, 979.5747187841673, 3397.371486275047, 3528.40017197072, 3604.9639503604626, 4141.233871733739, 4209.789510524462, 10362.430066346273, 10736.838947415796, 10886.40124793144, 10889.673309800719, 10949.178811589163, 10033.899661003297, 10734.0, 10827.07969822574, 10837.18721095921, 10840.349579025284, 6788.0, 8250.257476827115, 8396.202360775716, 8439.70461033861, 8451.957740211259, 5143.434222235476, 5152.690838549699, 6058.90911636317, 6062.484688801501, 6167.167432396569, 10151.289409741246, 10167.339122956984, 10180.79638407213, 10184.796304073729, 10244.334118282288, 10049.19606431469, 10131.0, 10175.491225438847, 10324.122449591569, 10455.215858810467, 6409.102725618324, 6908.5509441886115, 7008.579485230903, 7064.82337941535, 7117.7152913882865, 8202.630881610461, 8207.056188538394, 8381.790455238668, 8414.579271036302, 8429.789255268668, 7448.478606497493, 7614.619269036637, 7653.387728981779, 7689.730859419893, 7710.460267781202, 7465.514741541782, 7528.397728181622, 7528.661210245662, 7605.125410577854, 7652.846943061219, 7133.464990125865, 7171.748988785576, 7184.245654206442, 7185.856282874418, 7188.101487314086, 5003.224500202475, 5113.181890897438, 5137.332146821039, 5191.0, 5204.245384419314, 4393.406890069799, 4862.173430516891, 5170.741462926914, 5247.685138891678, 5251.921221181609], "value": 4553.538812973093, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [312.1602888230661, 314.45442157856115, 322.9660885607011, 326.31799538963577, 328.62864962592266, 4818.036392721457, 5436.619436639437, 5688.800891968782, 5745.2818397700285, 5889.232060931361, 4769.855234743663, 5764.769409223636, 5767.625104368223, 5778.0466223073145, 5785.421457854212, 3637.9272414551824, 4331.263685173512, 4354.97822510887, 4384.7149935254265, 4395.802188901491, 3496.8251587420655, 4318.2227199104145, 4320.265554855667, 4430.712003719748, 4455.952851079994, 4234.364845273219, 4258.659307255412, 4272.615464608185, 4299.978500107495, 4323.913521729563, 3600.5859326177565, 4306.406629547072, 4331.913361732748, 4338.0, 4345.543717909605, 2829.575563665446, 3228.886988955399, 3608.1881576645305, 3647.58052823926, 3715.0, 5018.698878067327, 5525.806596769094, 5603.579731520112, 5604.327480702342, 5653.23681303027, 4564.589186973155, 4826.758662066883, 4950.232713929347, 5004.874878128039, 5007.424146223195, 5139.7687104080405, 5770.480656740913, 5869.882602347971, 6181.805459062058, 6199.442050215457, 4060.8578699745367, 4087.6306437343524, 4095.1195492968855, 4098.344264917629, 4108.876733697994, 5115.897682046339, 5956.583039187259, 6120.571559990803, 6496.382843629847, 6501.772437964649, 3918.9804050979565, 3995.760254384746, 4149.688773342012, 4169.791510424467, 4186.769727665008, 3855.3445914194654, 3974.787689754595, 4163.250614889349, 4177.749335039907, 4220.957790422057, 3972.324704800191, 4971.428285747185, 4973.229149481764, 4979.0, 5001.099802035668, 4629.86110416688, 5624.268845050199, 5996.940030599725, 6023.789167379121, 6255.343188965184, 1404.2206575350779, 1418.7800890861934, 1425.3443416028535, 1428.9285535723172, 1431.2271373458216, 841.6323474354175, 863.3481721300378, 874.5714599846029, 875.7766769473743, 880.7621942075732, 4060.5382062457493, 4703.2004559225015, 4749.738764367993, 4785.458760517192, 4887.413510378778, 4980.900381992412, 5155.510226528474, 5386.572558272061, 5402.135658294733, 5440.3135028141405, 3908.022994251437, 4463.397441345442, 4578.931316030262, 4613.677042606985, 4618.67669263155, 2456.2140115162974, 2473.8515689058713, 2485.515324511703, 2492.95014099717, 2803.313188268887, 5498.120300751861, 5528.66000189849, 5559.416261292587, 5605.635633683797, 5625.915611265835, 4845.3834929138575, 5196.532312091855, 5331.760070796823, 5859.44335288147, 6546.378094081055, 5773.942260577509, 5879.941610510064, 6440.388163124496, 6645.933540664628, 6650.933490665128, 5650.378458369658, 5864.736086876186, 5951.411136155786, 6194.81415557534, 6419.607529675095, 5144.279800827962, 5362.865928351666, 5505.091659876076, 6129.804688085959, 6340.524460665376, 5551.333839939071, 6641.468682505291, 6682.396224906085, 6701.497387696039, 6751.831204219934, 3943.507061617298, 4129.91740165201, 4154.792260386908, 4178.24791537514, 4180.456540649696, 5016.749162541932, 6263.4362907338555, 6417.422431981144, 6484.416402523795, 6511.413972742476, 5440.836774896759, 6064.241969753781, 6242.282137554058, 6243.470349764337, 6267.498600111889, 6187.597806142587, 6237.345078766664, 6260.0, 6265.404786545271, 6266.310705822447, 4512.368268442355, 5327.813526526553, 5577.398646117051, 6104.6642434664745, 6109.389061093855, 5436.075867102523, 6159.6612186330185, 6255.593386429868, 6256.467165544471, 6316.399942005502, 5539.086050801573, 5754.9424505754405, 6055.212822333068, 6057.363976782374, 6060.242589621624], "value": 4275.0941781627425, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [752.3078767533868, 787.8109253779095, 799.5402643479997, 863.749512641334, 873.4148120759093, 15042.623934401645, 15090.849091509082, 15630.9839860409, 15755.369785208577, 15786.921065394672, 14399.856001439983, 14473.927630361834, 14474.927625361885, 14487.420503179887, 14489.927550362234, 660.4518249852634, 892.2638822971043, 894.575076838503, 911.6717981526646, 918.0681608167682, 793.2345286798238, 904.4482865452069, 910.2444970674358, 915.7619019055027, 917.9127982841653, 936.3913456253456, 969.5055521683921, 971.0144203633317, 971.5676523946846, 972.868662730533, 934.4019827310493, 963.4700914496999, 963.4845357733614, 964.5804075227298, 966.9323147379686, 670.8691805098008, 705.4673721340406, 709.1348554763181, 721.9675114619855, 724.6123324021623, 12966.351682415841, 14912.776308355384, 15024.09855408679, 15149.697006059927, 15171.696566068727, 14835.629109272246, 14868.62828429287, 14897.255137243095, 14913.925430372887, 15059.698806023927, 12054.578089766905, 12276.693082672915, 12448.377581120907, 12455.190412623151, 12509.06182036351, 3848.1534062506203, 3886.203328317688, 4048.210598933209, 4070.633642972147, 4071.633552980246, 111485.88514114836, 112572.0, 112892.22646641228, 113487.73024539463, 121284.0, 11350.886491135148, 11386.715332116763, 11408.429578521042, 11425.942870285595, 12029.09781766371, 22684.546309073725, 23203.651945220834, 23404.414889627555, 23450.413739656644, 23484.295471135552, 7937.523748575104, 7941.395158513548, 7969.203079691984, 8011.559364234907, 8060.435769496079, 3888.202918401721, 4465.397171381886, 4504.909901801946, 4532.206863798822, 4749.810007599657, 3548.8757893473603, 3661.707063434919, 3665.523481947383, 3671.0455281626437, 3950.7827069511454, 730.9415246780245, 732.8607564562716, 737.0602481835703, 745.4669911013543, 753.4838635534567, 527.7888844462245, 636.9968150159219, 680.7141000779684, 700.9509334346649, 705.7318219076718, 9690.854637180448, 9724.562394692255, 9785.07041831025, 9843.114119729258, 9854.21166306708, 571.5370549854631, 667.5894324990096, 669.4811521071111, 687.6664817563478, 690.4145463202615, 4410.051838854662, 4588.426446694163, 4707.95292047082, 4868.561829435368, 5062.82280120194, 94643.58034629487, 114266.0, 148929.0, 165250.0, 177316.0, 142743.28628356793, 142830.28584857009, 143261.0, 143682.0, 144043.0, 116936.0, 119838.40080799541, 124134.0, 128471.99999999814, 128988.35505822599, 8518.78703032429, 8756.78108047304, 9925.3548519346, 10193.490325483845, 10228.897711023092, 44467.555324446344, 59695.80608387721, 63996.68001659962, 65030.674846625465, 66006.33993660002, 89336.0, 93597.59603605815, 100416.0, 101097.0, 104140.0, 14219.573412797632, 14286.785698214328, 14377.0, 14633.414663413345, 14972.47596334123, 15372.308246129174, 15373.0, 15569.844301557292, 15588.610284742519, 15944.282507286967, 14238.572842814317, 14295.857041429452, 14324.0, 14359.353829077507, 14742.088064578651, 13292.601221963356, 13569.253691047088, 13599.52401665937, 13856.0, 14034.438622454987, 13801.654958626115, 13952.930235348758, 14012.509562165274, 14172.929135354258, 14256.857431425968, 11347.602833900773, 11742.88257117418, 11855.34795586251, 11911.285322880654, 11933.572477742486, 10675.06588930604, 11313.264637798517, 11772.293662380285, 11880.82178767302, 11928.940355298168], "value": 8163.1511726010185, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1585.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 117.0, 107.0, 117.0, 116.0, 118.0, 115.0, 117.0, 117.0, 153.0], "value": 118.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 111.0, 134.0, 168.0, 136.0, 159.0, 128.0, 108.0, 159.0, 106.0], "value": 134.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 202.0, 193.0, 216.0, 273.0, 271.0, 267.0, 201.0, 197.0, 204.0], "value": 221.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 82.0, 85.0, 178.0, 82.0, 51.0, 71.0, 78.0, 157.0, 83.0], "value": 93.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 78.0, 132.0, 70.0, 76.0, 102.0, 148.0, 61.0, 85.0, 109.0], "value": 93.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 130.0, 110.0, 85.0, 109.0, 129.0, 82.0, 132.0, 132.0, 85.0], "value": 110.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 94.0, 94.0, 133.0, 95.0, 95.0, 95.0, 98.0, 94.0, 95.0], "value": 101.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 93.0, 89.0, 92.0, 93.0, 90.0, 90.0, 93.0, 91.0, 92.0], "value": 92.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 56.0, 57.0, 57.0, 58.0, 58.0, 58.0, 57.0, 55.0], "value": 56.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 99.0, 98.0, 109.0, 83.0, 76.0, 75.0, 70.0, 79.0, 101.0], "value": 85.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 100.0, 104.0, 103.0, 85.0, 89.0, 86.0, 87.0, 85.0, 83.0], "value": 91.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 147.0, 154.0, 129.0, 146.0, 154.0, 149.0, 134.0, 167.0, 141.0], "value": 145.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 172.0, 170.0, 173.0, 165.0, 164.0, 164.0, 167.0, 165.0, 165.0], "value": 167.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 64.0, 67.0, 69.0, 80.0, 88.0, 81.0, 73.0], "value": 72.4, "unit": "score"}]}]} 12:34:28 INFO - Return code: 0 12:34:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:34:28 ERROR - # TBPL SUCCESS # 12:34:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:34:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:34:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:34:28 INFO - 2016-07-15 12:34:28.370 screenresolution[1900:11125] starting screenresolution argv=screenresolution get 12:34:28 INFO - 2016-07-15 12:34:28.373 screenresolution[1900:11125] Display 0: 1600x1200x32@60 12:34:28 INFO - 2016-07-15 12:34:28.379 screenresolution[1901:11128] starting screenresolution argv=screenresolution list 12:34:28 INFO - Available Modes on Display 0 12:34:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:34:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:34:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:34:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:34:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:34:28 INFO - Intel Iris: 12:34:28 INFO - Chipset Model: Intel Iris 12:34:28 INFO - Type: GPU 12:34:28 INFO - Bus: Built-In 12:34:28 INFO - VRAM (Dynamic, Max): 1536 MB 12:34:28 INFO - Vendor: Intel (0x8086) 12:34:28 INFO - Device ID: 0x0a2e 12:34:28 INFO - Revision ID: 0x0009 12:34:28 INFO - Displays: 12:34:28 INFO - Display: 12:34:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:34:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:34:28 INFO - Display Serial Number: 1600x1200 60 12:34:28 INFO - Main Display: Yes 12:34:28 INFO - Mirror: Off 12:34:28 INFO - Online: Yes 12:34:28 INFO - Rotation: Supported 12:34:28 INFO - Return code: 0 12:34:28 INFO - Running post-action listener: _resource_record_post_action 12:34:28 INFO - ##### 12:34:28 INFO - ##### Finished run-tests step (success) 12:34:28 INFO - ##### 12:34:28 INFO - Running post-run listener: _resource_record_post_run 12:34:28 INFO - Total resource usage - Wall time: 1208s; CPU: 54.0%; Read bytes: 32402944; Write bytes: 712804352; Read time: 305; Write time: 2540 12:34:28 INFO - TinderboxPrint: CPU usage
54.3% 12:34:28 INFO - TinderboxPrint: I/O read bytes / time
32,402,944 / 305 12:34:28 INFO - TinderboxPrint: I/O write bytes / time
712,804,352 / 2,540 12:34:28 INFO - TinderboxPrint: CPU idle
2,206.8 (45.7%) 12:34:28 INFO - TinderboxPrint: CPU system
976.5 (20.2%) 12:34:28 INFO - TinderboxPrint: CPU user
1,650.4 (34.1%) 12:34:28 INFO - TinderboxPrint: Swap in / out
436,097,024 / 0 12:34:28 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 401113600; Write bytes: 397654016; Read time: 29390; Write time: 949 12:34:28 INFO - run-tests - Wall time: 1174s; CPU: 54.0%; Read bytes: 30326272; Write bytes: 299364352; Read time: 281; Write time: 1529 12:34:29 INFO - Running post-run listener: _upload_blobber_files 12:34:29 INFO - Blob upload gear active. 12:34:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:34:29 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 12:34:29 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:34:29 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:34:29 INFO - (blobuploader) - INFO - Open directory for files ... 12:34:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:34:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:34:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:34:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:34:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:34:30 INFO - (blobuploader) - INFO - Done attempting. 12:34:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:34:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:34:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:34:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:34:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:34:31 INFO - (blobuploader) - INFO - Done attempting. 12:34:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:34:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:34:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:34:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:34:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:34:34 INFO - (blobuploader) - INFO - Done attempting. 12:34:34 INFO - (blobuploader) - INFO - Iteration through files over. 12:34:34 INFO - Return code: 0 12:34:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:34:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:34:34 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68485189c830a0d4a3c54a66b8d9727066d474c2a987026abf2341ec7480e5161d7f6949f28769f7b64467a442ac42c5bd5685f98f472cb1a70a1f76227c0bd8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2e2123aa001b0fe6e44d4feb2128ff56c71c2e39b3fda903d473e56980e2fd92246fafdb87cd16407f8aac5cc5cfdb9c5afc19f342ac6d7362300033220409b5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2454dc676ab677696876cbbcb7cffe9023f499bc45748a666181512d5d166dd26af285f53987e19ca3d90981722669c828c1089aab964164152de095fdcb341b"} 12:34:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:34:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:34:34 INFO - Contents: 12:34:34 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68485189c830a0d4a3c54a66b8d9727066d474c2a987026abf2341ec7480e5161d7f6949f28769f7b64467a442ac42c5bd5685f98f472cb1a70a1f76227c0bd8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2e2123aa001b0fe6e44d4feb2128ff56c71c2e39b3fda903d473e56980e2fd92246fafdb87cd16407f8aac5cc5cfdb9c5afc19f342ac6d7362300033220409b5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2454dc676ab677696876cbbcb7cffe9023f499bc45748a666181512d5d166dd26af285f53987e19ca3d90981722669c828c1089aab964164152de095fdcb341b"} 12:34:34 INFO - Running post-run listener: copy_logs_to_upload_dir 12:34:34 INFO - Copying logs to upload dir... 12:34:34 INFO - mkdir: /builds/slave/test/build/upload/logs 12:34:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.631969 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-07-15 12:34:34.209162) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-15 12:34:34.213353) ========= 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.8mJZMheXSL/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.Arn37w5ps1/Listeners TMPDIR=/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/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/Firefox/sha512/68485189c830a0d4a3c54a66b8d9727066d474c2a987026abf2341ec7480e5161d7f6949f28769f7b64467a442ac42c5bd5685f98f472cb1a70a1f76227c0bd8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2e2123aa001b0fe6e44d4feb2128ff56c71c2e39b3fda903d473e56980e2fd92246fafdb87cd16407f8aac5cc5cfdb9c5afc19f342ac6d7362300033220409b5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2454dc676ab677696876cbbcb7cffe9023f499bc45748a666181512d5d166dd26af285f53987e19ca3d90981722669c828c1089aab964164152de095fdcb341b"} build_url:https://queue.taskcluster.net/v1/task/RLV4jmnVQ6OhdEMMsDO4nw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010897 build_url: 'https://queue.taskcluster.net/v1/task/RLV4jmnVQ6OhdEMMsDO4nw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68485189c830a0d4a3c54a66b8d9727066d474c2a987026abf2341ec7480e5161d7f6949f28769f7b64467a442ac42c5bd5685f98f472cb1a70a1f76227c0bd8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2e2123aa001b0fe6e44d4feb2128ff56c71c2e39b3fda903d473e56980e2fd92246fafdb87cd16407f8aac5cc5cfdb9c5afc19f342ac6d7362300033220409b5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2454dc676ab677696876cbbcb7cffe9023f499bc45748a666181512d5d166dd26af285f53987e19ca3d90981722669c828c1089aab964164152de095fdcb341b"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-15 12:34:34.250741) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 12:34:34.251538) ========= 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.8mJZMheXSL/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.Arn37w5ps1/Listeners TMPDIR=/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/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.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 12:34:34.305828) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-15 12:34:34.306598) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-15 12:34:34.307290) ========= ========= Total master_lag: 0.62 =========