builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0112 starttime: 1463605155.73 results: success (0) buildid: 20160518120510 builduid: c4e7078188e44464b812096bf4fff1fa revision: 6d8d672786ef1098c9821400884992785f55beb3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 13:59:15.727865) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 13:59:15.728288) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 13:59:15.728566) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 13:59:15.798084) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 13:59:15.798455) ========= 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.kg4zanXkwl/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.AyVD57Unf6/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-18 13:59:15-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 143M=0s 2016-05-18 13:59:15 (143 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.164863 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 13:59:15.982225) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 13:59:15.982683) ========= 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.kg4zanXkwl/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.AyVD57Unf6/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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.020325 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 13:59:16.031600) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 13:59:16.031954) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 6d8d672786ef1098c9821400884992785f55beb3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 6d8d672786ef1098c9821400884992785f55beb3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kg4zanXkwl/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.AyVD57Unf6/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-18 13:59:16,109 truncating revision to first 12 chars 2016-05-18 13:59:16,109 Setting DEBUG logging. 2016-05-18 13:59:16,109 attempt 1/10 2016-05-18 13:59:16,109 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/6d8d672786ef?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-18 13:59:16,377 unpacking tar archive at: mozilla-aurora-6d8d672786ef/testing/mozharness/ program finished with exit code 0 elapsedTime=0.669552 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 13:59:16.750311) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 13:59:16.750606) ========= script_repo_revision: 6d8d672786ef1098c9821400884992785f55beb3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 13:59:16.751004) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 13:59:16.751264) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 13:59:16.774090) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2016-05-18 13:59:16.774334) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kg4zanXkwl/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.AyVD57Unf6/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 13:59:16 INFO - MultiFileLogger online at 20160518 13:59:16 in /builds/slave/test 13:59:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 13:59:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:59:16 INFO - {'append_to_log': False, 13:59:16 INFO - 'base_work_dir': '/builds/slave/test', 13:59:16 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 13:59:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:59:16 INFO - 'branch': 'Mozilla-Aurora', 13:59:16 INFO - 'buildbot_json_path': 'buildprops.json', 13:59:16 INFO - 'config_files': ('talos/mac_config.py',), 13:59:16 INFO - 'default_actions': ('clobber', 13:59:16 INFO - 'read-buildbot-config', 13:59:16 INFO - 'download-and-extract', 13:59:16 INFO - 'populate-webroot', 13:59:16 INFO - 'create-virtualenv', 13:59:16 INFO - 'install', 13:59:16 INFO - 'run-tests'), 13:59:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:59:16 INFO - 'download_minidump_stackwalk': True, 13:59:16 INFO - 'download_symbols': 'ondemand', 13:59:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:59:16 INFO - 'tooltool.py': '/tools/tooltool.py', 13:59:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:59:16 INFO - '/tools/misc-python/virtualenv.py')}, 13:59:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:59:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:59:16 INFO - 'installer_path': 'installer.exe', 13:59:16 INFO - 'log_level': 'info', 13:59:16 INFO - 'log_name': 'talos', 13:59:16 INFO - 'log_to_console': True, 13:59:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:59:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:59:16 INFO - 'opt_config_files': (), 13:59:16 INFO - 'pip_index': False, 13:59:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:59:16 INFO - 'cmd': ('bash', 13:59:16 INFO - '-c', 13:59:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:59:16 INFO - 'enabled': True, 13:59:16 INFO - 'halt_on_failure': False, 13:59:16 INFO - 'name': 'check_screen_resolution'},), 13:59:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:59:16 INFO - 'cmd': ('bash', 13:59:16 INFO - '-c', 13:59:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:59:16 INFO - 'enabled': True, 13:59:16 INFO - 'halt_on_failure': False, 13:59:16 INFO - 'name': 'check_screen_resolution'},), 13:59:16 INFO - 'run_cmd_checks_enabled': True, 13:59:16 INFO - 'sps_profile': False, 13:59:16 INFO - 'sps_profile_interval': 0, 13:59:16 INFO - 'suite': 'dromaeojs', 13:59:16 INFO - 'system_bits': '32', 13:59:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:59:16 INFO - 'title': 't-yosemite-r7-0112', 13:59:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:59:16 INFO - 'use_talos_json': True, 13:59:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:59:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:59:16 INFO - 'work_dir': 'build'} 13:59:16 INFO - ##### 13:59:16 INFO - ##### Running clobber step. 13:59:16 INFO - ##### 13:59:16 INFO - Running pre-action listener: _resource_record_pre_action 13:59:16 INFO - Running main action method: clobber 13:59:16 INFO - rmtree: /builds/slave/test/build 13:59:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:59:18 INFO - Running post-action listener: _resource_record_post_action 13:59:18 INFO - ##### 13:59:18 INFO - ##### Running read-buildbot-config step. 13:59:18 INFO - ##### 13:59:18 INFO - Running pre-action listener: _resource_record_pre_action 13:59:18 INFO - Running main action method: read_buildbot_config 13:59:18 INFO - Using buildbot properties: 13:59:18 INFO - { 13:59:18 INFO - "project": "", 13:59:18 INFO - "product": "firefox", 13:59:18 INFO - "script_repo_revision": "production", 13:59:18 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 13:59:18 INFO - "repository": "", 13:59:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 13:59:18 INFO - "buildid": "20160518120510", 13:59:18 INFO - "pgo_build": "False", 13:59:18 INFO - "basedir": "/builds/slave/test", 13:59:18 INFO - "buildnumber": 344, 13:59:18 INFO - "slavename": "t-yosemite-r7-0112", 13:59:18 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 13:59:18 INFO - "platform": "yosemite_r7", 13:59:18 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 13:59:18 INFO - "branch": "mozilla-aurora", 13:59:18 INFO - "repo_path": "releases/mozilla-aurora", 13:59:18 INFO - "revision": "6d8d672786ef1098c9821400884992785f55beb3", 13:59:18 INFO - "stage_platform": "macosx64", 13:59:18 INFO - "builduid": "c4e7078188e44464b812096bf4fff1fa", 13:59:18 INFO - "slavebuilddir": "test" 13:59:18 INFO - } 13:59:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/ADA-zJSnREq6u-PlaeBcfg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 13:59:18 INFO - Running post-action listener: _resource_record_post_action 13:59:18 INFO - ##### 13:59:18 INFO - ##### Running download-and-extract step. 13:59:18 INFO - ##### 13:59:18 INFO - Running pre-action listener: _resource_record_pre_action 13:59:18 INFO - Running main action method: download_and_extract 13:59:18 INFO - mkdir: /builds/slave/test/build/tests 13:59:18 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')]} 13:59:18 INFO - https://queue.taskcluster.net/v1/task/ADA-zJSnREq6u-PlaeBcfg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 13:59:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ADA-zJSnREq6u-PlaeBcfg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 13:59:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ADA-zJSnREq6u-PlaeBcfg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 13:59:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ADA-zJSnREq6u-PlaeBcfg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 13:59:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ADA-zJSnREq6u-PlaeBcfg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 13:59:31 INFO - Downloaded 1199 bytes. 13:59:31 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 13:59:31 INFO - Using the following test package requirements: 13:59:31 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 13:59:31 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:59:31 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 13:59:31 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:59:31 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 13:59:31 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:59:31 INFO - u'jsshell-mac.zip'], 13:59:31 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:59:31 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 13:59:31 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 13:59:31 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:59:31 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 13:59:31 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:59:31 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 13:59:31 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:59:31 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 13:59:31 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:59:31 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 13:59:31 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 13:59:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:59:31 INFO - https://queue.taskcluster.net/v1/task/ADA-zJSnREq6u-PlaeBcfg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:59:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ADA-zJSnREq6u-PlaeBcfg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:59:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ADA-zJSnREq6u-PlaeBcfg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:59:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ADA-zJSnREq6u-PlaeBcfg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:59:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ADA-zJSnREq6u-PlaeBcfg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 13:59:34 INFO - Downloaded 18102463 bytes. 13:59:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:59:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 13:59:38 INFO - Return code: 0 13:59:38 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 13:59: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')]} 13:59:38 INFO - https://queue.taskcluster.net/v1/task/ADA-zJSnREq6u-PlaeBcfg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:59:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ADA-zJSnREq6u-PlaeBcfg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:59:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ADA-zJSnREq6u-PlaeBcfg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:59:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ADA-zJSnREq6u-PlaeBcfg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:59:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ADA-zJSnREq6u-PlaeBcfg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 13:59:41 INFO - Downloaded 18102463 bytes. 13:59:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:59:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 13:59:45 INFO - Return code: 0 13:59: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')]} 13:59:45 INFO - https://queue.taskcluster.net/v1/task/ADA-zJSnREq6u-PlaeBcfg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 13:59:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ADA-zJSnREq6u-PlaeBcfg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 13:59:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ADA-zJSnREq6u-PlaeBcfg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 13:59:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ADA-zJSnREq6u-PlaeBcfg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 13:59:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ADA-zJSnREq6u-PlaeBcfg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 13:59:46 INFO - Downloaded 10840145 bytes. 13:59:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:59:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 13:59:46 INFO - Return code: 0 13:59:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:59:46 INFO - https://queue.taskcluster.net/v1/task/ADA-zJSnREq6u-PlaeBcfg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 13:59:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ADA-zJSnREq6u-PlaeBcfg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 13:59:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ADA-zJSnREq6u-PlaeBcfg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 13:59:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ADA-zJSnREq6u-PlaeBcfg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 13:59:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ADA-zJSnREq6u-PlaeBcfg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 13:59:53 INFO - Downloaded 114125860 bytes. 13:59:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ADA-zJSnREq6u-PlaeBcfg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 13:59:53 INFO - mkdir: /builds/slave/test/properties 13:59:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:59:53 INFO - Writing to file /builds/slave/test/properties/build_url 13:59:53 INFO - Contents: 13:59:53 INFO - build_url:https://queue.taskcluster.net/v1/task/ADA-zJSnREq6u-PlaeBcfg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 13:59:55 INFO - Running post-action listener: _resource_record_post_action 13:59:55 INFO - Running post-action listener: set_extra_try_arguments 13:59:55 INFO - ##### 13:59:55 INFO - ##### Running populate-webroot step. 13:59:55 INFO - ##### 13:59:55 INFO - Running pre-action listener: _resource_record_pre_action 13:59:55 INFO - Running main action method: populate_webroot 13:59:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:59:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:59:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:59:55 INFO - '--tppagecycles', 13:59:55 INFO - '7'], 13:59:55 INFO - 'tests': ['tsvgm']}}, 13:59:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:59:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:59:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:59:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:59:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:59:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:59:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:59:55 INFO - 'talos_options': ['--disable-e10s'], 13:59:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:59:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:59:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:59:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:59:55 INFO - 'talos_options': ['--disable-e10s'], 13:59:55 INFO - 'tests': ['damp', 'tps']}, 13:59:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:59:55 INFO - 'tests': ['damp', 'tps']}, 13:59:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:59:55 INFO - 'tests': ['dromaeo_dom']}, 13:59:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:59:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:59:55 INFO - 'tests': ['a11yr', 13:59:55 INFO - 'ts_paint', 13:59:55 INFO - 'tpaint', 13:59:55 INFO - 'sessionrestore', 13:59:55 INFO - 'sessionrestore_no_auto_restore', 13:59:55 INFO - 'tabpaint']}, 13:59:55 INFO - 'other-e10s': {'tests': ['a11yr', 13:59:55 INFO - 'ts_paint', 13:59:55 INFO - 'tpaint', 13:59:55 INFO - 'sessionrestore', 13:59:55 INFO - 'sessionrestore_no_auto_restore', 13:59:55 INFO - 'tabpaint']}, 13:59:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:59:55 INFO - 'ts_paint', 13:59:55 INFO - 'tpaint', 13:59:55 INFO - 'sessionrestore', 13:59:55 INFO - 'sessionrestore_no_auto_restore', 13:59:55 INFO - 'tabpaint']}, 13:59:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:59:55 INFO - 'ts_paint', 13:59:55 INFO - 'tpaint', 13:59:55 INFO - 'sessionrestore', 13:59:55 INFO - 'sessionrestore_no_auto_restore', 13:59:55 INFO - 'tabpaint']}, 13:59:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:59:55 INFO - 'tests': ['a11yr', 13:59:55 INFO - 'ts_paint', 13:59:55 INFO - 'tpaint', 13:59:55 INFO - 'sessionrestore', 13:59:55 INFO - 'sessionrestore_no_auto_restore', 13:59:55 INFO - 'tabpaint']}, 13:59:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:59:55 INFO - 'tests': ['a11yr', 13:59:55 INFO - 'ts_paint', 13:59:55 INFO - 'tpaint', 13:59:55 INFO - 'sessionrestore', 13:59:55 INFO - 'sessionrestore_no_auto_restore', 13:59:55 INFO - 'tabpaint']}, 13:59:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:59:55 INFO - 'tests': ['tsvgx', 13:59:55 INFO - 'tsvgr_opacity', 13:59:55 INFO - 'tart', 13:59:55 INFO - 'tscrollx', 13:59:55 INFO - 'cart']}, 13:59:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:59:55 INFO - 'tsvgr_opacity', 13:59:55 INFO - 'tart', 13:59:55 INFO - 'tscrollx', 13:59:55 INFO - 'cart']}, 13:59:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:59:55 INFO - 'talos_options': ['--disable-e10s'], 13:59:55 INFO - 'tests': ['tp5o']}, 13:59:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:59:55 INFO - 'tests': ['tp5o']}, 13:59:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:59:55 INFO - 'talos_options': ['--disable-e10s', 13:59:55 INFO - '--xperf_path', 13:59:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:59:55 INFO - 'tests': ['tp5n']}, 13:59:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:59:55 INFO - 'talos_options': ['--xperf_path', 13:59:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:59:55 INFO - 'tests': ['tp5n']}}, 13:59:55 INFO - 'talos.zip': {'path': '', 13:59:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:59:55 INFO - Running post-action listener: _resource_record_post_action 13:59:55 INFO - ##### 13:59:55 INFO - ##### Running create-virtualenv step. 13:59:55 INFO - ##### 13:59:55 INFO - Running pre-action listener: _resource_record_pre_action 13:59:55 INFO - Running main action method: create_virtualenv 13:59:55 INFO - Creating virtualenv /builds/slave/test/build/venv 13:59:55 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 13:59:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:59:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:59:55 INFO - Using real prefix '/tools/python27' 13:59:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:59:55 INFO - Installing distribute.............................................................................................................................................................................................done. 13:59:58 INFO - Installing pip.................done. 13:59:58 INFO - Return code: 0 13:59:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:59: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')]} 13:59:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59: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')]} 13:59:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c27ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c28b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe483579820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c11ae00>, '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 0x10bf83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c115b20>, '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.kg4zanXkwl/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.AyVD57Unf6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 13:59: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', 'pip>=1.5'] in /builds/slave/test/build 13:59: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 pip>=1.5 13:59:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kg4zanXkwl/Render', 13:59:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:58 INFO - 'HOME': '/Users/cltbld', 13:59:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:58 INFO - 'LOGNAME': 'cltbld', 13:59:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:58 INFO - 'MOZ_NO_REMOTE': '1', 13:59:58 INFO - 'NO_EM_RESTART': '1', 13:59:58 INFO - 'PAGER': '/bin/cat', 13:59:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:58 INFO - 'PWD': '/builds/slave/test', 13:59:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:58 INFO - 'SHELL': '/bin/bash', 13:59:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AyVD57Unf6/Listeners', 13:59:58 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 13:59:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:58 INFO - 'USER': 'cltbld', 13:59:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:58 INFO - 'XPC_FLAGS': '0x0', 13:59:58 INFO - 'XPC_SERVICE_NAME': '0', 13:59:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:59 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 13:59:59 INFO - Cleaning up... 13:59:59 INFO - Return code: 0 13:59:59 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 13:59:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:59:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:59:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c27ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c28b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe483579820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c11ae00>, '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 0x10bf83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c115b20>, '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.kg4zanXkwl/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.AyVD57Unf6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 13:59:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 13:59:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 13:59:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kg4zanXkwl/Render', 13:59:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:59 INFO - 'HOME': '/Users/cltbld', 13:59:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:59 INFO - 'LOGNAME': 'cltbld', 13:59:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:59 INFO - 'MOZ_NO_REMOTE': '1', 13:59:59 INFO - 'NO_EM_RESTART': '1', 13:59:59 INFO - 'PAGER': '/bin/cat', 13:59:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:59 INFO - 'PWD': '/builds/slave/test', 13:59:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:59 INFO - 'SHELL': '/bin/bash', 13:59:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AyVD57Unf6/Listeners', 13:59:59 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 13:59:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:59 INFO - 'USER': 'cltbld', 13:59:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:59 INFO - 'XPC_FLAGS': '0x0', 13:59:59 INFO - 'XPC_SERVICE_NAME': '0', 13:59:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:59 INFO - Downloading/unpacking psutil>=0.7.1 13:59:59 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 13:59:59 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 13:59:59 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 13:59:59 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 13:59:59 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 13:59:59 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 14:00:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:00:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:00:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:00:02 INFO - Installing collected packages: psutil 14:00:02 INFO - Running setup.py install for psutil 14:00:02 INFO - building 'psutil._psutil_osx' extension 14:00:02 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 14:00:02 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 14:00:02 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 14:00:02 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 14:00:02 INFO - building 'psutil._psutil_posix' extension 14:00:02 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 14:00:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:00:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:00:03 INFO - ^ 14:00:03 INFO - 1 warning generated. 14:00:03 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 14:00:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:00:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:00:03 INFO - Successfully installed psutil 14:00:03 INFO - Cleaning up... 14:00:03 INFO - Return code: 0 14:00:03 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 14:00:03 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')]} 14:00:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:03 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')]} 14:00:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c27ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c28b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe483579820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c11ae00>, '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 0x10bf83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c115b20>, '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.kg4zanXkwl/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.AyVD57Unf6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 14:00:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 14:00:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 14:00:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kg4zanXkwl/Render', 14:00:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:00:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:00:03 INFO - 'HOME': '/Users/cltbld', 14:00:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:00:03 INFO - 'LOGNAME': 'cltbld', 14:00:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:03 INFO - 'MOZ_NO_REMOTE': '1', 14:00:03 INFO - 'NO_EM_RESTART': '1', 14:00:03 INFO - 'PAGER': '/bin/cat', 14:00:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:00:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:03 INFO - 'PWD': '/builds/slave/test', 14:00:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:00:03 INFO - 'SHELL': '/bin/bash', 14:00:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AyVD57Unf6/Listeners', 14:00:03 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 14:00:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:00:03 INFO - 'USER': 'cltbld', 14:00:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:00:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:00:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:03 INFO - 'XPC_FLAGS': '0x0', 14:00:03 INFO - 'XPC_SERVICE_NAME': '0', 14:00:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:00:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:03 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 14:00: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 14:00: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 14:00: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 14:00: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 14:00: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 14:00: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 14:00:05 INFO - Downloading mozsystemmonitor-0.0.tar.gz 14:00:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:00:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 14:00:06 INFO - Installing collected packages: mozsystemmonitor 14:00:06 INFO - Running setup.py install for mozsystemmonitor 14:00:06 INFO - Successfully installed mozsystemmonitor 14:00:06 INFO - Cleaning up... 14:00:06 INFO - Return code: 0 14:00:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:00:06 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')]} 14:00:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:06 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')]} 14:00:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:06 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 0x10c27ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c28b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe483579820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c11ae00>, '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 0x10bf83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c115b20>, '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.kg4zanXkwl/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.AyVD57Unf6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 14:00:06 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 14:00:06 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 14:00:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kg4zanXkwl/Render', 14:00:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:00:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:00:06 INFO - 'HOME': '/Users/cltbld', 14:00:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:00:06 INFO - 'LOGNAME': 'cltbld', 14:00:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:06 INFO - 'MOZ_NO_REMOTE': '1', 14:00:06 INFO - 'NO_EM_RESTART': '1', 14:00:06 INFO - 'PAGER': '/bin/cat', 14:00:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:00:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:06 INFO - 'PWD': '/builds/slave/test', 14:00:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:00:06 INFO - 'SHELL': '/bin/bash', 14:00:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AyVD57Unf6/Listeners', 14:00:06 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 14:00:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:00:06 INFO - 'USER': 'cltbld', 14:00:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:00:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:00:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:06 INFO - 'XPC_FLAGS': '0x0', 14:00:06 INFO - 'XPC_SERVICE_NAME': '0', 14:00:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:00:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:06 INFO - Downloading/unpacking blobuploader==1.2.4 14:00:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:00:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:00:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:00:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:00:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:00:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:00:09 INFO - Downloading blobuploader-1.2.4.tar.gz 14:00:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:00:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:00:09 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 14:00:09 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 14:00:09 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 14:00:09 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 14:00:09 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 14:00:09 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 14:00:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:00:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:00:09 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 14:00:09 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 14:00:09 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 14:00:09 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 14:00:09 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 14:00:09 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 14:00:10 INFO - Downloading docopt-0.6.1.tar.gz 14:00:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:00:10 INFO - Installing collected packages: blobuploader, requests, docopt 14:00:10 INFO - Running setup.py install for blobuploader 14:00:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:00:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:00:10 INFO - Running setup.py install for requests 14:00:10 INFO - Running setup.py install for docopt 14:00:11 INFO - Successfully installed blobuploader requests docopt 14:00:11 INFO - Cleaning up... 14:00:11 INFO - Return code: 0 14:00:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:00:11 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')]} 14:00:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:11 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')]} 14:00:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:11 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 0x10c27ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c28b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe483579820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c11ae00>, '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 0x10bf83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c115b20>, '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.kg4zanXkwl/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.AyVD57Unf6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 14:00:11 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 14:00:11 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 14:00:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kg4zanXkwl/Render', 14:00:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:00:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:00:11 INFO - 'HOME': '/Users/cltbld', 14:00:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:00:11 INFO - 'LOGNAME': 'cltbld', 14:00:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:11 INFO - 'MOZ_NO_REMOTE': '1', 14:00:11 INFO - 'NO_EM_RESTART': '1', 14:00:11 INFO - 'PAGER': '/bin/cat', 14:00:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:00:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:11 INFO - 'PWD': '/builds/slave/test', 14:00:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:00:11 INFO - 'SHELL': '/bin/bash', 14:00:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AyVD57Unf6/Listeners', 14:00:11 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 14:00:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:00:11 INFO - 'USER': 'cltbld', 14:00:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:00:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:00:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:11 INFO - 'XPC_FLAGS': '0x0', 14:00:11 INFO - 'XPC_SERVICE_NAME': '0', 14:00:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:00:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:00:11 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-1X1iFF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:00:11 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-aGSBrY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:00:11 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-hTCt_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:00:11 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-AURgXC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:00:11 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-PXMSCc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:00:11 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-Gw0vXR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:00:12 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-6rK5Ea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:00:12 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-kYDfVi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:00:12 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-vzEUpU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:00:12 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-1WlQG_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:00:12 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-bTCBbl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:00:12 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-Vh2Flr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:00:12 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-d_eyFM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:00:12 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-8loGhL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:00:12 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-_qOzpF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:00:12 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-QN12yu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:00:13 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-FnhavU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:00:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:00:13 INFO - Running setup.py install for manifestparser 14:00:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:00:13 INFO - Running setup.py install for mozcrash 14:00:13 INFO - Running setup.py install for mozdebug 14:00:13 INFO - Running setup.py install for mozdevice 14:00:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:00:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:00:13 INFO - Running setup.py install for mozfile 14:00:13 INFO - Running setup.py install for mozhttpd 14:00:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:00:14 INFO - Running setup.py install for mozinfo 14:00:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:00:14 INFO - Running setup.py install for mozInstall 14:00:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:00:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:00:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:00:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:00:14 INFO - Running setup.py install for mozleak 14:00:14 INFO - Running setup.py install for mozlog 14:00:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:00:14 INFO - Running setup.py install for moznetwork 14:00:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:00:14 INFO - Running setup.py install for mozprocess 14:00:14 INFO - Running setup.py install for mozprofile 14:00:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:00:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:00:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:00:15 INFO - Running setup.py install for mozrunner 14:00:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:00:15 INFO - Running setup.py install for mozscreenshot 14:00:15 INFO - Running setup.py install for moztest 14:00:15 INFO - Running setup.py install for mozversion 14:00:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:00:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:00:15 INFO - Cleaning up... 14:00:15 INFO - Return code: 0 14:00:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:00:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:00:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:15 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 0x10c27ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c28b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe483579820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c11ae00>, '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 0x10bf83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c115b20>, '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.kg4zanXkwl/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.AyVD57Unf6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 14:00:15 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 14:00:15 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 14:00:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kg4zanXkwl/Render', 14:00:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:00:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:00:15 INFO - 'HOME': '/Users/cltbld', 14:00:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:00:15 INFO - 'LOGNAME': 'cltbld', 14:00:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:15 INFO - 'MOZ_NO_REMOTE': '1', 14:00:15 INFO - 'NO_EM_RESTART': '1', 14:00:15 INFO - 'PAGER': '/bin/cat', 14:00:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:00:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:15 INFO - 'PWD': '/builds/slave/test', 14:00:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:00:15 INFO - 'SHELL': '/bin/bash', 14:00:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AyVD57Unf6/Listeners', 14:00:15 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 14:00:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:00:15 INFO - 'USER': 'cltbld', 14:00:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:00:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:00:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:15 INFO - 'XPC_FLAGS': '0x0', 14:00:15 INFO - 'XPC_SERVICE_NAME': '0', 14:00:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:00:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:00:16 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-ubVHEW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:00:16 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)) 14:00:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:00:16 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-dyPWCm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:00:16 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)) 14:00:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:00:16 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-lHyPck-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:00:16 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)) 14:00:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:00:16 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-1u01x_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:00:16 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)) 14:00:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:00:16 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-043Rp1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:00:16 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)) 14:00:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:00:16 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-vVQ0hR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:00:16 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)) 14:00:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:00:16 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-_hC6jV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:00:16 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)) 14:00:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:00:16 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-Fya3kX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:00:16 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)) 14:00:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:00:16 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-nHqMdF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:00:16 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)) 14:00:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:00:16 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-3bHozO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:00:17 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-jiSDS8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:00:17 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)) 14:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:00:17 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-J4ph7U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 14:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:00:17 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-5mFmrq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:00:17 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)) 14:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:00:17 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-f79Wyu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:00:17 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-dktfU5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:00:17 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)) 14:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:00:17 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-jRE4rd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:00:17 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)) 14:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:00:17 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-kbflYD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:00:17 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)) 14:00:17 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)) 14:00:17 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)) 14:00:17 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)) 14:00:17 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)) 14:00:17 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)) 14:00:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:00:17 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 14:00:17 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 14:00:17 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 14:00:17 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 14:00:17 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 14:00:17 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 14:00:20 INFO - Downloading blessings-1.6.tar.gz 14:00:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:00:20 INFO - Installing collected packages: blessings 14:00:20 INFO - Running setup.py install for blessings 14:00:21 INFO - Successfully installed blessings 14:00:21 INFO - Cleaning up... 14:00:21 INFO - Return code: 0 14:00:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:00:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:00:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:00:21 INFO - Reading from file tmpfile_stdout 14:00:21 INFO - Current package versions: 14:00:21 INFO - blessings == 1.6 14:00:21 INFO - blobuploader == 1.2.4 14:00:21 INFO - docopt == 0.6.1 14:00:21 INFO - manifestparser == 1.1 14:00:21 INFO - mozInstall == 1.12 14:00:21 INFO - mozcrash == 0.17 14:00:21 INFO - mozdebug == 0.1 14:00:21 INFO - mozdevice == 0.48 14:00:21 INFO - mozfile == 1.2 14:00:21 INFO - mozhttpd == 0.7 14:00:21 INFO - mozinfo == 0.9 14:00:21 INFO - mozleak == 0.1 14:00:21 INFO - mozlog == 3.1 14:00:21 INFO - moznetwork == 0.27 14:00:21 INFO - mozprocess == 0.22 14:00:21 INFO - mozprofile == 0.28 14:00:21 INFO - mozrunner == 6.11 14:00:21 INFO - mozscreenshot == 0.1 14:00:21 INFO - mozsystemmonitor == 0.0 14:00:21 INFO - moztest == 0.7 14:00:21 INFO - mozversion == 1.4 14:00:21 INFO - psutil == 3.1.1 14:00:21 INFO - requests == 1.2.3 14:00:21 INFO - wsgiref == 0.1.2 14:00:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:00:21 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')]} 14:00:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:21 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')]} 14:00:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:21 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 0x10c27ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c28b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe483579820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c11ae00>, '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 0x10bf83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c115b20>, '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.kg4zanXkwl/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.AyVD57Unf6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 14:00:21 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 14:00:21 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 14:00:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kg4zanXkwl/Render', 14:00:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:00:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:00:21 INFO - 'HOME': '/Users/cltbld', 14:00:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:00:21 INFO - 'LOGNAME': 'cltbld', 14:00:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:21 INFO - 'MOZ_NO_REMOTE': '1', 14:00:21 INFO - 'NO_EM_RESTART': '1', 14:00:21 INFO - 'PAGER': '/bin/cat', 14:00:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:00:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:21 INFO - 'PWD': '/builds/slave/test', 14:00:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:00:21 INFO - 'SHELL': '/bin/bash', 14:00:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AyVD57Unf6/Listeners', 14:00:21 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 14:00:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:00:21 INFO - 'USER': 'cltbld', 14:00:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:00:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:00:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:21 INFO - 'XPC_FLAGS': '0x0', 14:00:21 INFO - 'XPC_SERVICE_NAME': '0', 14:00:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:00:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:22 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)) 14:00:22 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)) 14:00:22 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)) 14:00:22 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)) 14:00:22 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)) 14:00:22 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)) 14:00:22 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)) 14:00:22 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)) 14:00:22 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)) 14:00:22 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)) 14:00:22 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)) 14:00:22 INFO - Cleaning up... 14:00:22 INFO - Return code: 0 14:00:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:00:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:00:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c27ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c28b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe483579820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c11ae00>, '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 0x10bf83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c115b20>, '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.kg4zanXkwl/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.AyVD57Unf6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 14:00:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:00:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:00:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kg4zanXkwl/Render', 14:00:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:00:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:00:22 INFO - 'HOME': '/Users/cltbld', 14:00:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:00:22 INFO - 'LOGNAME': 'cltbld', 14:00:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:22 INFO - 'MOZ_NO_REMOTE': '1', 14:00:22 INFO - 'NO_EM_RESTART': '1', 14:00:22 INFO - 'PAGER': '/bin/cat', 14:00:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:00:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:22 INFO - 'PWD': '/builds/slave/test', 14:00:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:00:22 INFO - 'SHELL': '/bin/bash', 14:00:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AyVD57Unf6/Listeners', 14:00:22 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 14:00:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:00:22 INFO - 'USER': 'cltbld', 14:00:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:00:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:00:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:22 INFO - 'XPC_FLAGS': '0x0', 14:00:22 INFO - 'XPC_SERVICE_NAME': '0', 14:00:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:00:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:22 INFO - Downloading/unpacking jsonschema 14:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:00:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:00:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:00:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:00:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:00:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:00:24 INFO - Downloading/unpacking functools32 (from jsonschema) 14:00:24 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 14:00:24 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 14:00:24 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 14:00:24 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 14:00:24 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 14:00:24 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 14:00:25 INFO - Downloading functools32-3.2.3-2.tar.gz 14:00:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:00:25 INFO - warning: no files found matching '*.txt' 14:00:25 INFO - no previously-included directories found matching 'build' 14:00:25 INFO - no previously-included directories found matching 'dist' 14:00:25 INFO - no previously-included directories found matching '.git*' 14:00:25 INFO - Installing collected packages: jsonschema, functools32 14:00:25 INFO - Running setup.py install for functools32 14:00:25 INFO - warning: no files found matching '*.txt' 14:00:25 INFO - no previously-included directories found matching 'build' 14:00:25 INFO - no previously-included directories found matching 'dist' 14:00:25 INFO - no previously-included directories found matching '.git*' 14:00:25 INFO - Successfully installed jsonschema functools32 14:00:25 INFO - Cleaning up... 14:00:25 INFO - Return code: 0 14:00:25 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 14:00:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:00:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c27ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c28b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe483579820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c11ae00>, '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 0x10bf83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c115b20>, '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.kg4zanXkwl/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.AyVD57Unf6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 14:00:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 14:00:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 14:00:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kg4zanXkwl/Render', 14:00:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:00:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:00:25 INFO - 'HOME': '/Users/cltbld', 14:00:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:00:25 INFO - 'LOGNAME': 'cltbld', 14:00:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:25 INFO - 'MOZ_NO_REMOTE': '1', 14:00:25 INFO - 'NO_EM_RESTART': '1', 14:00:25 INFO - 'PAGER': '/bin/cat', 14:00:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:00:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:25 INFO - 'PWD': '/builds/slave/test', 14:00:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:00:25 INFO - 'SHELL': '/bin/bash', 14:00:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AyVD57Unf6/Listeners', 14:00:25 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 14:00:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:00:25 INFO - 'USER': 'cltbld', 14:00:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:00:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:00:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:25 INFO - 'XPC_FLAGS': '0x0', 14:00:25 INFO - 'XPC_SERVICE_NAME': '0', 14:00:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:00:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:25 INFO - Downloading/unpacking flake8 14:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:00:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:00:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:00:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:00:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:00:28 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:00:28 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:00:28 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 14:00:28 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 14:00:28 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 14:00:28 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 14:00:28 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 14:00:28 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 14:00:28 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:00:28 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:00:28 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 14:00:28 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 14:00:28 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 14:00:28 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 14:00:28 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 14:00:28 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 14:00:29 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:00:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:00:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:00:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:00:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:00:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 14:00:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:00:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:00:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:00:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:00:29 INFO - no previously-included directories found matching 'docs/_build' 14:00:29 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:00:29 INFO - Running setup.py install for pep8 14:00:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:00:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:00:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:00:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:00:29 INFO - no previously-included directories found matching 'docs/_build' 14:00:29 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 14:00:29 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:00:29 INFO - Cleaning up... 14:00:29 INFO - Return code: 0 14:00:29 INFO - Running post-action listener: _resource_record_post_action 14:00:29 INFO - Running post-action listener: _start_resource_monitoring 14:00:30 INFO - Starting resource monitoring. 14:00:30 INFO - ##### 14:00:30 INFO - ##### Running install step. 14:00:30 INFO - ##### 14:00:30 INFO - Running pre-action listener: _resource_record_pre_action 14:00:30 INFO - Running main action method: install 14:00:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:00:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:00:30 INFO - Reading from file tmpfile_stdout 14:00:30 INFO - Detecting whether we're running mozinstall >=1.0... 14:00:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:00:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:00:30 INFO - Reading from file tmpfile_stdout 14:00:30 INFO - Output received: 14:00:30 INFO - Usage: mozinstall [options] installer 14:00:30 INFO - Options: 14:00:30 INFO - -h, --help show this help message and exit 14:00:30 INFO - -d DEST, --destination=DEST 14:00:30 INFO - Directory to install application into. [default: 14:00:30 INFO - "/builds/slave/test"] 14:00:30 INFO - --app=APP Application being installed. [default: firefox] 14:00:30 INFO - mkdir: /builds/slave/test/build/application 14:00:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:00:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 14:01:02 INFO - Reading from file tmpfile_stdout 14:01:02 INFO - Output received: 14:01:02 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 14:01:02 INFO - Running post-action listener: _resource_record_post_action 14:01:02 INFO - ##### 14:01:02 INFO - ##### Running run-tests step. 14:01:02 INFO - ##### 14:01:02 INFO - Running pre-action listener: _resource_record_pre_action 14:01:02 INFO - Running main action method: run_tests 14:01:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:01:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:01:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:01:02 INFO - 2016-05-18 14:01:02.226 screenresolution[1919:12335] starting screenresolution argv=screenresolution get 14:01:02 INFO - 2016-05-18 14:01:02.228 screenresolution[1919:12335] Display 0: 1600x1200x32@60 14:01:02 INFO - 2016-05-18 14:01:02.234 screenresolution[1920:12338] starting screenresolution argv=screenresolution list 14:01:02 INFO - Available Modes on Display 0 14:01:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:01:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:01:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:01:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:01:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:01:02 INFO - Intel Iris: 14:01:02 INFO - Chipset Model: Intel Iris 14:01:02 INFO - Type: GPU 14:01:02 INFO - Bus: Built-In 14:01:02 INFO - VRAM (Dynamic, Max): 1536 MB 14:01:02 INFO - Vendor: Intel (0x8086) 14:01:02 INFO - Device ID: 0x0a2e 14:01:02 INFO - Revision ID: 0x0009 14:01:02 INFO - Displays: 14:01:02 INFO - Display: 14:01:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:01:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:01:02 INFO - Display Serial Number: 1600x1200 60 14:01:02 INFO - Main Display: Yes 14:01:02 INFO - Mirror: Off 14:01:02 INFO - Online: Yes 14:01:02 INFO - Rotation: Supported 14:01:02 INFO - Return code: 0 14:01:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:01:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:01:02 INFO - Python 2.7.3 14:01:02 INFO - Return code: 0 14:01:02 INFO - grabbing minidump binary from tooltool 14:01:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:01:02 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c11ae00>, '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 0x10bf83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c115b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:01:02 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 14:01:02 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 14:01:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:01:02 INFO - Return code: 0 14:01:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:01:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:01:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:01:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:01:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:01:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 14:01:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 14:01:03 INFO - Return code: 0 14:01:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ADA-zJSnREq6u-PlaeBcfg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0112', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 14:01:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ADA-zJSnREq6u-PlaeBcfg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0112 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 14:01:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kg4zanXkwl/Render', 14:01:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:01:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:01:03 INFO - 'HOME': '/Users/cltbld', 14:01:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:01:03 INFO - 'LOGNAME': 'cltbld', 14:01:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:01:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:01:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:03 INFO - 'MOZ_NO_REMOTE': '1', 14:01:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:01:03 INFO - 'NO_EM_RESTART': '1', 14:01:03 INFO - 'PAGER': '/bin/cat', 14:01:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:01:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:03 INFO - 'PWD': '/builds/slave/test', 14:01:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:01:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:01:03 INFO - 'SHELL': '/bin/bash', 14:01:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AyVD57Unf6/Listeners', 14:01:03 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 14:01:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:01:03 INFO - 'USER': 'cltbld', 14:01:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:01:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:01:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:03 INFO - 'XPC_FLAGS': '0x0', 14:01:03 INFO - 'XPC_SERVICE_NAME': '0', 14:01:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:01:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ADA-zJSnREq6u-PlaeBcfg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0112', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 14:01:03 INFO - mozversion application_buildid: 20160518120510 14:01:03 INFO - mozversion application_changeset: 6d8d672786ef1098c9821400884992785f55beb3 14:01:03 INFO - mozversion application_display_name: FirefoxDeveloperEdition 14:01:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:01:03 INFO - mozversion application_name: Firefox 14:01:03 INFO - mozversion application_remotingname: firefox-dev 14:01:03 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:01:03 INFO - mozversion application_vendor: Mozilla 14:01:03 INFO - mozversion application_version: 48.0a2 14:01:03 INFO - mozversion platform_buildid: 20160518120510 14:01:03 INFO - mozversion platform_changeset: 6d8d672786ef1098c9821400884992785f55beb3 14:01:03 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:01:03 INFO - mozversion platform_version: 48.0a2 14:01:03 INFO - using testdate: 1463605263 14:01:03 INFO - actual date: 1463605263 14:01:03 INFO - starting webserver on 'localhost:50919' 14:01:03 INFO - SUITE-START | Running 2 tests 14:01:03 INFO - TEST-START | dromaeo_css 14:01:03 INFO - operating with platform_type : mac_ 14:01:03 INFO - Initialising browser for dromaeo_css test... 14:01:03 INFO - TEST-INFO | started process 1938 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmpWbafLf/profile http://localhost:50919/getInfo.html) 14:01:05 INFO - PROCESS | 1938 | __metrics Screen width/height:1600/1200 14:01:05 INFO - PROCESS | 1938 | colorDepth:24 14:01:05 INFO - PROCESS | 1938 | Browser inner width/height: 1024/689 14:01:05 INFO - PROCESS | 1938 | __metrics 14:01:06 INFO - PROCESS | 1938 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:01:06 ERROR - PROCESS | 1938 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:01:06 INFO - TEST-INFO | 1938: exit 0 14:01:06 INFO - Browser initialized. 14:01:06 INFO - Running cycle 1/1 for dromaeo_css test... 14:01:06 INFO - TEST-INFO | started process 1940 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmpWbafLf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:01:18 INFO - PROCESS | 1940 | RSS: Main: 210984960 14:01:18 INFO - PROCESS | 1940 | 14:02:10 INFO - PROCESS | 1940 | 1463605330051 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] 14:04:21 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:50919/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50919/tests/dromaeo/cssquery-ext.html) 14:04:22 INFO - PROCESS | 1940 | RSS: Main: 336953344 14:04:22 INFO - PROCESS | 1940 | 14:07:25 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:50919/tests/dromaeo/cssquery-ext.html (next: http://localhost:50919/tests/dromaeo/cssquery-jquery.html) 14:07:25 INFO - PROCESS | 1940 | RSS: Main: 358002688 14:07:25 INFO - PROCESS | 1940 | 14:10:27 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:50919/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50919/tests/dromaeo/cssquery-mootools.html) 14:10:28 INFO - PROCESS | 1940 | RSS: Main: 358801408 14:10:28 INFO - PROCESS | 1940 | 14:13:30 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:50919/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50919/tests/dromaeo/cssquery-prototype.html) 14:13:31 INFO - PROCESS | 1940 | RSS: Main: 360816640 14:13:31 INFO - PROCESS | 1940 | 14:16:34 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:50919/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50919/tests/dromaeo/cssquery-yui.html) 14:16:35 INFO - PROCESS | 1940 | RSS: Main: 328896512 14:16:35 INFO - PROCESS | 1940 | 14:19:37 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:50919/tests/dromaeo/cssquery-yui.html (next: http://localhost:50919/tests/dromaeo/cssquery-dojo.html) 14:19:38 INFO - PROCESS | 1940 | RSS: Main: 366284800 14:19:38 INFO - PROCESS | 1940 | 14:19:38 INFO - PROCESS | 1940 | __start_tp_report 14:19:38 INFO - PROCESS | 1940 | _x_x_mozilla_page_load 14:19:38 INFO - PROCESS | 1940 | _x_x_mozilla_page_load_details 14:19:38 INFO - PROCESS | 1940 | |i|pagename|runs| 14:19:38 INFO - PROCESS | 1940 | |0;dojo.html;2688.408550118974;3439.415299399102;3452.5166476693266;3471;3515.2442224921638;5697.344805347387;5725.341585717633;5731.914021289679;5746.1093530502785;5771.480566748992;5724.484796368326;5761.654300741959;5768.019436695761;5773.047447171222;5775.2203452534;5113.539781419671;5158.613104017195;5163.32876726026;5283.339582552181;5302.125149350352;5247.763850626712;5250.684958902459;5256.842294731145;5263.131583288753;5264.263003179558;5191.792328306863;5193.1171700810955;5236.764345604457;5252.65857719247;5263.815766448175;5202.92195617066;5228.032813929397;5248.081585722522;5249.711265880375;5287.365516138088;5065.088284108859;5090.261912022773;5099.260607211933;5144.4083930348115;5163.690178589297;5548.805791797267;5549.944500554983;5602.60781745278;5606.887862242773;5607.271054762896;6192.102145188923;6200.751969921198;6206.162168107293;6215.937840621581;6218.440340369343;6118.724657390429;6238.251409830804;6243.781467648656;6264.843378915518;6352.269489008777;5841.9415805842245;5860.208871802337;5875.882482350415;5885.499732522698;5886.676232807194;6309.211348581427;6318.988961766096;6330.272018717907;6337.556371053982;6340.492760579143;6050.183225264621;6144.5084393248435;6220.813375598649;6231.096491008869;6231.688415579293;6310.179676642099;6337.366263373718;6339.683015849281;6347.841303967438;6357.8728425432155;6277.435030847154;6286.4342209201395;6303.873922521524;6306.337834527309;6308.495320374359;6111.083337499411;6165.876682466415;6178.382161783786;6191.071339299141;6321.683915804191;4877.073356062408;4923.01539692063;4932.8520144395725;4947.406311242674;4960.528749768839;4431.512533621299;4433.844815431445;4447.310666846579;4453.087117140978;4470.374147619336;4728.763561821896;5545.694986775686;5650.604457688005;5690.77236910519;5716.599838011382;5947.613405128653;6184.072389141665;6218.098375735583;6267.373262673696;6278.874422511524;5642.774289028393;5646.096624540054;5651.180578816049;5652.802151924745;5653;5331.333583302087;5382.058139825514;5383.327084114486;5393.2179833923865;5424.430434804368;5165.690058596496;6199.969000155151;6222.751089956351;6264.843378915472;6304.590201636971;6274.341194174546;6314.715837787218;6316.526260530387;6319.178506794087;6330.335314792064;6330.746770129143;6334.619922804646;6337;6362.0774987627465;6366.77716279928;6037.275526936795;6093.969530152321;6124.777645458507;6164.291106522807;6247.656378899204;6147.200863887666;6181.196444462191;6186.81439556814;6199.721012554536;6212.409821066992;4931.2849636803185;5957.702114894151;5973.044312910088;5980.20593821843;6019.127226552213;5884.293884733859;5990.820275391745;5992.250968628921;6015.977283861666;6085.026395776831;5942.6731529766275;5956.702164891824;5968.970155149196;5993.340732519507;6024.246969128859;6068.241469816273;6092.969535152296;6121.84695382619;6141.570090093829;6142.201513803177;5389.784408623611;5854.824355269348;5938.257717785277;5985;6095.116208149882 14:19:38 INFO - PROCESS | 1940 | |1;ext.html;521.9217117432385;535.1464414259257;558.4638746803068;582.9125631155327;610.8992016317308;27611.723882761165;30848.074557763273;37123.62876371235;38145.80927095367;38210.8089459553;40181.79909100458;40518.78443646695;40563.18873622533;40633.796831015876;40773.59226407735;9497.430154190735;9653.324267301292;9664.22686185107;9668.178204852597;9686.75783105421;7491.475596708233;8594.226519613234;11466.254693444942;11647.883521164807;11654.592089276877;11338;11409.429528523582;11411.54353825846;11420.828687569694;11510.482028308705;8587.184217499327;11310.264832785884;11399.715007124805;11406.315621062722;11419.54321827126;11335.829962550568;11467.770644587144;11467.942660286646;11475.942620286929;11483.196176267664;37607.05982350436;38114.237715245814;38122.23755524874;38209.42685859687;38211.23577528434;3151.558781770554;3251.1546997780747;3257.641659417462;3279.262166012652;3514.947275790865;27350.45299094027;28060.719392806015;29066.709332906612;29076.854615727;29107.56338654922;2745.645360763304;2764.5853122031654;2793.4971705093076;2807.620971168907;2858.9285267868263;482990;486300;486953;488076.5596172032;488195;29113;36098.09754756152;59981.10028349578;60459.697701512094;60465.697671512244;34583.308333833185;34712.82643586816;34835.477467838005;34863.65136348655;34902.12744681403;15320.540383788504;15505;15508.457203998032;15545;15554.84445155534;12095;12348.073894457952;13016.544420945222;13164.078514503894;13240.73518529624;7934.960325198337;7978.760637180894;8020.639071241808;8039.598020099089;8107.83784324322;3369.0735047862036;3394.7963122212745;3417.1457135716073;3426.4517677171534;3442.397580423466;959.2949182350967;961.403929563675;1068.8770791358938;1086.739182596187;1089.700332408594;29417.705822941924;29427.705722942497;29442.852785735933;29888;30096.946606868656;789.6328207383594;1019.4800651667703;1109.5949978257877;1147.2657499200518;1147.701597584634;12100.75798484043;14149.221792801289;14333.918207585419;14725.263736813116;14779.113253204841;621646;839109;865979;866854;867538;59539.702301488214;59770.402295976484;60699.69650151721;60742.392576073675;60765.69617151886;1337.5920344295084;1373.7183877305129;1470.6102882736297;1498.340730078762;1506.5329747778223;28210.71789282081;28472.857635711687;29317;29386.706132938398;29603.259918502208;53520.929581408935;53787.73106134444;54363;54477;54546;571547;586751;588173.0591347187;588439.0578047083;588489;34849.12877178091;40663.32371777165;41626.375604365356;41909;42544.23462474929;41770.37344439897;42297.788511058476;42970.78514607407;43289;43360.48238311644;35415;35586.46620300646;36812.447813282284;37346.06634834151;37477.4378384319;36074;36331.09172270714;36342;36929.89210323695;36987;37047.073823154635;37243.62756372402;37789;38492.230155397294;38514.0371490715;29398;31347.843260784462;31374;31484;31518.21204469815;24642;24881.37796555028;24982.750172498043;25188.992440302914;25230.495390091728 14:19:38 INFO - PROCESS | 1940 | |2;jquery.html;397.1699148779051;398.5078428141245;399.8980260033691;400.55738409058006;412.05022423314267;16331.673366532677;19271;19593.11830967606;20250.291239806593;20453.97730113492;19729;20127.698084528707;20213.393598192077;21014.264500742476;21017.68473472899;5821.388754180803;5857.941420585804;6118.418750218744;6120.265568131808;6153.292371377304;5923.792667256647;5996.070609055581;6025.457708806207;6026.578139530235;6038.516918646497;5782.595218334718;5976.641401515923;5985.162077309158;5986.191864098357;6107.450329470346;5831.912521312183;6047.606905551125;6057.51539876811;6074.696265186745;6077.544184186182;6070.089486577004;6119.265688117454;6119.87760244797;6132.509399248094;6133.815985520441;13967.37146828385;14237.430502779876;21012.579748404947;26604.935802567874;26639.600405993926;2232.05137816429;2256.4584499720113;2260.5026894083276;2265.76209498002;2350.4123969007746;10317.896821031844;11027.393493357862;13854.584362469142;14621.78067328991;15240.923795380952;1620.9270582823801;1771.9557011074696;1773.202059073422;1782.6256486137797;1791.3730194431844;114096.42951785271;116483.41758291239;117205.41397292959;117239;121177;11345.829812552818;11701.180917335874;11787.469563869647;11816.291022538675;11887.989520890647;13914.817240534465;15180.772288415683;15286.082835029934;15303.081815091131;15335;7430.591317477482;8297.751067467985;9102;9107;9181.989981102073;9052.230560402308;9062.954685226532;9076.954615226881;9077.863832042525;9150.63397464094;3718.5723641781;4700.08348372071;4726.621870250372;4738.317897146532;4776.283557466339;1741.2276553485244;1937.5446770009037;1977.7330060441943;1981.3758666020158;1989.7811240763504;792.6155814429997;842;934.9672761453453;983.0366241083779;1009.369144284822;19152.52118697016;20154.59690806205;20695.79304206939;21565.24521641735;21599.136034558756;973.6738192705382;977.726236653738;988.9802203956025;992.7518120469882;1002.774375765447;11136.164787640797;11434.599789007345;11434.94282528582;11470.254433461798;11521.827172592419;619896;622092;633594;635742;641472;122831;133722.33138834243;137812;137981;138873;1023.2172388123092;1027.2706378471369;1072.9946350268356;1095.397531357766;1095.4084794211196;18280.085995699897;18767.624647506702;18991.33530326432;19003.42989710311;19234.326798561982;53044;54183;54476.18285725797;55310;57889;72221.6388918073;79936;81953.77069343845;83427;83482.5825870891;7801.687932482637;8267.62795674184;8304.128066552925;8400.915990840012;8420.073791883011;9358.017408172043;12026.819597705866;12681.175723577939;13003.869961300645;13082.672933176747;10919.945400272947;11085.94457027742;11488.942555287504;11502.367369794742;11554.711132221762;10871.402072886067;11089.33463992163;11223.66329010131;11405.315681059163;11625.476853541442;11375.772484550098;11445.713857153638;11479.36863472517;11753.529858805552;11784.469698863742;8319.417640765088;8434.114417986268;8467.703630372907;8492.830143397221;8559.186877246651;10740.62407815754;11158.163137764537;11389.54441822351;11445.969862712396;11478.311301321948 14:19:38 INFO - PROCESS | 1940 | |3;mootools.html;1404.8805851502623;1546.1805243221092;1569.5448319987204;1770.8760386772924;1903.3623736048414;7854.685812567498;8488.490690558563;8663.696770613014;8667.046624871275;8677.479351238922;8497.830043399128;8610.52642104684;8682.609282582283;8690.869636955451;8704.086070962538;2055.814976652101;2084.124667639595;2390.6772585700883;2427.5266323067053;2453.987730061347;2459.4589190378088;2468.605023196289;2475.4059025833762;2475.6410320503514;2485.030837973192;2237.7762223777654;2299.3331933739196;2388.5903840615;2414;2427.4659574893494;2156.644153714635;2388.7133543974833;2446.0949448704005;2453.7178224504232;2457.2505385857394;2408.6025805742033;2450.4486490539484;2450.4976479821594;2452.0191923230673;2457.2751038443703;8530.360222983301;8563.914360856435;8581.39930204886;8581.871271930864;8606.87089693655;5289.973550132263;5436.130219164954;5439;5461.508464238198;5484.396716361197;3472.0625431133453;3521.2077282611463;3622.6377362263825;3654.6162652921325;3668.1746607013474;947.2800671489648;1014.7260239735303;1044.5038606661851;1067.5356220044305;1072.6728347853903;11900.999999999913;11959.461824217931;11965;11994.820077698754;12002.69993250167;4758.559966759342;4786.688865223749;4803.447603525569;4851.199552073939;4854.199057155601;10048.899511004796;10122.139618132394;10128.240381971382;10151.543180556893;10160.745981350377;5461.918071228934;5463.672179669233;5471.781128754806;5507.256520369779;5515.806946756844;4392.582704643054;4486.170058539181;4525.615322697542;4548.977255113769;4958.9504104959215;3726.888193354204;3992.640662340403;4094.5905409458815;4098.282800509899;4098.508179018537;1298.1432254712033;1352.6956434802091;1353.3909740616762;1357.0839683213987;1360.5782207515701;841.9873701894477;879.8064425826308;903.7876099116702;917.3532659475054;931.6180366050022;5687.772489100473;5756.366799652035;5808.622439541501;5844.240248767718;5867.941320586825;852.8379607874484;869.6694058091131;899.6356475627381;935.4714586258742;974.858645496399;3804.2201348723447;3853.61463853618;3931.57143989221;3938.330483817758;3949.5260568731937;10509.632162874263;10881.673549793519;10925.836112458319;10934.507947142398;11044.723881903017;10761.78476430467;10791.6222932197;10905.781884362268;10914.72713182161;10914.781704365869;7891.763247102481;8332.208440198172;8438.860753798159;8444.155584441507;8515;4980.651354405157;5780.566457515787;5880.676562789087;5966.791162309299;6018.759249630141;9396.06039396055;9550.61797528119;9706.272029597949;9712;9719.319647624881;8113.7565873023905;9617.134457898823;9816.165625921876;9848.409095454295;10017.04838040385;7454.850902982019;7589.7343592976185;7599.392048635986;7705.576193309197;7707.807304817425;7234.529755565872;7381.372583330477;7941;8198;8203.71287004952;7202.279772022755;7265.854682906418;7277.9272207277245;7294.270572942663;7370.705171793068;7177.210506844347;7185.101862267216;7190.352868241884;7280.854382912418;7391.4477959629085;6889.414399776075;6927.896081558882;6944.4097251734165;6974.755883544051;6992.475564332797;4778.952210477851;4856.465788763304;4881.438634557071;4914.434839993303;4957.934044180519;4120.979395103005;4889.315495830516;4950.985048065066;4963.627727920348;5034.370703662043 14:19:38 INFO - PROCESS | 1940 | |4;prototype.html;316.9952450713241;321.1297384089118;330.4514505920173;333.1554509318877;334.37138180221183;6636.966815165923;6678.2987786282465;6684.097646817678;6697.856685860526;6704;6438.549301548894;6476.902846457307;6598.0432837238695;6621.635810030459;6639.73441062357;4881.585065269456;4885.413750349954;4897.04507621014;4899.216125419934;4909.950900490985;4843.176659967797;4897.240927656219;4921.729304888229;4923.163062279421;4926.261060840867;4640.582347588716;4713.693609915362;4849.102915960559;4859.101066302727;4882.316475693406;4827.251775974694;4835.081334546425;4837;4840.177169881129;4864.854054378339;4128.483939507561;4142.316517774563;4163.417121602978;4164;4164.604362585549;6348.301686814447;6410.903836442457;6416.422521973045;6429;6430.1319321891415;5853.824385268449;5860.179574859523;5868.911966320508;5873.148393482921;5893.410658934115;5978.312494063195;6786.626735529544;6953.130858642669;7103.964480177618;7120.857582848365;4586.243269860469;4616.469106052779;4620.491745907947;4627.514111018329;4634.814607415699;7386.446016548781;7484.101907771103;7486.887696684554;7488.138864030598;7493.063367079115;4651.093036857848;4661.067786442725;4661.394018777605;4668.6965347253;4685.367475390847;4719.457262414798;4721.504242054603;4726.361941137902;4726.385569875894;4728.550787675165;5438.048341540215;5642.758593109509;5644.463775941279;5661.2923384576925;5675.631083979528;5724.685142317129;7067.434605231672;7124.394426473704;7131;7136.571805691675;1620.1818081868817;1641.958951026234;1643.95890102746;1646.4237516869;1649.826768189347;978.8923218445965;987.8863930647924;1028.2134167361974;1097.7584931315098;1132.2300835431986;6578.440832529289;6612.371824676648;6659.433948114464;6678.098456708282;6685.732570697215;7352.228016058237;7371.88942165868;7374.225706300868;7380.5940673263485;7386.667599958015;5562.916556251659;6635.535512514174;6659.234188068434;6663.666816659244;6665.600063996176;3790.6209379061875;3850.614938506127;3873.24471728016;3888.16404473034;3897.629725176104;7678.96160519205;7717.922820771721;7719.150893401721;7725.961370193226;7737.264959828807;7678.078630564256;7683;7685.807854803675;7685.884711729329;7697.538147711155;7780.455368124177;7807.297343239135;7811.609419529114;7813.413993950477;7821.256980586835;7190.676419561237;7407.1852096268285;7423.220561840929;7488.138864030489;7492.475526713296;7295.306945840137;7326.230745771831;7332.5967071811565;7334.229905859808;7342.192358840416;7760.456768026184;7767.495112817649;7772.727954521564;7778.455508114378;7783.182765809508;4617.12274667812;4658.650601204856;4664.323673067454;4682.063587282625;4687.507811679725;7152.892706609301;7401.851962960818;7403.296686814744;7432.665530051268;7442.441816863865;7088.468364872552;7150.96424517874;7155.350771106669;7159.140903091662;7162.677679504331;7116.323949224816;7135.678894449658;7137.9649950757885;7140.250273721184;7147.606881621561;5597.244372009726;6406.327335629878;6941.791746247621;6955.478339124687;6964.129483814523;6854.8286292841085;7094.787156385317;7102.325279098477;7125.714971401086;7213.60325182099;5546.473085056913;6651.43462805667;6865.656717164222;6980.092587963532;6984.4063254623925 14:19:38 INFO - PROCESS | 1940 | |5;yui.html;665.8335416145964;670.5674839728374;690.5269890125264;727.3090563964236;730.6127752291284;14866.182359970197;15099.6980060399;15222.238888055594;15257.542273731779;15261.00803447775;12494.375281235925;13172.538961136363;13695.315234238298;13935.860641393609;13953.953453491004;721.6752461392368;740.6185814305637;771.1632878327022;878.767126711422;895.9596818143183;918.2699753695795;920;921.7741653294952;923.7829110159107;923.8152369526088;955.5508910811907;961.5961296255555;962.8122516109362;963.1476143612883;966.632679581758;737.8376757113426;847.2967437027253;935.9064093590621;942.3168203052801;946.0066929723763;702.7961891051589;703.63762662229;705.4391758551964;706.7067167125637;709.9609521476317;12137.332446715427;14802.62993425173;14848.628784280372;14863.405463781435;14880.107193568312;12479.625611231677;12574.497020119185;12631.873681263161;12754.489820407172;12832.80750788739;10015.248856335804;10151.822570489103;10284.691459256159;10522.158227341793;10541.947290263577;3447.4486481083363;3514.9824250878837;3521.7358698097746;3556.893293201208;3759.2105657811894;76257;77354;77593.22406775973;77656;83777;9485.098915603006;9645.565949532287;9652.951735241279;9689.467079310565;9785.412875227508;20436.693449598268;20450.79549204489;20470.181192752123;20480.795192048186;20493.79506204919;7098.3256590623805;7513.812154696177;7522.511036782592;7597.088349398107;7696.461247712718;4087.3868919661695;4143.834246630161;4177.352510360899;4244.830206791756;4245.61789438952;3584.1039740064984;3586.318599466093;3639.5996440391536;3643.890683279506;3648.051506608248;517.5755880178293;538.21689441862;649.1172006071739;659.1134923527848;674.8414122681166;536.8382997479209;576.6223123853882;589.4518098168659;610.3042531514078;613.5796979069352;6858.931410685929;7826.099998500132;8073.717419890276;8109.797255068553;8187.4678145920325;534.794104269859;595.7736060297059;597.7673389888762;609.0590038390696;629.1946308724839;2858.9285267868054;3758.8684396046006;3776.527934008249;3957.5154932945284;4009.859654912064;83252;84025.57987210025;84235;84532.15467845366;84579.30841383137;73380.63309683418;73891.52216955631;73975.52048958991;75338.24661753312;77901.61049194718;67501.3249867505;68275.3172468279;71303.57392852218;71883;72482;8373.832523349378;8430.927283004332;8464.53445060503;8538.573071346533;8549;45616;46206.537934620224;46970;48560.757196213795;48850.893638295216;60575.69712151411;65051.02423463557;66030;66136;67144;10832.97086776755;10964.835527467254;11081.058110060412;11150.2681842949;11412.543498259976;11571.19001669875;11623.360715160747;11655.30068195911;11721.413929303671;12403.813942790684;8982.775430614287;9252.352335336462;10944.398058106563;11044.668659940215;11502.597409090642;11058.281211721212;11084.722881928017;11089.27919685218;11180.496877640688;12105.636830895088;10722.571097156339;10960.409641563632;11064;11091.38997355121;11354.148438866952;9027.232685221566;9083.909160908306;9180.586873590571;9267;9273.258139348702;9240.306976976619;9278.539536865666;9297.30270229722;9440.10319019692;9544.85682714746 14:19:38 INFO - PROCESS | 1940 | __end_tp_report 14:19:38 INFO - PROCESS | 1940 | __start_cc_report 14:19:38 INFO - PROCESS | 1940 | _x_x_mozilla_cycle_collect,1167 14:19:38 INFO - PROCESS | 1940 | __end_cc_report 14:19:38 INFO - PROCESS | 1940 | __startTimestamp1463606378066__endTimestamp 14:19:38 INFO - PROCESS | 1940 | 14:19:38 INFO - PROCESS | 1940 | ------- Summary: start ------- 14:19:38 INFO - PROCESS | 1940 | Number of tests: 6 14:19:38 INFO - PROCESS | 1940 | 14:19:38 INFO - PROCESS | 1940 | [#0] dojo.html Cycles:165 Average:5722.96 Median:5947.61 stddev:655.25 (11.0%) stddev-sans-first:612.49 14:19:38 INFO - PROCESS | 1940 | Values: 2688.4 3439.4 3452.5 3471.0 3515.2 5697.3 5725.3 5731.9 5746.1 5771.5 5724.5 5761.7 5768.0 5773.0 5775.2 5113.5 5158.6 5163.3 5283.3 5302.1 5247.8 5250.7 5256.8 5263.1 5264.3 5191.8 5193.1 5236.8 5252.7 5263.8 5202.9 5228.0 5248.1 5249.7 5287.4 5065.1 5090.3 5099.3 5144.4 5163.7 5548.8 5549.9 5602.6 5606.9 5607.3 6192.1 6200.8 6206.2 6215.9 6218.4 6118.7 6238.3 6243.8 6264.8 6352.3 5841.9 5860.2 5875.9 5885.5 5886.7 6309.2 6319.0 6330.3 6337.6 6340.5 6050.2 6144.5 6220.8 6231.1 6231.7 6310.2 6337.4 6339.7 6347.8 6357.9 6277.4 6286.4 6303.9 6306.3 6308.5 6111.1 6165.9 6178.4 6191.1 6321.7 4877.1 4923.0 4932.9 4947.4 4960.5 4431.5 4433.8 4447.3 4453.1 4470.4 4728.8 5545.7 5650.6 5690.8 5716.6 5947.6 6184.1 6218.1 6267.4 6278.9 5642.8 5646.1 5651.2 5652.8 5653.0 5331.3 5382.1 5383.3 5393.2 5424.4 5165.7 6200.0 6222.8 6264.8 6304.6 6274.3 6314.7 6316.5 6319.2 6330.3 6330.7 6334.6 6337.0 6362.1 6366.8 6037.3 6094.0 6124.8 6164.3 6247.7 6147.2 6181.2 6186.8 6199.7 6212.4 4931.3 5957.7 5973.0 5980.2 6019.1 5884.3 5990.8 5992.3 6016.0 6085.0 5942.7 5956.7 5969.0 5993.3 6024.2 6068.2 6093.0 6121.8 6141.6 6142.2 5389.8 5854.8 5938.3 5985.0 6095.1 14:19:38 INFO - PROCESS | 1940 | 14:19:38 INFO - PROCESS | 1940 | [#1] ext.html Cycles:180 Average:73592.06 Median:34882.89 stddev:173701.39 (498.0%) stddev-sans-first:174102.03 14:19:38 INFO - PROCESS | 1940 | Values: 521.9 535.1 558.5 582.9 610.9 27611.7 30848.1 37123.6 38145.8 38210.8 40181.8 40518.8 40563.2 40633.8 40773.6 9497.4 9653.3 9664.2 9668.2 9686.8 7491.5 8594.2 11466.3 11647.9 11654.6 11338.0 11409.4 11411.5 11420.8 11510.5 8587.2 11310.3 11399.7 11406.3 11419.5 11335.8 11467.8 11467.9 11475.9 11483.2 37607.1 38114.2 38122.2 38209.4 38211.2 3151.6 3251.2 3257.6 3279.3 3514.9 27350.5 28060.7 29066.7 29076.9 29107.6 2745.6 2764.6 2793.5 2807.6 2858.9 482990.0 486300.0 486953.0 488076.6 488195.0 29113.0 36098.1 59981.1 60459.7 60465.7 34583.3 34712.8 34835.5 34863.7 34902.1 15320.5 15505.0 15508.5 15545.0 15554.8 12095.0 12348.1 13016.5 13164.1 13240.7 7935.0 7978.8 8020.6 8039.6 8107.8 3369.1 3394.8 3417.1 3426.5 3442.4 959.3 961.4 1068.9 1086.7 1089.7 29417.7 29427.7 29442.9 29888.0 30096.9 789.6 1019.5 1109.6 1147.3 1147.7 12100.8 14149.2 14333.9 14725.3 14779.1 621646.0 839109.0 865979.0 866854.0 867538.0 59539.7 59770.4 60699.7 60742.4 60765.7 1337.6 1373.7 1470.6 1498.3 1506.5 28210.7 28472.9 29317.0 29386.7 29603.3 53520.9 53787.7 54363.0 54477.0 54546.0 571547.0 586751.0 588173.1 588439.1 588489.0 34849.1 40663.3 41626.4 41909.0 42544.2 41770.4 42297.8 42970.8 43289.0 43360.5 35415.0 35586.5 36812.4 37346.1 37477.4 36074.0 36331.1 36342.0 36929.9 36987.0 37047.1 37243.6 37789.0 38492.2 38514.0 29398.0 31347.8 31374.0 31484.0 31518.2 24642.0 24881.4 24982.8 25189.0 25230.5 14:19:38 INFO - PROCESS | 1940 | 14:19:38 INFO - PROCESS | 1940 | [#2] jquery.html Cycles:180 Average:36213.57 Median:21015.97 stddev:105121.27 (500.2%) stddev-sans-first:105381.76 14:19:38 INFO - PROCESS | 1940 | Values: 397.2 398.5 399.9 400.6 412.1 16331.7 19271.0 19593.1 20250.3 20454.0 19729.0 20127.7 20213.4 21014.3 21017.7 5821.4 5857.9 6118.4 6120.3 6153.3 5923.8 5996.1 6025.5 6026.6 6038.5 5782.6 5976.6 5985.2 5986.2 6107.5 5831.9 6047.6 6057.5 6074.7 6077.5 6070.1 6119.3 6119.9 6132.5 6133.8 13967.4 14237.4 21012.6 26604.9 26639.6 2232.1 2256.5 2260.5 2265.8 2350.4 10317.9 11027.4 13854.6 14621.8 15240.9 1620.9 1772.0 1773.2 1782.6 1791.4 114096.4 116483.4 117205.4 117239.0 121177.0 11345.8 11701.2 11787.5 11816.3 11888.0 13914.8 15180.8 15286.1 15303.1 15335.0 7430.6 8297.8 9102.0 9107.0 9182.0 9052.2 9063.0 9077.0 9077.9 9150.6 3718.6 4700.1 4726.6 4738.3 4776.3 1741.2 1937.5 1977.7 1981.4 1989.8 792.6 842.0 935.0 983.0 1009.4 19152.5 20154.6 20695.8 21565.2 21599.1 973.7 977.7 989.0 992.8 1002.8 11136.2 11434.6 11434.9 11470.3 11521.8 619896.0 622092.0 633594.0 635742.0 641472.0 122831.0 133722.3 137812.0 137981.0 138873.0 1023.2 1027.3 1073.0 1095.4 1095.4 18280.1 18767.6 18991.3 19003.4 19234.3 53044.0 54183.0 54476.2 55310.0 57889.0 72221.6 79936.0 81953.8 83427.0 83482.6 7801.7 8267.6 8304.1 8400.9 8420.1 9358.0 12026.8 12681.2 13003.9 13082.7 10919.9 11085.9 11488.9 11502.4 11554.7 10871.4 11089.3 11223.7 11405.3 11625.5 11375.8 11445.7 11479.4 11753.5 11784.5 8319.4 8434.1 8467.7 8492.8 8559.2 10740.6 11158.2 11389.5 11446.0 11478.3 14:19:38 INFO - PROCESS | 1940 | 14:19:38 INFO - PROCESS | 1940 | [#3] mootools.html Cycles:180 Average:5680.68 Median:4954.46 stddev:3177.35 (64.1%) stddev-sans-first:3170.01 14:19:38 INFO - PROCESS | 1940 | Values: 1404.9 1546.2 1569.5 1770.9 1903.4 7854.7 8488.5 8663.7 8667.0 8677.5 8497.8 8610.5 8682.6 8690.9 8704.1 2055.8 2084.1 2390.7 2427.5 2454.0 2459.5 2468.6 2475.4 2475.6 2485.0 2237.8 2299.3 2388.6 2414.0 2427.5 2156.6 2388.7 2446.1 2453.7 2457.3 2408.6 2450.4 2450.5 2452.0 2457.3 8530.4 8563.9 8581.4 8581.9 8606.9 5290.0 5436.1 5439.0 5461.5 5484.4 3472.1 3521.2 3622.6 3654.6 3668.2 947.3 1014.7 1044.5 1067.5 1072.7 11901.0 11959.5 11965.0 11994.8 12002.7 4758.6 4786.7 4803.4 4851.2 4854.2 10048.9 10122.1 10128.2 10151.5 10160.7 5461.9 5463.7 5471.8 5507.3 5515.8 4392.6 4486.2 4525.6 4549.0 4959.0 3726.9 3992.6 4094.6 4098.3 4098.5 1298.1 1352.7 1353.4 1357.1 1360.6 842.0 879.8 903.8 917.4 931.6 5687.8 5756.4 5808.6 5844.2 5867.9 852.8 869.7 899.6 935.5 974.9 3804.2 3853.6 3931.6 3938.3 3949.5 10509.6 10881.7 10925.8 10934.5 11044.7 10761.8 10791.6 10905.8 10914.7 10914.8 7891.8 8332.2 8438.9 8444.2 8515.0 4980.7 5780.6 5880.7 5966.8 6018.8 9396.1 9550.6 9706.3 9712.0 9719.3 8113.8 9617.1 9816.2 9848.4 10017.0 7454.9 7589.7 7599.4 7705.6 7707.8 7234.5 7381.4 7941.0 8198.0 8203.7 7202.3 7265.9 7277.9 7294.3 7370.7 7177.2 7185.1 7190.4 7280.9 7391.4 6889.4 6927.9 6944.4 6974.8 6992.5 4779.0 4856.5 4881.4 4914.4 4957.9 4121.0 4889.3 4951.0 4963.6 5034.4 14:19:38 INFO - PROCESS | 1940 | 14:19:38 INFO - PROCESS | 1940 | [#4] prototype.html Cycles:180 Average:5816.72 Median:6636.25 stddev:1872.38 (28.2%) stddev-sans-first:1831.56 14:19:38 INFO - PROCESS | 1940 | Values: 317.0 321.1 330.5 333.2 334.4 6637.0 6678.3 6684.1 6697.9 6704.0 6438.5 6476.9 6598.0 6621.6 6639.7 4881.6 4885.4 4897.0 4899.2 4910.0 4843.2 4897.2 4921.7 4923.2 4926.3 4640.6 4713.7 4849.1 4859.1 4882.3 4827.3 4835.1 4837.0 4840.2 4864.9 4128.5 4142.3 4163.4 4164.0 4164.6 6348.3 6410.9 6416.4 6429.0 6430.1 5853.8 5860.2 5868.9 5873.1 5893.4 5978.3 6786.6 6953.1 7104.0 7120.9 4586.2 4616.5 4620.5 4627.5 4634.8 7386.4 7484.1 7486.9 7488.1 7493.1 4651.1 4661.1 4661.4 4668.7 4685.4 4719.5 4721.5 4726.4 4726.4 4728.6 5438.0 5642.8 5644.5 5661.3 5675.6 5724.7 7067.4 7124.4 7131.0 7136.6 1620.2 1642.0 1644.0 1646.4 1649.8 978.9 987.9 1028.2 1097.8 1132.2 6578.4 6612.4 6659.4 6678.1 6685.7 7352.2 7371.9 7374.2 7380.6 7386.7 5562.9 6635.5 6659.2 6663.7 6665.6 3790.6 3850.6 3873.2 3888.2 3897.6 7679.0 7717.9 7719.2 7726.0 7737.3 7678.1 7683.0 7685.8 7685.9 7697.5 7780.5 7807.3 7811.6 7813.4 7821.3 7190.7 7407.2 7423.2 7488.1 7492.5 7295.3 7326.2 7332.6 7334.2 7342.2 7760.5 7767.5 7772.7 7778.5 7783.2 4617.1 4658.7 4664.3 4682.1 4687.5 7152.9 7401.9 7403.3 7432.7 7442.4 7088.5 7151.0 7155.4 7159.1 7162.7 7116.3 7135.7 7138.0 7140.3 7147.6 5597.2 6406.3 6941.8 6955.5 6964.1 6854.8 7094.8 7102.3 7125.7 7213.6 5546.5 6651.4 6865.7 6980.1 6984.4 14:19:38 INFO - PROCESS | 1940 | 14:19:38 INFO - PROCESS | 1940 | [#5] yui.html Cycles:180 Average:17658.21 Median:639.16 stddev:24496.24 (3832.6%) stddev-sans-first:24531.73 14:19:38 INFO - PROCESS | 1940 | Values: 665.8 670.6 690.5 727.3 730.6 14866.2 15099.7 15222.2 15257.5 15261.0 12494.4 13172.5 13695.3 13935.9 13954.0 721.7 740.6 771.2 878.8 896.0 918.3 920.0 921.8 923.8 923.8 955.6 961.6 962.8 963.1 966.6 737.8 847.3 935.9 942.3 946.0 702.8 703.6 705.4 706.7 710.0 12137.3 14802.6 14848.6 14863.4 14880.1 12479.6 12574.5 12631.9 12754.5 12832.8 10015.2 10151.8 10284.7 10522.2 10541.9 3447.4 3515.0 3521.7 3556.9 3759.2 76257.0 77354.0 77593.2 77656.0 83777.0 9485.1 9645.6 9653.0 9689.5 9785.4 20436.7 20450.8 20470.2 20480.8 20493.8 7098.3 7513.8 7522.5 7597.1 7696.5 4087.4 4143.8 4177.4 4244.8 4245.6 3584.1 3586.3 3639.6 3643.9 3648.1 517.6 538.2 649.1 659.1 674.8 536.8 576.6 589.5 610.3 613.6 6858.9 7826.1 8073.7 8109.8 8187.5 534.8 595.8 597.8 609.1 629.2 2858.9 3758.9 3776.5 3957.5 4009.9 83252.0 84025.6 84235.0 84532.2 84579.3 73380.6 73891.5 73975.5 75338.2 77901.6 67501.3 68275.3 71303.6 71883.0 72482.0 8373.8 8430.9 8464.5 8538.6 8549.0 45616.0 46206.5 46970.0 48560.8 48850.9 60575.7 65051.0 66030.0 66136.0 67144.0 10833.0 10964.8 11081.1 11150.3 11412.5 11571.2 11623.4 11655.3 11721.4 12403.8 8982.8 9252.4 10944.4 11044.7 11502.6 11058.3 11084.7 11089.3 11180.5 12105.6 10722.6 10960.4 11064.0 11091.4 11354.1 9027.2 9083.9 9180.6 9267.0 9273.3 9240.3 9278.5 9297.3 9440.1 9544.9 14:19:38 INFO - PROCESS | 1940 | -------- Summary: end -------- 14:19:38 INFO - PROCESS | 1940 | 14:19:38 INFO - TEST-INFO | 1940: exit 0 14:19:38 INFO - TEST-OK | dromaeo_css | took 1114775ms 14:19:38 INFO - TEST-START | kraken 14:19:38 INFO - operating with platform_type : mac_ 14:19:38 INFO - Initialising browser for kraken test... 14:19:38 INFO - TEST-INFO | started process 1943 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmpKlgyv5/profile http://localhost:50919/getInfo.html) 14:19:40 INFO - PROCESS | 1943 | __metrics Screen width/height:1600/1200 14:19:40 INFO - PROCESS | 1943 | colorDepth:24 14:19:40 INFO - PROCESS | 1943 | Browser inner width/height: 1024/689 14:19:40 INFO - PROCESS | 1943 | __metrics 14:19:40 INFO - PROCESS | 1943 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:19:40 ERROR - PROCESS | 1943 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:19:41 INFO - TEST-INFO | 1943: exit 0 14:19:41 INFO - Browser initialized. 14:19:41 INFO - Running cycle 1/1 for kraken test... 14:19:41 INFO - TEST-INFO | started process 1945 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmpKlgyv5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:19:53 INFO - PROCESS | 1945 | RSS: Main: 211382272 14:19:53 INFO - PROCESS | 1945 | 14:19:55 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50919/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50919/tests/kraken/driver.html?testName=audio-beat-detection) 14:19:56 INFO - PROCESS | 1945 | RSS: Main: 271695872 14:19:56 INFO - PROCESS | 1945 | 14:19:59 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50919/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50919/tests/kraken/driver.html?testName=audio-dft) 14:20:00 INFO - PROCESS | 1945 | RSS: Main: 286089216 14:20:00 INFO - PROCESS | 1945 | 14:20:03 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50919/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50919/tests/kraken/driver.html?testName=audio-fft) 14:20:04 INFO - PROCESS | 1945 | RSS: Main: 375169024 14:20:04 INFO - PROCESS | 1945 | 14:20:05 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50919/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50919/tests/kraken/driver.html?testName=audio-oscillator) 14:20:06 INFO - PROCESS | 1945 | RSS: Main: 278339584 14:20:06 INFO - PROCESS | 1945 | 14:20:08 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50919/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50919/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:20:09 INFO - PROCESS | 1945 | RSS: Main: 279187456 14:20:09 INFO - PROCESS | 1945 | 14:20:12 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50919/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50919/tests/kraken/driver.html?testName=imaging-darkroom) 14:20:12 INFO - PROCESS | 1945 | RSS: Main: 320131072 14:20:12 INFO - PROCESS | 1945 | 14:20:15 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50919/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50919/tests/kraken/driver.html?testName=imaging-desaturate) 14:20:16 INFO - PROCESS | 1945 | RSS: Main: 318824448 14:20:16 INFO - PROCESS | 1945 | 14:20:18 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50919/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50919/tests/kraken/driver.html?testName=json-parse-financial) 14:20:19 INFO - PROCESS | 1945 | RSS: Main: 316694528 14:20:19 INFO - PROCESS | 1945 | 14:20:21 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50919/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50919/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:20:21 INFO - PROCESS | 1945 | RSS: Main: 307920896 14:20:21 INFO - PROCESS | 1945 | 14:20:23 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50919/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50919/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:20:24 INFO - PROCESS | 1945 | RSS: Main: 314519552 14:20:24 INFO - PROCESS | 1945 | 14:20:26 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50919/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50919/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:20:27 INFO - PROCESS | 1945 | RSS: Main: 318832640 14:20:27 INFO - PROCESS | 1945 | 14:20:29 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50919/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50919/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:20:30 INFO - PROCESS | 1945 | RSS: Main: 323813376 14:20:30 INFO - PROCESS | 1945 | 14:20:33 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50919/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50919/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:20:33 INFO - PROCESS | 1945 | RSS: Main: 322125824 14:20:33 INFO - PROCESS | 1945 | 14:20:35 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50919/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50919/tests/kraken/driver.html?testName=ai-astar) 14:20:35 INFO - PROCESS | 1945 | RSS: Main: 387035136 14:20:35 INFO - PROCESS | 1945 | 14:20:35 INFO - PROCESS | 1945 | __start_tp_report 14:20:35 INFO - PROCESS | 1945 | _x_x_mozilla_page_load 14:20:35 INFO - PROCESS | 1945 | _x_x_mozilla_page_load_details 14:20:35 INFO - PROCESS | 1945 | |i|pagename|runs| 14:20:35 INFO - PROCESS | 1945 | |0;ai-astar;114;114;119;113;112;113;115;113;114;134 14:20:35 INFO - PROCESS | 1945 | |1;audio-beat-detection;155;124;160;187;152;145;121;152;159;115 14:20:35 INFO - PROCESS | 1945 | |2;audio-dft;185;186;188;187;185;185;184;185;186;185 14:20:35 INFO - PROCESS | 1945 | |3;audio-fft;69;79;75;154;57;55;58;65;135;56 14:20:35 INFO - PROCESS | 1945 | |4;audio-oscillator;90;88;142;77;82;88;125;78;77;124 14:20:35 INFO - PROCESS | 1945 | |5;imaging-gaussian-blur;88;85;89;85;87;84;89;84;86;85 14:20:35 INFO - PROCESS | 1945 | |6;imaging-darkroom;93;95;95;94;93;94;92;95;93;94 14:20:35 INFO - PROCESS | 1945 | |7;imaging-desaturate;90;88;90;87;91;90;91;90;90;84 14:20:35 INFO - PROCESS | 1945 | |8;json-parse-financial;61;63;65;63;64;65;67;82;59;58 14:20:35 INFO - PROCESS | 1945 | |9;json-stringify-tinderbox;65;68;67;75;78;72;68;71;68;70 14:20:35 INFO - PROCESS | 1945 | |10;stanford-crypto-aes;83;92;105;103;77;81;81;79;82;86 14:20:35 INFO - PROCESS | 1945 | |11;stanford-crypto-ccm;130;127;119;129;126;128;127;125;151;143 14:20:35 INFO - PROCESS | 1945 | |12;stanford-crypto-pbkdf2;166;168;167;171;166;164;167;168;167;162 14:20:35 INFO - PROCESS | 1945 | |13;stanford-crypto-sha256-iterative;66;69;67;68;67;67;66;70;66;65 14:20:35 INFO - PROCESS | 1945 | __end_tp_report 14:20:35 INFO - PROCESS | 1945 | __start_cc_report 14:20:35 INFO - PROCESS | 1945 | _x_x_mozilla_cycle_collect,2008 14:20:35 INFO - PROCESS | 1945 | __end_cc_report 14:20:35 INFO - PROCESS | 1945 | __startTimestamp1463606435856__endTimestamp 14:20:35 INFO - PROCESS | 1945 | 14:20:35 INFO - PROCESS | 1945 | ------- Summary: start ------- 14:20:35 INFO - PROCESS | 1945 | Number of tests: 14 14:20:35 INFO - PROCESS | 1945 | 14:20:35 INFO - PROCESS | 1945 | [#0] ai-astar Cycles:10 Average:116.10 Median:114.00 stddev:6.57 (5.8%) stddev-sans-first:6.93 14:20:35 INFO - PROCESS | 1945 | Values: 114.0 114.0 119.0 113.0 112.0 113.0 115.0 113.0 114.0 134.0 14:20:35 INFO - PROCESS | 1945 | 14:20:35 INFO - PROCESS | 1945 | [#1] audio-beat-detection Cycles:10 Average:147.00 Median:153.50 stddev:21.76 (14.2%) stddev-sans-first:22.88 14:20:35 INFO - PROCESS | 1945 | Values: 155.0 124.0 160.0 187.0 152.0 145.0 121.0 152.0 159.0 115.0 14:20:35 INFO - PROCESS | 1945 | 14:20:35 INFO - PROCESS | 1945 | [#2] audio-dft Cycles:10 Average:185.60 Median:185.50 stddev:1.17 (0.6%) stddev-sans-first:1.22 14:20:35 INFO - PROCESS | 1945 | Values: 185.0 186.0 188.0 187.0 185.0 185.0 184.0 185.0 186.0 185.0 14:20:35 INFO - PROCESS | 1945 | 14:20:35 INFO - PROCESS | 1945 | [#3] audio-fft Cycles:10 Average:80.30 Median:61.50 stddev:35.10 (57.1%) stddev-sans-first:36.99 14:20:35 INFO - PROCESS | 1945 | Values: 69.0 79.0 75.0 154.0 57.0 55.0 58.0 65.0 135.0 56.0 14:20:35 INFO - PROCESS | 1945 | 14:20:35 INFO - PROCESS | 1945 | [#4] audio-oscillator Cycles:10 Average:97.10 Median:80.00 stddev:23.89 (29.9%) stddev-sans-first:25.20 14:20:35 INFO - PROCESS | 1945 | Values: 90.0 88.0 142.0 77.0 82.0 88.0 125.0 78.0 77.0 124.0 14:20:35 INFO - PROCESS | 1945 | 14:20:35 INFO - PROCESS | 1945 | [#5] imaging-gaussian-blur Cycles:10 Average:86.20 Median:86.50 stddev:1.93 (2.2%) stddev-sans-first:1.94 14:20:35 INFO - PROCESS | 1945 | Values: 88.0 85.0 89.0 85.0 87.0 84.0 89.0 84.0 86.0 85.0 14:20:35 INFO - PROCESS | 1945 | 14:20:35 INFO - PROCESS | 1945 | [#6] imaging-darkroom Cycles:10 Average:93.80 Median:94.00 stddev:1.03 (1.1%) stddev-sans-first:1.05 14:20:35 INFO - PROCESS | 1945 | Values: 93.0 95.0 95.0 94.0 93.0 94.0 92.0 95.0 93.0 94.0 14:20:35 INFO - PROCESS | 1945 | 14:20:35 INFO - PROCESS | 1945 | [#7] imaging-desaturate Cycles:10 Average:89.10 Median:90.00 stddev:2.18 (2.4%) stddev-sans-first:2.29 14:20:35 INFO - PROCESS | 1945 | Values: 90.0 88.0 90.0 87.0 91.0 90.0 91.0 90.0 90.0 84.0 14:20:35 INFO - PROCESS | 1945 | 14:20:35 INFO - PROCESS | 1945 | [#8] json-parse-financial Cycles:10 Average:64.70 Median:64.50 stddev:6.68 (10.4%) stddev-sans-first:6.95 14:20:35 INFO - PROCESS | 1945 | Values: 61.0 63.0 65.0 63.0 64.0 65.0 67.0 82.0 59.0 58.0 14:20:35 INFO - PROCESS | 1945 | 14:20:35 INFO - PROCESS | 1945 | [#9] json-stringify-tinderbox Cycles:10 Average:70.20 Median:70.50 stddev:3.94 (5.6%) stddev-sans-first:3.70 14:20:35 INFO - PROCESS | 1945 | Values: 65.0 68.0 67.0 75.0 78.0 72.0 68.0 71.0 68.0 70.0 14:20:35 INFO - PROCESS | 1945 | 14:20:35 INFO - PROCESS | 1945 | [#10] stanford-crypto-aes Cycles:10 Average:86.90 Median:81.50 stddev:9.90 (12.2%) stddev-sans-first:10.40 14:20:35 INFO - PROCESS | 1945 | Values: 83.0 92.0 105.0 103.0 77.0 81.0 81.0 79.0 82.0 86.0 14:20:35 INFO - PROCESS | 1945 | 14:20:35 INFO - PROCESS | 1945 | [#11] stanford-crypto-ccm Cycles:10 Average:130.50 Median:128.50 stddev:9.38 (7.3%) stddev-sans-first:9.95 14:20:35 INFO - PROCESS | 1945 | Values: 130.0 127.0 119.0 129.0 126.0 128.0 127.0 125.0 151.0 143.0 14:20:35 INFO - PROCESS | 1945 | 14:20:35 INFO - PROCESS | 1945 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.60 Median:167.00 stddev:2.41 (1.4%) stddev-sans-first:2.55 14:20:35 INFO - PROCESS | 1945 | Values: 166.0 168.0 167.0 171.0 166.0 164.0 167.0 168.0 167.0 162.0 14:20:35 INFO - PROCESS | 1945 | 14:20:35 INFO - PROCESS | 1945 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.00 stddev:1.52 (2.3%) stddev-sans-first:1.56 14:20:35 INFO - PROCESS | 1945 | Values: 66.0 69.0 67.0 68.0 67.0 67.0 66.0 70.0 66.0 65.0 14:20:35 INFO - PROCESS | 1945 | -------- Summary: end -------- 14:20:35 INFO - PROCESS | 1945 | 14:20:36 INFO - TEST-INFO | 1945: exit 0 14:20:36 INFO - TEST-OK | kraken | took 58093ms 14:20:36 INFO - SUITE-END | took 1172s 14:20:37 INFO - Completed test suite (00:19:33) 14:20:37 INFO - javascript benchmark 14:20:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2688.408550118974, 3439.415299399102, 3452.5166476693266, 3471.0, 3515.2442224921638, 5697.344805347387, 5725.341585717633, 5731.914021289679, 5746.1093530502785, 5771.480566748992, 5724.484796368326, 5761.654300741959, 5768.019436695761, 5773.047447171222, 5775.2203452534, 5113.539781419671, 5158.613104017195, 5163.32876726026, 5283.339582552181, 5302.125149350352, 5247.763850626712, 5250.684958902459, 5256.842294731145, 5263.131583288753, 5264.263003179558, 5191.792328306863, 5193.1171700810955, 5236.764345604457, 5252.65857719247, 5263.815766448175, 5202.92195617066, 5228.032813929397, 5248.081585722522, 5249.711265880375, 5287.365516138088, 5065.088284108859, 5090.261912022773, 5099.260607211933, 5144.4083930348115, 5163.690178589297, 5548.805791797267, 5549.944500554983, 5602.60781745278, 5606.887862242773, 5607.271054762896, 6192.102145188923, 6200.751969921198, 6206.162168107293, 6215.937840621581, 6218.440340369343, 6118.724657390429, 6238.251409830804, 6243.781467648656, 6264.843378915518, 6352.269489008777, 5841.9415805842245, 5860.208871802337, 5875.882482350415, 5885.499732522698, 5886.676232807194, 6309.211348581427, 6318.988961766096, 6330.272018717907, 6337.556371053982, 6340.492760579143, 6050.183225264621, 6144.5084393248435, 6220.813375598649, 6231.096491008869, 6231.688415579293, 6310.179676642099, 6337.366263373718, 6339.683015849281, 6347.841303967438, 6357.8728425432155, 6277.435030847154, 6286.4342209201395, 6303.873922521524, 6306.337834527309, 6308.495320374359, 6111.083337499411, 6165.876682466415, 6178.382161783786, 6191.071339299141, 6321.683915804191, 4877.073356062408, 4923.01539692063, 4932.8520144395725, 4947.406311242674, 4960.528749768839, 4431.512533621299, 4433.844815431445, 4447.310666846579, 4453.087117140978, 4470.374147619336, 4728.763561821896, 5545.694986775686, 5650.604457688005, 5690.77236910519, 5716.599838011382, 5947.613405128653, 6184.072389141665, 6218.098375735583, 6267.373262673696, 6278.874422511524, 5642.774289028393, 5646.096624540054, 5651.180578816049, 5652.802151924745, 5653.0, 5331.333583302087, 5382.058139825514, 5383.327084114486, 5393.2179833923865, 5424.430434804368, 5165.690058596496, 6199.969000155151, 6222.751089956351, 6264.843378915472, 6304.590201636971, 6274.341194174546, 6314.715837787218, 6316.526260530387, 6319.178506794087, 6330.335314792064, 6330.746770129143, 6334.619922804646, 6337.0, 6362.0774987627465, 6366.77716279928, 6037.275526936795, 6093.969530152321, 6124.777645458507, 6164.291106522807, 6247.656378899204, 6147.200863887666, 6181.196444462191, 6186.81439556814, 6199.721012554536, 6212.409821066992, 4931.2849636803185, 5957.702114894151, 5973.044312910088, 5980.20593821843, 6019.127226552213, 5884.293884733859, 5990.820275391745, 5992.250968628921, 6015.977283861666, 6085.026395776831, 5942.6731529766275, 5956.702164891824, 5968.970155149196, 5993.340732519507, 6024.246969128859, 6068.241469816273, 6092.969535152296, 6121.84695382619, 6141.570090093829, 6142.201513803177, 5389.784408623611, 5854.824355269348, 5938.257717785277, 5985.0, 6095.116208149882], "value": 5680.712475045874, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [521.9217117432385, 535.1464414259257, 558.4638746803068, 582.9125631155327, 610.8992016317308, 27611.723882761165, 30848.074557763273, 37123.62876371235, 38145.80927095367, 38210.8089459553, 40181.79909100458, 40518.78443646695, 40563.18873622533, 40633.796831015876, 40773.59226407735, 9497.430154190735, 9653.324267301292, 9664.22686185107, 9668.178204852597, 9686.75783105421, 7491.475596708233, 8594.226519613234, 11466.254693444942, 11647.883521164807, 11654.592089276877, 11338.0, 11409.429528523582, 11411.54353825846, 11420.828687569694, 11510.482028308705, 8587.184217499327, 11310.264832785884, 11399.715007124805, 11406.315621062722, 11419.54321827126, 11335.829962550568, 11467.770644587144, 11467.942660286646, 11475.942620286929, 11483.196176267664, 37607.05982350436, 38114.237715245814, 38122.23755524874, 38209.42685859687, 38211.23577528434, 3151.558781770554, 3251.1546997780747, 3257.641659417462, 3279.262166012652, 3514.947275790865, 27350.45299094027, 28060.719392806015, 29066.709332906612, 29076.854615727, 29107.56338654922, 2745.645360763304, 2764.5853122031654, 2793.4971705093076, 2807.620971168907, 2858.9285267868263, 482990.0, 486300.0, 486953.0, 488076.5596172032, 488195.0, 29113.0, 36098.09754756152, 59981.10028349578, 60459.697701512094, 60465.697671512244, 34583.308333833185, 34712.82643586816, 34835.477467838005, 34863.65136348655, 34902.12744681403, 15320.540383788504, 15505.0, 15508.457203998032, 15545.0, 15554.84445155534, 12095.0, 12348.073894457952, 13016.544420945222, 13164.078514503894, 13240.73518529624, 7934.960325198337, 7978.760637180894, 8020.639071241808, 8039.598020099089, 8107.83784324322, 3369.0735047862036, 3394.7963122212745, 3417.1457135716073, 3426.4517677171534, 3442.397580423466, 959.2949182350967, 961.403929563675, 1068.8770791358938, 1086.739182596187, 1089.700332408594, 29417.705822941924, 29427.705722942497, 29442.852785735933, 29888.0, 30096.946606868656, 789.6328207383594, 1019.4800651667703, 1109.5949978257877, 1147.2657499200518, 1147.701597584634, 12100.75798484043, 14149.221792801289, 14333.918207585419, 14725.263736813116, 14779.113253204841, 621646.0, 839109.0, 865979.0, 866854.0, 867538.0, 59539.702301488214, 59770.402295976484, 60699.69650151721, 60742.392576073675, 60765.69617151886, 1337.5920344295084, 1373.7183877305129, 1470.6102882736297, 1498.340730078762, 1506.5329747778223, 28210.71789282081, 28472.857635711687, 29317.0, 29386.706132938398, 29603.259918502208, 53520.929581408935, 53787.73106134444, 54363.0, 54477.0, 54546.0, 571547.0, 586751.0, 588173.0591347187, 588439.0578047083, 588489.0, 34849.12877178091, 40663.32371777165, 41626.375604365356, 41909.0, 42544.23462474929, 41770.37344439897, 42297.788511058476, 42970.78514607407, 43289.0, 43360.48238311644, 35415.0, 35586.46620300646, 36812.447813282284, 37346.06634834151, 37477.4378384319, 36074.0, 36331.09172270714, 36342.0, 36929.89210323695, 36987.0, 37047.073823154635, 37243.62756372402, 37789.0, 38492.230155397294, 38514.0371490715, 29398.0, 31347.843260784462, 31374.0, 31484.0, 31518.21204469815, 24642.0, 24881.37796555028, 24982.750172498043, 25188.992440302914, 25230.495390091728], "value": 18934.074069028084, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.1699148779051, 398.5078428141245, 399.8980260033691, 400.55738409058006, 412.05022423314267, 16331.673366532677, 19271.0, 19593.11830967606, 20250.291239806593, 20453.97730113492, 19729.0, 20127.698084528707, 20213.393598192077, 21014.264500742476, 21017.68473472899, 5821.388754180803, 5857.941420585804, 6118.418750218744, 6120.265568131808, 6153.292371377304, 5923.792667256647, 5996.070609055581, 6025.457708806207, 6026.578139530235, 6038.516918646497, 5782.595218334718, 5976.641401515923, 5985.162077309158, 5986.191864098357, 6107.450329470346, 5831.912521312183, 6047.606905551125, 6057.51539876811, 6074.696265186745, 6077.544184186182, 6070.089486577004, 6119.265688117454, 6119.87760244797, 6132.509399248094, 6133.815985520441, 13967.37146828385, 14237.430502779876, 21012.579748404947, 26604.935802567874, 26639.600405993926, 2232.05137816429, 2256.4584499720113, 2260.5026894083276, 2265.76209498002, 2350.4123969007746, 10317.896821031844, 11027.393493357862, 13854.584362469142, 14621.78067328991, 15240.923795380952, 1620.9270582823801, 1771.9557011074696, 1773.202059073422, 1782.6256486137797, 1791.3730194431844, 114096.42951785271, 116483.41758291239, 117205.41397292959, 117239.0, 121177.0, 11345.829812552818, 11701.180917335874, 11787.469563869647, 11816.291022538675, 11887.989520890647, 13914.817240534465, 15180.772288415683, 15286.082835029934, 15303.081815091131, 15335.0, 7430.591317477482, 8297.751067467985, 9102.0, 9107.0, 9181.989981102073, 9052.230560402308, 9062.954685226532, 9076.954615226881, 9077.863832042525, 9150.63397464094, 3718.5723641781, 4700.08348372071, 4726.621870250372, 4738.317897146532, 4776.283557466339, 1741.2276553485244, 1937.5446770009037, 1977.7330060441943, 1981.3758666020158, 1989.7811240763504, 792.6155814429997, 842.0, 934.9672761453453, 983.0366241083779, 1009.369144284822, 19152.52118697016, 20154.59690806205, 20695.79304206939, 21565.24521641735, 21599.136034558756, 973.6738192705382, 977.726236653738, 988.9802203956025, 992.7518120469882, 1002.774375765447, 11136.164787640797, 11434.599789007345, 11434.94282528582, 11470.254433461798, 11521.827172592419, 619896.0, 622092.0, 633594.0, 635742.0, 641472.0, 122831.0, 133722.33138834243, 137812.0, 137981.0, 138873.0, 1023.2172388123092, 1027.2706378471369, 1072.9946350268356, 1095.397531357766, 1095.4084794211196, 18280.085995699897, 18767.624647506702, 18991.33530326432, 19003.42989710311, 19234.326798561982, 53044.0, 54183.0, 54476.18285725797, 55310.0, 57889.0, 72221.6388918073, 79936.0, 81953.77069343845, 83427.0, 83482.5825870891, 7801.687932482637, 8267.62795674184, 8304.128066552925, 8400.915990840012, 8420.073791883011, 9358.017408172043, 12026.819597705866, 12681.175723577939, 13003.869961300645, 13082.672933176747, 10919.945400272947, 11085.94457027742, 11488.942555287504, 11502.367369794742, 11554.711132221762, 10871.402072886067, 11089.33463992163, 11223.66329010131, 11405.315681059163, 11625.476853541442, 11375.772484550098, 11445.713857153638, 11479.36863472517, 11753.529858805552, 11784.469698863742, 8319.417640765088, 8434.114417986268, 8467.703630372907, 8492.830143397221, 8559.186877246651, 10740.62407815754, 11158.163137764537, 11389.54441822351, 11445.969862712396, 11478.311301321948], "value": 9756.037126917518, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1404.8805851502623, 1546.1805243221092, 1569.5448319987204, 1770.8760386772924, 1903.3623736048414, 7854.685812567498, 8488.490690558563, 8663.696770613014, 8667.046624871275, 8677.479351238922, 8497.830043399128, 8610.52642104684, 8682.609282582283, 8690.869636955451, 8704.086070962538, 2055.814976652101, 2084.124667639595, 2390.6772585700883, 2427.5266323067053, 2453.987730061347, 2459.4589190378088, 2468.605023196289, 2475.4059025833762, 2475.6410320503514, 2485.030837973192, 2237.7762223777654, 2299.3331933739196, 2388.5903840615, 2414.0, 2427.4659574893494, 2156.644153714635, 2388.7133543974833, 2446.0949448704005, 2453.7178224504232, 2457.2505385857394, 2408.6025805742033, 2450.4486490539484, 2450.4976479821594, 2452.0191923230673, 2457.2751038443703, 8530.360222983301, 8563.914360856435, 8581.39930204886, 8581.871271930864, 8606.87089693655, 5289.973550132263, 5436.130219164954, 5439.0, 5461.508464238198, 5484.396716361197, 3472.0625431133453, 3521.2077282611463, 3622.6377362263825, 3654.6162652921325, 3668.1746607013474, 947.2800671489648, 1014.7260239735303, 1044.5038606661851, 1067.5356220044305, 1072.6728347853903, 11900.999999999913, 11959.461824217931, 11965.0, 11994.820077698754, 12002.69993250167, 4758.559966759342, 4786.688865223749, 4803.447603525569, 4851.199552073939, 4854.199057155601, 10048.899511004796, 10122.139618132394, 10128.240381971382, 10151.543180556893, 10160.745981350377, 5461.918071228934, 5463.672179669233, 5471.781128754806, 5507.256520369779, 5515.806946756844, 4392.582704643054, 4486.170058539181, 4525.615322697542, 4548.977255113769, 4958.9504104959215, 3726.888193354204, 3992.640662340403, 4094.5905409458815, 4098.282800509899, 4098.508179018537, 1298.1432254712033, 1352.6956434802091, 1353.3909740616762, 1357.0839683213987, 1360.5782207515701, 841.9873701894477, 879.8064425826308, 903.7876099116702, 917.3532659475054, 931.6180366050022, 5687.772489100473, 5756.366799652035, 5808.622439541501, 5844.240248767718, 5867.941320586825, 852.8379607874484, 869.6694058091131, 899.6356475627381, 935.4714586258742, 974.858645496399, 3804.2201348723447, 3853.61463853618, 3931.57143989221, 3938.330483817758, 3949.5260568731937, 10509.632162874263, 10881.673549793519, 10925.836112458319, 10934.507947142398, 11044.723881903017, 10761.78476430467, 10791.6222932197, 10905.781884362268, 10914.72713182161, 10914.781704365869, 7891.763247102481, 8332.208440198172, 8438.860753798159, 8444.155584441507, 8515.0, 4980.651354405157, 5780.566457515787, 5880.676562789087, 5966.791162309299, 6018.759249630141, 9396.06039396055, 9550.61797528119, 9706.272029597949, 9712.0, 9719.319647624881, 8113.7565873023905, 9617.134457898823, 9816.165625921876, 9848.409095454295, 10017.04838040385, 7454.850902982019, 7589.7343592976185, 7599.392048635986, 7705.576193309197, 7707.807304817425, 7234.529755565872, 7381.372583330477, 7941.0, 8198.0, 8203.71287004952, 7202.279772022755, 7265.854682906418, 7277.9272207277245, 7294.270572942663, 7370.705171793068, 7177.210506844347, 7185.101862267216, 7190.352868241884, 7280.854382912418, 7391.4477959629085, 6889.414399776075, 6927.896081558882, 6944.4097251734165, 6974.755883544051, 6992.475564332797, 4778.952210477851, 4856.465788763304, 4881.438634557071, 4914.434839993303, 4957.934044180519, 4120.979395103005, 4889.315495830516, 4950.985048065066, 4963.627727920348, 5034.370703662043], "value": 4575.947463794991, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.9952450713241, 321.1297384089118, 330.4514505920173, 333.1554509318877, 334.37138180221183, 6636.966815165923, 6678.2987786282465, 6684.097646817678, 6697.856685860526, 6704.0, 6438.549301548894, 6476.902846457307, 6598.0432837238695, 6621.635810030459, 6639.73441062357, 4881.585065269456, 4885.413750349954, 4897.04507621014, 4899.216125419934, 4909.950900490985, 4843.176659967797, 4897.240927656219, 4921.729304888229, 4923.163062279421, 4926.261060840867, 4640.582347588716, 4713.693609915362, 4849.102915960559, 4859.101066302727, 4882.316475693406, 4827.251775974694, 4835.081334546425, 4837.0, 4840.177169881129, 4864.854054378339, 4128.483939507561, 4142.316517774563, 4163.417121602978, 4164.0, 4164.604362585549, 6348.301686814447, 6410.903836442457, 6416.422521973045, 6429.0, 6430.1319321891415, 5853.824385268449, 5860.179574859523, 5868.911966320508, 5873.148393482921, 5893.410658934115, 5978.312494063195, 6786.626735529544, 6953.130858642669, 7103.964480177618, 7120.857582848365, 4586.243269860469, 4616.469106052779, 4620.491745907947, 4627.514111018329, 4634.814607415699, 7386.446016548781, 7484.101907771103, 7486.887696684554, 7488.138864030598, 7493.063367079115, 4651.093036857848, 4661.067786442725, 4661.394018777605, 4668.6965347253, 4685.367475390847, 4719.457262414798, 4721.504242054603, 4726.361941137902, 4726.385569875894, 4728.550787675165, 5438.048341540215, 5642.758593109509, 5644.463775941279, 5661.2923384576925, 5675.631083979528, 5724.685142317129, 7067.434605231672, 7124.394426473704, 7131.0, 7136.571805691675, 1620.1818081868817, 1641.958951026234, 1643.95890102746, 1646.4237516869, 1649.826768189347, 978.8923218445965, 987.8863930647924, 1028.2134167361974, 1097.7584931315098, 1132.2300835431986, 6578.440832529289, 6612.371824676648, 6659.433948114464, 6678.098456708282, 6685.732570697215, 7352.228016058237, 7371.88942165868, 7374.225706300868, 7380.5940673263485, 7386.667599958015, 5562.916556251659, 6635.535512514174, 6659.234188068434, 6663.666816659244, 6665.600063996176, 3790.6209379061875, 3850.614938506127, 3873.24471728016, 3888.16404473034, 3897.629725176104, 7678.96160519205, 7717.922820771721, 7719.150893401721, 7725.961370193226, 7737.264959828807, 7678.078630564256, 7683.0, 7685.807854803675, 7685.884711729329, 7697.538147711155, 7780.455368124177, 7807.297343239135, 7811.609419529114, 7813.413993950477, 7821.256980586835, 7190.676419561237, 7407.1852096268285, 7423.220561840929, 7488.138864030489, 7492.475526713296, 7295.306945840137, 7326.230745771831, 7332.5967071811565, 7334.229905859808, 7342.192358840416, 7760.456768026184, 7767.495112817649, 7772.727954521564, 7778.455508114378, 7783.182765809508, 4617.12274667812, 4658.650601204856, 4664.323673067454, 4682.063587282625, 4687.507811679725, 7152.892706609301, 7401.851962960818, 7403.296686814744, 7432.665530051268, 7442.441816863865, 7088.468364872552, 7150.96424517874, 7155.350771106669, 7159.140903091662, 7162.677679504331, 7116.323949224816, 7135.678894449658, 7137.9649950757885, 7140.250273721184, 7147.606881621561, 5597.244372009726, 6406.327335629878, 6941.791746247621, 6955.478339124687, 6964.129483814523, 6854.8286292841085, 7094.787156385317, 7102.325279098477, 7125.714971401086, 7213.60325182099, 5546.473085056913, 6651.43462805667, 6865.656717164222, 6980.092587963532, 6984.4063254623925], "value": 5185.832605696326, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [665.8335416145964, 670.5674839728374, 690.5269890125264, 727.3090563964236, 730.6127752291284, 14866.182359970197, 15099.6980060399, 15222.238888055594, 15257.542273731779, 15261.00803447775, 12494.375281235925, 13172.538961136363, 13695.315234238298, 13935.860641393609, 13953.953453491004, 721.6752461392368, 740.6185814305637, 771.1632878327022, 878.767126711422, 895.9596818143183, 918.2699753695795, 920.0, 921.7741653294952, 923.7829110159107, 923.8152369526088, 955.5508910811907, 961.5961296255555, 962.8122516109362, 963.1476143612883, 966.632679581758, 737.8376757113426, 847.2967437027253, 935.9064093590621, 942.3168203052801, 946.0066929723763, 702.7961891051589, 703.63762662229, 705.4391758551964, 706.7067167125637, 709.9609521476317, 12137.332446715427, 14802.62993425173, 14848.628784280372, 14863.405463781435, 14880.107193568312, 12479.625611231677, 12574.497020119185, 12631.873681263161, 12754.489820407172, 12832.80750788739, 10015.248856335804, 10151.822570489103, 10284.691459256159, 10522.158227341793, 10541.947290263577, 3447.4486481083363, 3514.9824250878837, 3521.7358698097746, 3556.893293201208, 3759.2105657811894, 76257.0, 77354.0, 77593.22406775973, 77656.0, 83777.0, 9485.098915603006, 9645.565949532287, 9652.951735241279, 9689.467079310565, 9785.412875227508, 20436.693449598268, 20450.79549204489, 20470.181192752123, 20480.795192048186, 20493.79506204919, 7098.3256590623805, 7513.812154696177, 7522.511036782592, 7597.088349398107, 7696.461247712718, 4087.3868919661695, 4143.834246630161, 4177.352510360899, 4244.830206791756, 4245.61789438952, 3584.1039740064984, 3586.318599466093, 3639.5996440391536, 3643.890683279506, 3648.051506608248, 517.5755880178293, 538.21689441862, 649.1172006071739, 659.1134923527848, 674.8414122681166, 536.8382997479209, 576.6223123853882, 589.4518098168659, 610.3042531514078, 613.5796979069352, 6858.931410685929, 7826.099998500132, 8073.717419890276, 8109.797255068553, 8187.4678145920325, 534.794104269859, 595.7736060297059, 597.7673389888762, 609.0590038390696, 629.1946308724839, 2858.9285267868054, 3758.8684396046006, 3776.527934008249, 3957.5154932945284, 4009.859654912064, 83252.0, 84025.57987210025, 84235.0, 84532.15467845366, 84579.30841383137, 73380.63309683418, 73891.52216955631, 73975.52048958991, 75338.24661753312, 77901.61049194718, 67501.3249867505, 68275.3172468279, 71303.57392852218, 71883.0, 72482.0, 8373.832523349378, 8430.927283004332, 8464.53445060503, 8538.573071346533, 8549.0, 45616.0, 46206.537934620224, 46970.0, 48560.757196213795, 48850.893638295216, 60575.69712151411, 65051.02423463557, 66030.0, 66136.0, 67144.0, 10832.97086776755, 10964.835527467254, 11081.058110060412, 11150.2681842949, 11412.543498259976, 11571.19001669875, 11623.360715160747, 11655.30068195911, 11721.413929303671, 12403.813942790684, 8982.775430614287, 9252.352335336462, 10944.398058106563, 11044.668659940215, 11502.597409090642, 11058.281211721212, 11084.722881928017, 11089.27919685218, 11180.496877640688, 12105.636830895088, 10722.571097156339, 10960.409641563632, 11064.0, 11091.38997355121, 11354.148438866952, 9027.232685221566, 9083.909160908306, 9180.586873590571, 9267.0, 9273.258139348702, 9240.306976976619, 9278.539536865666, 9297.30270229722, 9440.10319019692, 9544.85682714746], "value": 6758.008611642716, "unit": "score"}], "name": "dromaeo_css", "value": 7430.404417387644, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 119.0, 113.0, 112.0, 113.0, 115.0, 113.0, 114.0, 134.0], "value": 116.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 124.0, 160.0, 187.0, 152.0, 145.0, 121.0, 152.0, 159.0, 115.0], "value": 147.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 188.0, 187.0, 185.0, 185.0, 184.0, 185.0, 186.0, 185.0], "value": 185.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 79.0, 75.0, 154.0, 57.0, 55.0, 58.0, 65.0, 135.0, 56.0], "value": 80.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 142.0, 77.0, 82.0, 88.0, 125.0, 78.0, 77.0, 124.0], "value": 97.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 89.0, 85.0, 87.0, 84.0, 89.0, 84.0, 86.0, 85.0], "value": 86.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 95.0, 94.0, 93.0, 94.0, 92.0, 95.0, 93.0, 94.0], "value": 93.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 90.0, 87.0, 91.0, 90.0, 91.0, 90.0, 90.0, 84.0], "value": 89.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 65.0, 63.0, 64.0, 65.0, 67.0, 82.0, 59.0, 58.0], "value": 64.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 67.0, 75.0, 78.0, 72.0, 68.0, 71.0, 68.0, 70.0], "value": 70.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 92.0, 105.0, 103.0, 77.0, 81.0, 81.0, 79.0, 82.0, 86.0], "value": 86.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 127.0, 119.0, 129.0, 126.0, 128.0, 127.0, 125.0, 151.0, 143.0], "value": 130.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 168.0, 167.0, 171.0, 166.0, 164.0, 167.0, 168.0, 167.0, 162.0], "value": 166.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 67.0, 68.0, 67.0, 67.0, 66.0, 70.0, 66.0, 65.0], "value": 67.1, "unit": "score"}], "name": "kraken", "value": 1481.2, "alertThreshold": 2.0}]} 14:20:37 INFO - Return code: 0 14:20:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 14:20:37 ERROR - # TBPL SUCCESS # 14:20:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:20:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:20:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:20:37 INFO - 2016-05-18 14:20:37.155 screenresolution[1949:14219] starting screenresolution argv=screenresolution get 14:20:37 INFO - 2016-05-18 14:20:37.159 screenresolution[1949:14219] Display 0: 1600x1200x32@60 14:20:37 INFO - 2016-05-18 14:20:37.165 screenresolution[1950:14222] starting screenresolution argv=screenresolution list 14:20:37 INFO - Available Modes on Display 0 14:20:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:20:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:20:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:20:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:20:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:20:37 INFO - Intel Iris: 14:20:37 INFO - Chipset Model: Intel Iris 14:20:37 INFO - Type: GPU 14:20:37 INFO - Bus: Built-In 14:20:37 INFO - VRAM (Dynamic, Max): 1536 MB 14:20:37 INFO - Vendor: Intel (0x8086) 14:20:37 INFO - Device ID: 0x0a2e 14:20:37 INFO - Revision ID: 0x0009 14:20:37 INFO - Displays: 14:20:37 INFO - Display: 14:20:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:20:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:20:37 INFO - Display Serial Number: 1600x1200 60 14:20:37 INFO - Main Display: Yes 14:20:37 INFO - Mirror: Off 14:20:37 INFO - Online: Yes 14:20:37 INFO - Rotation: Supported 14:20:37 INFO - Return code: 0 14:20:37 INFO - Running post-action listener: _resource_record_post_action 14:20:37 INFO - Running post-run listener: _resource_record_post_run 14:20:38 INFO - Total resource usage - Wall time: 1206s; CPU: 52.0%; Read bytes: 770048; Write bytes: 651175936; Read time: 9; Write time: 1935 14:20:38 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 352860672; Write bytes: 347461632; Read time: 27429; Write time: 629 14:20:38 INFO - run-tests - Wall time: 1175s; CPU: 52.0%; Read bytes: 737280; Write bytes: 293974016; Read time: 9; Write time: 1266 14:20:38 INFO - Running post-run listener: _upload_blobber_files 14:20:38 INFO - Blob upload gear active. 14:20:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:20:38 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 14:20:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:20:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:20:38 INFO - (blobuploader) - INFO - Open directory for files ... 14:20:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:20:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:20:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:20:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:20:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:20:40 INFO - (blobuploader) - INFO - Done attempting. 14:20:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:20:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:20:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:20:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:20:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:20:41 INFO - (blobuploader) - INFO - Done attempting. 14:20:41 INFO - (blobuploader) - INFO - Iteration through files over. 14:20:41 INFO - Return code: 0 14:20:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:20:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:20:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c7c63fca6fb365e1dd8473c3921f29386cfa7ab83b2354260a1d26e9eb5fed4168cbc928cf168d2ac517c043c99757f3e738d038a92b57ce9da94607b420acf9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f7812b59bbd4ed2cbfebed81df0e427cf458c08d5b06d811779aa7a1ade8d8c2d1d27440adbd135a3c43a707c93420b4f67b562527463fad3036174810ea48b4"} 14:20:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:20:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:20:41 INFO - Contents: 14:20:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c7c63fca6fb365e1dd8473c3921f29386cfa7ab83b2354260a1d26e9eb5fed4168cbc928cf168d2ac517c043c99757f3e738d038a92b57ce9da94607b420acf9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f7812b59bbd4ed2cbfebed81df0e427cf458c08d5b06d811779aa7a1ade8d8c2d1d27440adbd135a3c43a707c93420b4f67b562527463fad3036174810ea48b4"} 14:20:41 INFO - Running post-run listener: copy_logs_to_upload_dir 14:20:41 INFO - Copying logs to upload dir... 14:20:41 INFO - mkdir: /builds/slave/test/build/upload/logs 14:20:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.417783 ========= master_lag: 0.37 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2016-05-18 14:20:41.559051) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 14:20:41.562534) ========= 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.kg4zanXkwl/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.AyVD57Unf6/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c7c63fca6fb365e1dd8473c3921f29386cfa7ab83b2354260a1d26e9eb5fed4168cbc928cf168d2ac517c043c99757f3e738d038a92b57ce9da94607b420acf9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f7812b59bbd4ed2cbfebed81df0e427cf458c08d5b06d811779aa7a1ade8d8c2d1d27440adbd135a3c43a707c93420b4f67b562527463fad3036174810ea48b4"} build_url:https://queue.taskcluster.net/v1/task/ADA-zJSnREq6u-PlaeBcfg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010856 build_url: 'https://queue.taskcluster.net/v1/task/ADA-zJSnREq6u-PlaeBcfg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c7c63fca6fb365e1dd8473c3921f29386cfa7ab83b2354260a1d26e9eb5fed4168cbc928cf168d2ac517c043c99757f3e738d038a92b57ce9da94607b420acf9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f7812b59bbd4ed2cbfebed81df0e427cf458c08d5b06d811779aa7a1ade8d8c2d1d27440adbd135a3c43a707c93420b4f67b562527463fad3036174810ea48b4"}' ========= master_lag: 0.29 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 14:20:41.861471) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 14:20:41.862868) ========= 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.kg4zanXkwl/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.AyVD57Unf6/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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.005111 ========= master_lag: 0.13 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 14:20:42.001095) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 14:20:42.001410) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 14:20:42.006585) ========= ========= Total master_lag: 0.88 =========