builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0088 starttime: 1460670485.27 results: success (0) buildid: 20160414135042 builduid: 01a52fe40b6e427992a2beb9730695b5 revision: 73965b0ca49cbeae82ab392a3e12bc2c4024efc5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-14 14:48:05.266538) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-14 14:48:05.266930) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 4 secs) (at 2016-04-14 14:48:05.267205) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 4 secs) (at 2016-04-14 14:48:09.660743) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-14 14:48:09.661019) ========= 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.j9cIY6cGjj/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.4bVxM6Ivwh/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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-04-14 14:48:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.11M=0.001s 2016-04-14 14:48:10 (8.11 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.604954 ========= master_lag: 2.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-14 14:48:12.312582) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 14:48:12.312900) ========= 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.j9cIY6cGjj/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.4bVxM6Ivwh/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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.019611 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 14:48:12.386683) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-14 14:48:12.386992) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 73965b0ca49cbeae82ab392a3e12bc2c4024efc5 --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 73965b0ca49cbeae82ab392a3e12bc2c4024efc5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.j9cIY6cGjj/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.4bVxM6Ivwh/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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-04-14 14:48:12,468 truncating revision to first 12 chars 2016-04-14 14:48:12,468 Setting DEBUG logging. 2016-04-14 14:48:12,468 attempt 1/10 2016-04-14 14:48:12,468 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/73965b0ca49c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-14 14:48:13,573 unpacking tar archive at: mozilla-aurora-73965b0ca49c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.350305 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-14 14:48:13.797044) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-14 14:48:13.800363) ========= script_repo_revision: 73965b0ca49cbeae82ab392a3e12bc2c4024efc5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-14 14:48:13.800745) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-14 14:48:13.801006) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-14 14:48:13.875141) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-04-14 14:48:13.875595) ========= /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.j9cIY6cGjj/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.4bVxM6Ivwh/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 14:48:14 INFO - MultiFileLogger online at 20160414 14:48:14 in /builds/slave/test 14:48:14 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 14:48:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:48:14 INFO - {'append_to_log': False, 14:48:14 INFO - 'base_work_dir': '/builds/slave/test', 14:48:14 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 14:48:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:48:14 INFO - 'branch': 'Mozilla-Aurora', 14:48:14 INFO - 'buildbot_json_path': 'buildprops.json', 14:48:14 INFO - 'config_files': ('talos/mac_config.py',), 14:48:14 INFO - 'default_actions': ('clobber', 14:48:14 INFO - 'read-buildbot-config', 14:48:14 INFO - 'download-and-extract', 14:48:14 INFO - 'populate-webroot', 14:48:14 INFO - 'create-virtualenv', 14:48:14 INFO - 'install', 14:48:14 INFO - 'run-tests'), 14:48:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:48:14 INFO - 'download_minidump_stackwalk': True, 14:48:14 INFO - 'download_symbols': 'ondemand', 14:48:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:48:14 INFO - 'tooltool.py': '/tools/tooltool.py', 14:48:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:48:14 INFO - '/tools/misc-python/virtualenv.py')}, 14:48:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:48:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:48:14 INFO - 'installer_path': 'installer.exe', 14:48:14 INFO - 'log_level': 'info', 14:48:14 INFO - 'log_name': 'talos', 14:48:14 INFO - 'log_to_console': True, 14:48:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:48:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:48:14 INFO - 'opt_config_files': (), 14:48:14 INFO - 'pip_index': False, 14:48:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:48:14 INFO - 'cmd': ('bash', 14:48:14 INFO - '-c', 14:48:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:48:14 INFO - 'enabled': True, 14:48:14 INFO - 'halt_on_failure': False, 14:48:14 INFO - 'name': 'check_screen_resolution'},), 14:48:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:48:14 INFO - 'cmd': ('bash', 14:48:14 INFO - '-c', 14:48:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:48:14 INFO - 'enabled': True, 14:48:14 INFO - 'halt_on_failure': False, 14:48:14 INFO - 'name': 'check_screen_resolution'},), 14:48:14 INFO - 'run_cmd_checks_enabled': True, 14:48:14 INFO - 'sps_profile': False, 14:48:14 INFO - 'sps_profile_interval': 0, 14:48:14 INFO - 'suite': 'dromaeojs', 14:48:14 INFO - 'system_bits': '32', 14:48:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:48:14 INFO - 'title': 't-yosemite-r7-0088', 14:48:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:48:14 INFO - 'use_talos_json': True, 14:48:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:48:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:48:14 INFO - 'work_dir': 'build'} 14:48:14 INFO - ##### 14:48:14 INFO - ##### Running clobber step. 14:48:14 INFO - ##### 14:48:14 INFO - Running pre-action listener: _resource_record_pre_action 14:48:14 INFO - Running main action method: clobber 14:48:14 INFO - rmtree: /builds/slave/test/build 14:48:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:48:15 INFO - Running post-action listener: _resource_record_post_action 14:48:15 INFO - ##### 14:48:15 INFO - ##### Running read-buildbot-config step. 14:48:15 INFO - ##### 14:48:15 INFO - Running pre-action listener: _resource_record_pre_action 14:48:15 INFO - Running main action method: read_buildbot_config 14:48:15 INFO - Using buildbot properties: 14:48:15 INFO - { 14:48:15 INFO - "project": "", 14:48:15 INFO - "product": "firefox", 14:48:15 INFO - "script_repo_revision": "production", 14:48:15 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 14:48:15 INFO - "repository": "", 14:48:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 14:48:15 INFO - "buildid": "20160414135042", 14:48:15 INFO - "pgo_build": "False", 14:48:15 INFO - "basedir": "/builds/slave/test", 14:48:15 INFO - "buildnumber": 276, 14:48:15 INFO - "slavename": "t-yosemite-r7-0088", 14:48:15 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 14:48:15 INFO - "platform": "yosemite_r7", 14:48:15 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 14:48:15 INFO - "branch": "mozilla-aurora", 14:48:15 INFO - "repo_path": "releases/mozilla-aurora", 14:48:15 INFO - "revision": "73965b0ca49cbeae82ab392a3e12bc2c4024efc5", 14:48:15 INFO - "stage_platform": "macosx64", 14:48:15 INFO - "builduid": "01a52fe40b6e427992a2beb9730695b5", 14:48:15 INFO - "slavebuilddir": "test" 14:48:15 INFO - } 14:48:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/HGsVpuGcQnGqV1d1aW18CA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg. 14:48:15 INFO - Running post-action listener: _resource_record_post_action 14:48:15 INFO - ##### 14:48:15 INFO - ##### Running download-and-extract step. 14:48:15 INFO - ##### 14:48:15 INFO - Running pre-action listener: _resource_record_pre_action 14:48:15 INFO - Running main action method: download_and_extract 14:48:15 INFO - mkdir: /builds/slave/test/build/tests 14:48: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:48:15 INFO - https://queue.taskcluster.net/v1/task/HGsVpuGcQnGqV1d1aW18CA/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 14:48:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGsVpuGcQnGqV1d1aW18CA/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 14:48:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGsVpuGcQnGqV1d1aW18CA/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 14:48:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGsVpuGcQnGqV1d1aW18CA/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 14:48:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGsVpuGcQnGqV1d1aW18CA/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json'}, attempt #1 14:48:17 INFO - Downloaded 1277 bytes. 14:48:17 INFO - Reading from file /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 14:48:17 INFO - Using the following test package requirements: 14:48:17 INFO - {u'common': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 14:48:17 INFO - u'cppunittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 14:48:17 INFO - u'firefox-47.0a2.en-US.mac.cppunittest.tests.zip'], 14:48:17 INFO - u'gtest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 14:48:17 INFO - u'firefox-47.0a2.en-US.mac.gtest.tests.zip'], 14:48:17 INFO - u'jittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 14:48:17 INFO - u'jsshell-mac.zip'], 14:48:17 INFO - u'mochitest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 14:48:17 INFO - u'firefox-47.0a2.en-US.mac.mochitest.tests.zip'], 14:48:17 INFO - u'mozbase': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 14:48:17 INFO - u'reftest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 14:48:17 INFO - u'firefox-47.0a2.en-US.mac.reftest.tests.zip'], 14:48:17 INFO - u'talos': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 14:48:17 INFO - u'firefox-47.0a2.en-US.mac.talos.tests.zip'], 14:48:17 INFO - u'web-platform': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 14:48:17 INFO - u'firefox-47.0a2.en-US.mac.web-platform.tests.zip'], 14:48:17 INFO - u'webapprt': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 14:48:17 INFO - u'xpcshell': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 14:48:17 INFO - u'firefox-47.0a2.en-US.mac.xpcshell.tests.zip']} 14:48:17 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip'] for test suite category: common 14:48:17 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:48:17 INFO - https://queue.taskcluster.net/v1/task/HGsVpuGcQnGqV1d1aW18CA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:48:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGsVpuGcQnGqV1d1aW18CA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 14:48:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGsVpuGcQnGqV1d1aW18CA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 14:48:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGsVpuGcQnGqV1d1aW18CA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 14:48:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGsVpuGcQnGqV1d1aW18CA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 14:48:18 INFO - Downloaded 17910948 bytes. 14:48:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:48:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 14:48:21 INFO - Return code: 0 14:48:21 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip', u'firefox-47.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 14:48: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:48:21 INFO - https://queue.taskcluster.net/v1/task/HGsVpuGcQnGqV1d1aW18CA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:48:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGsVpuGcQnGqV1d1aW18CA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 14:48:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGsVpuGcQnGqV1d1aW18CA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 14:48:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGsVpuGcQnGqV1d1aW18CA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 14:48:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGsVpuGcQnGqV1d1aW18CA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 14:48:23 INFO - Downloaded 17910948 bytes. 14:48:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:48:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 14:48:27 INFO - Return code: 0 14:48:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:48:27 INFO - https://queue.taskcluster.net/v1/task/HGsVpuGcQnGqV1d1aW18CA/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 14:48:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGsVpuGcQnGqV1d1aW18CA/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 14:48:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGsVpuGcQnGqV1d1aW18CA/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 14:48:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGsVpuGcQnGqV1d1aW18CA/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip 14:48:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGsVpuGcQnGqV1d1aW18CA/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip'}, attempt #1 14:48:28 INFO - Downloaded 10845168 bytes. 14:48:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:48:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 14:48:28 INFO - Return code: 0 14:48:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:48:28 INFO - https://queue.taskcluster.net/v1/task/HGsVpuGcQnGqV1d1aW18CA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 14:48:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGsVpuGcQnGqV1d1aW18CA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 14:48:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGsVpuGcQnGqV1d1aW18CA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 14:48:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGsVpuGcQnGqV1d1aW18CA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg 14:48:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGsVpuGcQnGqV1d1aW18CA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg'}, attempt #1 14:48:32 INFO - Downloaded 115193570 bytes. 14:48:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HGsVpuGcQnGqV1d1aW18CA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 14:48:32 INFO - mkdir: /builds/slave/test/properties 14:48:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:48:32 INFO - Writing to file /builds/slave/test/properties/build_url 14:48:32 INFO - Contents: 14:48:32 INFO - build_url:https://queue.taskcluster.net/v1/task/HGsVpuGcQnGqV1d1aW18CA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 14:48:34 INFO - Running post-action listener: _resource_record_post_action 14:48:34 INFO - Running post-action listener: set_extra_try_arguments 14:48:34 INFO - ##### 14:48:34 INFO - ##### Running populate-webroot step. 14:48:34 INFO - ##### 14:48:34 INFO - Running pre-action listener: _resource_record_pre_action 14:48:34 INFO - Running main action method: populate_webroot 14:48:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:48:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:48:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:48:34 INFO - '--tppagecycles', 14:48:34 INFO - '7'], 14:48:34 INFO - 'tests': ['tsvgm']}}, 14:48:34 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 14:48:34 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 14:48:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:48:34 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 14:48:34 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 14:48:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:48:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:48:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:48:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:48:34 INFO - 'talos_options': ['--e10s'], 14:48:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:48:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:48:34 INFO - 'tests': ['damp', 'tps']}, 14:48:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:48:34 INFO - 'talos_options': ['--e10s'], 14:48:34 INFO - 'tests': ['damp', 'tps']}, 14:48:34 INFO - 'g3': {'tests': ['dromaeo_dom']}, 14:48:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:48:34 INFO - 'other': {'tests': ['a11yr', 14:48:34 INFO - 'ts_paint', 14:48:34 INFO - 'tpaint', 14:48:34 INFO - 'sessionrestore', 14:48:34 INFO - 'sessionrestore_no_auto_restore', 14:48:34 INFO - 'tabpaint']}, 14:48:34 INFO - 'other-e10s': {'talos_options': ['--e10s'], 14:48:34 INFO - 'tests': ['a11yr', 14:48:34 INFO - 'ts_paint', 14:48:34 INFO - 'tpaint', 14:48:34 INFO - 'sessionrestore', 14:48:34 INFO - 'sessionrestore_no_auto_restore', 14:48:34 INFO - 'tabpaint']}, 14:48:34 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 14:48:34 INFO - 'tests': ['a11yr', 14:48:34 INFO - 'ts_paint', 14:48:34 INFO - 'tpaint', 14:48:34 INFO - 'sessionrestore', 14:48:34 INFO - 'sessionrestore_no_auto_restore', 14:48:34 INFO - 'tabpaint']}, 14:48:34 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 14:48:34 INFO - 'tests': ['a11yr', 14:48:34 INFO - 'ts_paint', 14:48:34 INFO - 'tpaint', 14:48:34 INFO - 'sessionrestore', 14:48:34 INFO - 'sessionrestore_no_auto_restore', 14:48:34 INFO - 'tabpaint']}, 14:48:34 INFO - 'other_l64': {'tests': ['a11yr', 14:48:34 INFO - 'ts_paint', 14:48:34 INFO - 'tpaint', 14:48:34 INFO - 'sessionrestore', 14:48:34 INFO - 'sessionrestore_no_auto_restore', 14:48:34 INFO - 'tabpaint']}, 14:48:34 INFO - 'other_nol64': {'tests': ['a11yr', 14:48:34 INFO - 'ts_paint', 14:48:34 INFO - 'tpaint', 14:48:34 INFO - 'sessionrestore', 14:48:34 INFO - 'sessionrestore_no_auto_restore', 14:48:34 INFO - 'tabpaint']}, 14:48:34 INFO - 'svgr': {'tests': ['tsvgx', 14:48:34 INFO - 'tsvgr_opacity', 14:48:34 INFO - 'tart', 14:48:34 INFO - 'tscrollx', 14:48:34 INFO - 'cart']}, 14:48:34 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 14:48:34 INFO - 'tests': ['tsvgx', 14:48:34 INFO - 'tsvgr_opacity', 14:48:34 INFO - 'tart', 14:48:34 INFO - 'tscrollx', 14:48:34 INFO - 'cart']}, 14:48:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:48:34 INFO - 'tests': ['tp5o']}, 14:48:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:48:34 INFO - 'talos_options': ['--e10s'], 14:48:34 INFO - 'tests': ['tp5o']}, 14:48:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:48:34 INFO - 'talos_options': ['--xperf_path', 14:48:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:48:34 INFO - 'tests': ['tp5n']}, 14:48:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:48:34 INFO - 'talos_options': ['--e10s', 14:48:34 INFO - '--xperf_path', 14:48:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:48:34 INFO - 'tests': ['tp5n']}}, 14:48:34 INFO - 'talos.zip': {'path': '', 14:48:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:48:34 INFO - Running post-action listener: _resource_record_post_action 14:48:34 INFO - ##### 14:48:34 INFO - ##### Running create-virtualenv step. 14:48:34 INFO - ##### 14:48:34 INFO - Running pre-action listener: _resource_record_pre_action 14:48:34 INFO - Running main action method: create_virtualenv 14:48:34 INFO - Creating virtualenv /builds/slave/test/build/venv 14:48:34 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 14:48:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:48:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:48:34 INFO - Using real prefix '/tools/python27' 14:48:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:48:35 INFO - Installing distribute.............................................................................................................................................................................................done. 14:48:37 INFO - Installing pip.................done. 14:48:37 INFO - Return code: 0 14:48:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:48:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:48:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:48:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:48:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:48:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102532a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025394c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f820ad97d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023d6d30>, '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 0x10223adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023cdb20>, '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.j9cIY6cGjj/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.4bVxM6Ivwh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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:48:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:48:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:48:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j9cIY6cGjj/Render', 14:48:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:48:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:48:37 INFO - 'HOME': '/Users/cltbld', 14:48:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:48:37 INFO - 'LOGNAME': 'cltbld', 14:48:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:48:37 INFO - 'MOZ_NO_REMOTE': '1', 14:48:37 INFO - 'NO_EM_RESTART': '1', 14:48:37 INFO - 'PAGER': '/bin/cat', 14:48:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:48:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:48:37 INFO - 'PWD': '/builds/slave/test', 14:48:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:48:37 INFO - 'SHELL': '/bin/bash', 14:48:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4bVxM6Ivwh/Listeners', 14:48:37 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 14:48:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:48:37 INFO - 'USER': 'cltbld', 14:48:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:48:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:48:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:48:37 INFO - 'XPC_FLAGS': '0x0', 14:48:37 INFO - 'XPC_SERVICE_NAME': '0', 14:48:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:48:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:48:38 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 14:48:38 INFO - Cleaning up... 14:48:38 INFO - Return code: 0 14:48:38 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 14:48: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')]} 14:48:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:48:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48: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')]} 14:48:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:48:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:38 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 0x102532a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025394c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f820ad97d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023d6d30>, '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 0x10223adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023cdb20>, '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.j9cIY6cGjj/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.4bVxM6Ivwh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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:48:38 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 14:48:38 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 14:48:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j9cIY6cGjj/Render', 14:48:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:48:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:48:38 INFO - 'HOME': '/Users/cltbld', 14:48:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:48:38 INFO - 'LOGNAME': 'cltbld', 14:48:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:48:38 INFO - 'MOZ_NO_REMOTE': '1', 14:48:38 INFO - 'NO_EM_RESTART': '1', 14:48:38 INFO - 'PAGER': '/bin/cat', 14:48:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:48:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:48:38 INFO - 'PWD': '/builds/slave/test', 14:48:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:48:38 INFO - 'SHELL': '/bin/bash', 14:48:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4bVxM6Ivwh/Listeners', 14:48:38 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 14:48:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:48:38 INFO - 'USER': 'cltbld', 14:48:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:48:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:48:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:48:38 INFO - 'XPC_FLAGS': '0x0', 14:48:38 INFO - 'XPC_SERVICE_NAME': '0', 14:48:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:48:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:48:38 INFO - Downloading/unpacking psutil>=0.7.1 14:48:38 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:48:38 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:48:38 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:48:38 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:48:38 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:48:38 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:48:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:48:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:48:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:48:41 INFO - Installing collected packages: psutil 14:48:41 INFO - Running setup.py install for psutil 14:48:41 INFO - building 'psutil._psutil_osx' extension 14:48:41 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:48:42 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:48:42 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:48:42 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:48:42 INFO - building 'psutil._psutil_posix' extension 14:48:42 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:48:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:48:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:48:42 INFO - ^ 14:48:42 INFO - 1 warning generated. 14:48:42 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:48:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:48:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:48:42 INFO - Successfully installed psutil 14:48:42 INFO - Cleaning up... 14:48:42 INFO - Return code: 0 14:48:42 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 14:48:42 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:48:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:48:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:42 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:48:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:48:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:42 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 0x102532a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025394c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f820ad97d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023d6d30>, '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 0x10223adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023cdb20>, '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.j9cIY6cGjj/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.4bVxM6Ivwh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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:48:42 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:48:42 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:48:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j9cIY6cGjj/Render', 14:48:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:48:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:48:42 INFO - 'HOME': '/Users/cltbld', 14:48:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:48:42 INFO - 'LOGNAME': 'cltbld', 14:48:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:48:42 INFO - 'MOZ_NO_REMOTE': '1', 14:48:42 INFO - 'NO_EM_RESTART': '1', 14:48:42 INFO - 'PAGER': '/bin/cat', 14:48:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:48:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:48:42 INFO - 'PWD': '/builds/slave/test', 14:48:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:48:42 INFO - 'SHELL': '/bin/bash', 14:48:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4bVxM6Ivwh/Listeners', 14:48:42 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 14:48:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:48:42 INFO - 'USER': 'cltbld', 14:48:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:48:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:48:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:48:42 INFO - 'XPC_FLAGS': '0x0', 14:48:42 INFO - 'XPC_SERVICE_NAME': '0', 14:48:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:48:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:48:42 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 14:48:42 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:48:42 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:48:42 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:48:42 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:48:42 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:48:42 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:48:45 INFO - Downloading mozsystemmonitor-0.0.tar.gz 14:48:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:48:45 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:48:45 INFO - Installing collected packages: mozsystemmonitor 14:48:45 INFO - Running setup.py install for mozsystemmonitor 14:48:45 INFO - Successfully installed mozsystemmonitor 14:48:45 INFO - Cleaning up... 14:48:45 INFO - Return code: 0 14:48:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:48: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')]} 14:48:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:48:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48: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')]} 14:48:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:48:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:45 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 0x102532a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025394c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f820ad97d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023d6d30>, '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 0x10223adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023cdb20>, '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.j9cIY6cGjj/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.4bVxM6Ivwh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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:48:45 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:48:45 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:48:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j9cIY6cGjj/Render', 14:48:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:48:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:48:45 INFO - 'HOME': '/Users/cltbld', 14:48:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:48:45 INFO - 'LOGNAME': 'cltbld', 14:48:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:48:45 INFO - 'MOZ_NO_REMOTE': '1', 14:48:45 INFO - 'NO_EM_RESTART': '1', 14:48:45 INFO - 'PAGER': '/bin/cat', 14:48:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:48:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:48:45 INFO - 'PWD': '/builds/slave/test', 14:48:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:48:45 INFO - 'SHELL': '/bin/bash', 14:48:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4bVxM6Ivwh/Listeners', 14:48:45 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 14:48:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:48:45 INFO - 'USER': 'cltbld', 14:48:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:48:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:48:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:48:45 INFO - 'XPC_FLAGS': '0x0', 14:48:45 INFO - 'XPC_SERVICE_NAME': '0', 14:48:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:48:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:48:45 INFO - Downloading/unpacking blobuploader==1.2.4 14:48:45 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:48:45 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:48:45 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:48:45 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:48:45 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:48:45 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:48:48 INFO - Downloading blobuploader-1.2.4.tar.gz 14:48:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:48:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:48:48 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:48:48 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:48:48 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:48:48 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:48:48 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:48:48 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:48:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:48:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:48:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:48:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:48:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:48:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:48:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:48:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:48:49 INFO - Downloading docopt-0.6.1.tar.gz 14:48:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:48:49 INFO - Installing collected packages: blobuploader, requests, docopt 14:48:49 INFO - Running setup.py install for blobuploader 14:48:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:48:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:48:49 INFO - Running setup.py install for requests 14:48:50 INFO - Running setup.py install for docopt 14:48:50 INFO - Successfully installed blobuploader requests docopt 14:48:50 INFO - Cleaning up... 14:48:50 INFO - Return code: 0 14:48:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:48:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:48:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:48:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:48:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:48:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:50 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 0x102532a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025394c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f820ad97d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023d6d30>, '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 0x10223adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023cdb20>, '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.j9cIY6cGjj/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.4bVxM6Ivwh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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:48:50 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:48:50 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:48:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j9cIY6cGjj/Render', 14:48:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:48:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:48:50 INFO - 'HOME': '/Users/cltbld', 14:48:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:48:50 INFO - 'LOGNAME': 'cltbld', 14:48:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:48:50 INFO - 'MOZ_NO_REMOTE': '1', 14:48:50 INFO - 'NO_EM_RESTART': '1', 14:48:50 INFO - 'PAGER': '/bin/cat', 14:48:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:48:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:48:50 INFO - 'PWD': '/builds/slave/test', 14:48:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:48:50 INFO - 'SHELL': '/bin/bash', 14:48:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4bVxM6Ivwh/Listeners', 14:48:50 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 14:48:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:48:50 INFO - 'USER': 'cltbld', 14:48:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:48:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:48:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:48:50 INFO - 'XPC_FLAGS': '0x0', 14:48:50 INFO - 'XPC_SERVICE_NAME': '0', 14:48:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:48:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:48:50 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-bHNDsR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:48:50 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-eUXq2l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:48:50 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-_yXrHI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:48:51 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-ZOxKa3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:48:51 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-NEdHV_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:48:51 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-6rkOGP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:48:51 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-60azTz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:48:51 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-fF1ZwJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:48:51 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-kuYXpv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:48:51 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-h9qeSS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:48:51 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-ERglRw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:48:51 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-3Zj4ZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:48:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:48:52 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-gQzEjI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:48:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:48:52 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-fk1esM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:48:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:48:52 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-OkTwPH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:48:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:48:52 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-guXhFK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:48:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:48:52 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-HxBbA_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:48:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:48:52 INFO - Running setup.py install for manifestparser 14:48:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:48:52 INFO - Running setup.py install for mozcrash 14:48:52 INFO - Running setup.py install for mozdebug 14:48:52 INFO - Running setup.py install for mozdevice 14:48:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:48:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:48:53 INFO - Running setup.py install for mozfile 14:48:53 INFO - Running setup.py install for mozhttpd 14:48:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:48:53 INFO - Running setup.py install for mozinfo 14:48:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:48:53 INFO - Running setup.py install for mozInstall 14:48:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:48:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:48:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:48:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:48:53 INFO - Running setup.py install for mozleak 14:48:53 INFO - Running setup.py install for mozlog 14:48:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:48:54 INFO - Running setup.py install for moznetwork 14:48:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:48:54 INFO - Running setup.py install for mozprocess 14:48:54 INFO - Running setup.py install for mozprofile 14:48:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:48:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:48:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:48:54 INFO - Running setup.py install for mozrunner 14:48:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:48:54 INFO - Running setup.py install for mozscreenshot 14:48:54 INFO - Running setup.py install for moztest 14:48:54 INFO - Running setup.py install for mozversion 14:48:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:48:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:48:55 INFO - Cleaning up... 14:48:55 INFO - Return code: 0 14:48:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:48:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:48:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:48:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:48:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:48:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:55 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 0x102532a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025394c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f820ad97d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023d6d30>, '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 0x10223adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023cdb20>, '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.j9cIY6cGjj/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.4bVxM6Ivwh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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:48:55 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:48:55 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:48:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j9cIY6cGjj/Render', 14:48:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:48:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:48:55 INFO - 'HOME': '/Users/cltbld', 14:48:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:48:55 INFO - 'LOGNAME': 'cltbld', 14:48:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:48:55 INFO - 'MOZ_NO_REMOTE': '1', 14:48:55 INFO - 'NO_EM_RESTART': '1', 14:48:55 INFO - 'PAGER': '/bin/cat', 14:48:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:48:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:48:55 INFO - 'PWD': '/builds/slave/test', 14:48:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:48:55 INFO - 'SHELL': '/bin/bash', 14:48:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4bVxM6Ivwh/Listeners', 14:48:55 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 14:48:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:48:55 INFO - 'USER': 'cltbld', 14:48:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:48:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:48:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:48:55 INFO - 'XPC_FLAGS': '0x0', 14:48:55 INFO - 'XPC_SERVICE_NAME': '0', 14:48:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:48:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:48:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:48:55 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-DP5efV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:48:55 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:48:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:48:55 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-8pWUOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:48:55 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:48:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:48:55 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-23TAxC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:48:55 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:48:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:48:55 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-tVWrrN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:48:55 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:48:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:48:55 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-gmJEEj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:48:55 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:48:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:48:55 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-2rskm9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:48:56 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:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:48:56 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-Hf1LWR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:48:56 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:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:48:56 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-2dq1Mm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:48:56 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:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:48:56 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-dlrJll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:48:56 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:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:48:56 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-mekTfs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:48:56 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:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:48:56 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-EssaQD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:48:56 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:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:48:56 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-2XW3A1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:48:56 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:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:48:56 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-7Akcnj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:48:56 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:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:48:56 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-u5gOg3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:48:56 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:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:48:56 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-0AHmLl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:48:57 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:48:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:48:57 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-JGgFSg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:48:57 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:48:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:48:57 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-qtU3oi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:48:57 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:48:57 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:48:57 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:48:57 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:48:57 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:48:57 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:48:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:48:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:48:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:48:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:48:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:48:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:48:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:48:59 INFO - Downloading blessings-1.6.tar.gz 14:48:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:48:59 INFO - Installing collected packages: blessings 14:48:59 INFO - Running setup.py install for blessings 14:48:59 INFO - Successfully installed blessings 14:48:59 INFO - Cleaning up... 14:48:59 INFO - Return code: 0 14:49:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:49:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:49:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:49:00 INFO - Reading from file tmpfile_stdout 14:49:00 INFO - Current package versions: 14:49:00 INFO - blessings == 1.6 14:49:00 INFO - blobuploader == 1.2.4 14:49:00 INFO - docopt == 0.6.1 14:49:00 INFO - manifestparser == 1.1 14:49:00 INFO - mozInstall == 1.12 14:49:00 INFO - mozcrash == 0.17 14:49:00 INFO - mozdebug == 0.1 14:49:00 INFO - mozdevice == 0.48 14:49:00 INFO - mozfile == 1.2 14:49:00 INFO - mozhttpd == 0.7 14:49:00 INFO - mozinfo == 0.9 14:49:00 INFO - mozleak == 0.1 14:49:00 INFO - mozlog == 3.1 14:49:00 INFO - moznetwork == 0.27 14:49:00 INFO - mozprocess == 0.22 14:49:00 INFO - mozprofile == 0.28 14:49:00 INFO - mozrunner == 6.11 14:49:00 INFO - mozscreenshot == 0.1 14:49:00 INFO - mozsystemmonitor == 0.0 14:49:00 INFO - moztest == 0.7 14:49:00 INFO - mozversion == 1.4 14:49:00 INFO - psutil == 3.1.1 14:49:00 INFO - requests == 1.2.3 14:49:00 INFO - wsgiref == 0.1.2 14:49:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:49:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:49:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:49:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:49:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:49:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:00 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 0x102532a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025394c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f820ad97d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023d6d30>, '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 0x10223adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023cdb20>, '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.j9cIY6cGjj/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.4bVxM6Ivwh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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:49:00 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:49:00 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:49:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j9cIY6cGjj/Render', 14:49:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:49:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:49:00 INFO - 'HOME': '/Users/cltbld', 14:49:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:49:00 INFO - 'LOGNAME': 'cltbld', 14:49:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:49:00 INFO - 'MOZ_NO_REMOTE': '1', 14:49:00 INFO - 'NO_EM_RESTART': '1', 14:49:00 INFO - 'PAGER': '/bin/cat', 14:49:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:49:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:49:00 INFO - 'PWD': '/builds/slave/test', 14:49:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:49:00 INFO - 'SHELL': '/bin/bash', 14:49:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4bVxM6Ivwh/Listeners', 14:49:00 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 14:49:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:49:00 INFO - 'USER': 'cltbld', 14:49:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:49:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:49:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:49:00 INFO - 'XPC_FLAGS': '0x0', 14:49:00 INFO - 'XPC_SERVICE_NAME': '0', 14:49:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:49:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:49:00 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:49:00 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:49:00 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:49:00 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:49:00 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:49:00 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:49:00 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:49:00 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:49:00 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:49:00 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:49:00 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:49:00 INFO - Cleaning up... 14:49:00 INFO - Return code: 0 14:49:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:49:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:49:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:49:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:49:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:49:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:00 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 0x102532a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025394c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f820ad97d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023d6d30>, '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 0x10223adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023cdb20>, '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.j9cIY6cGjj/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.4bVxM6Ivwh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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:49:00 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:49:00 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:49:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j9cIY6cGjj/Render', 14:49:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:49:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:49:00 INFO - 'HOME': '/Users/cltbld', 14:49:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:49:00 INFO - 'LOGNAME': 'cltbld', 14:49:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:49:00 INFO - 'MOZ_NO_REMOTE': '1', 14:49:00 INFO - 'NO_EM_RESTART': '1', 14:49:00 INFO - 'PAGER': '/bin/cat', 14:49:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:49:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:49:00 INFO - 'PWD': '/builds/slave/test', 14:49:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:49:00 INFO - 'SHELL': '/bin/bash', 14:49:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4bVxM6Ivwh/Listeners', 14:49:00 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 14:49:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:49:00 INFO - 'USER': 'cltbld', 14:49:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:49:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:49:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:49:00 INFO - 'XPC_FLAGS': '0x0', 14:49:00 INFO - 'XPC_SERVICE_NAME': '0', 14:49:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:49:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:49:00 INFO - Downloading/unpacking jsonschema 14:49:00 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:49:00 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:49:00 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:49:00 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:49:00 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:49:00 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:49:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:49:03 INFO - Downloading/unpacking functools32 (from jsonschema) 14:49: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:49: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:49: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:49: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:49: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:49: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:49:03 INFO - Downloading functools32-3.2.3-2.tar.gz 14:49:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:49:03 INFO - warning: no files found matching '*.txt' 14:49:03 INFO - no previously-included directories found matching 'build' 14:49:03 INFO - no previously-included directories found matching 'dist' 14:49:03 INFO - no previously-included directories found matching '.git*' 14:49:03 INFO - Installing collected packages: jsonschema, functools32 14:49:03 INFO - Running setup.py install for functools32 14:49:03 INFO - warning: no files found matching '*.txt' 14:49:03 INFO - no previously-included directories found matching 'build' 14:49:03 INFO - no previously-included directories found matching 'dist' 14:49:03 INFO - no previously-included directories found matching '.git*' 14:49:03 INFO - Successfully installed jsonschema functools32 14:49:03 INFO - Cleaning up... 14:49:03 INFO - Return code: 0 14:49:03 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 14:49: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:49:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:49:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49: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:49:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:49:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102532a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025394c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f820ad97d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023d6d30>, '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 0x10223adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023cdb20>, '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.j9cIY6cGjj/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.4bVxM6Ivwh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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:49: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', 'flake8'] in /builds/slave/test/build 14:49: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 flake8 14:49:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j9cIY6cGjj/Render', 14:49:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:49:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:49:03 INFO - 'HOME': '/Users/cltbld', 14:49:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:49:03 INFO - 'LOGNAME': 'cltbld', 14:49:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:49:03 INFO - 'MOZ_NO_REMOTE': '1', 14:49:03 INFO - 'NO_EM_RESTART': '1', 14:49:03 INFO - 'PAGER': '/bin/cat', 14:49:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:49:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:49:03 INFO - 'PWD': '/builds/slave/test', 14:49:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:49:03 INFO - 'SHELL': '/bin/bash', 14:49:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4bVxM6Ivwh/Listeners', 14:49:03 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 14:49:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:49:03 INFO - 'USER': 'cltbld', 14:49:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:49:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:49:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:49:03 INFO - 'XPC_FLAGS': '0x0', 14:49:03 INFO - 'XPC_SERVICE_NAME': '0', 14:49:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:49:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:49:04 INFO - Downloading/unpacking flake8 14:49:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:49:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:49:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:49:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:49:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:49:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:49:06 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:49:06 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:49: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:49: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:49: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:49: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:49: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:49: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:49:06 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:49:06 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:49: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:49: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:49: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:49: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:49: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:49: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:49:07 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:49:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:49:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:49:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:49:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:49:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:49:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:49:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 14:49:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:49:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:49:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:49:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:49:07 INFO - no previously-included directories found matching 'docs/_build' 14:49:07 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:49:07 INFO - Running setup.py install for pep8 14:49:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:49:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:49:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:49:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:49:08 INFO - no previously-included directories found matching 'docs/_build' 14:49:08 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 14:49:08 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:49:08 INFO - Cleaning up... 14:49:08 INFO - Return code: 0 14:49:08 INFO - Running post-action listener: _resource_record_post_action 14:49:08 INFO - Running post-action listener: _start_resource_monitoring 14:49:08 INFO - Starting resource monitoring. 14:49:08 INFO - ##### 14:49:08 INFO - ##### Running install step. 14:49:08 INFO - ##### 14:49:08 INFO - Running pre-action listener: _resource_record_pre_action 14:49:08 INFO - Running main action method: install 14:49:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:49:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:49:08 INFO - Reading from file tmpfile_stdout 14:49:08 INFO - Detecting whether we're running mozinstall >=1.0... 14:49:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:49:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:49:08 INFO - Reading from file tmpfile_stdout 14:49:08 INFO - Output received: 14:49:08 INFO - Usage: mozinstall [options] installer 14:49:08 INFO - Options: 14:49:08 INFO - -h, --help show this help message and exit 14:49:08 INFO - -d DEST, --destination=DEST 14:49:08 INFO - Directory to install application into. [default: 14:49:08 INFO - "/builds/slave/test"] 14:49:08 INFO - --app=APP Application being installed. [default: firefox] 14:49:08 INFO - mkdir: /builds/slave/test/build/application 14:49:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:49:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 14:49:41 INFO - Reading from file tmpfile_stdout 14:49:41 INFO - Output received: 14:49:41 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 14:49:41 INFO - Running post-action listener: _resource_record_post_action 14:49:41 INFO - ##### 14:49:41 INFO - ##### Running run-tests step. 14:49:41 INFO - ##### 14:49:41 INFO - Running pre-action listener: _resource_record_pre_action 14:49:41 INFO - Running main action method: run_tests 14:49:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:49:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:49:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:49:41 INFO - 2016-04-14 14:49:41.195 screenresolution[2249:18189] starting screenresolution argv=screenresolution get 14:49:41 INFO - 2016-04-14 14:49:41.198 screenresolution[2249:18189] Display 0: 1600x1200x32@60 14:49:41 INFO - 2016-04-14 14:49:41.204 screenresolution[2250:18192] starting screenresolution argv=screenresolution list 14:49:41 INFO - Available Modes on Display 0 14:49:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:49:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:49:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:49:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:49:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:49:41 INFO - Intel Iris: 14:49:41 INFO - Chipset Model: Intel Iris 14:49:41 INFO - Type: GPU 14:49:41 INFO - Bus: Built-In 14:49:41 INFO - VRAM (Dynamic, Max): 1536 MB 14:49:41 INFO - Vendor: Intel (0x8086) 14:49:41 INFO - Device ID: 0x0a2e 14:49:41 INFO - Revision ID: 0x0009 14:49:41 INFO - Displays: 14:49:41 INFO - Display: 14:49:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:49:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:49:41 INFO - Display Serial Number: 1600x1200 60 14:49:41 INFO - Main Display: Yes 14:49:41 INFO - Mirror: Off 14:49:41 INFO - Online: Yes 14:49:41 INFO - Rotation: Supported 14:49:41 INFO - Return code: 0 14:49:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:49:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:49:41 INFO - Python 2.7.3 14:49:41 INFO - Return code: 0 14:49:41 INFO - grabbing minidump binary from tooltool 14:49:41 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:49:41 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 0x1023d6d30>, '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 0x10223adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023cdb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:49:41 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:49:41 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:49:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:49:41 INFO - Return code: 0 14:49:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:49:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:49:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:49:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:49:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:49:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 14:49:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 14:49:42 INFO - Return code: 0 14:49:42 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/HGsVpuGcQnGqV1d1aW18CA/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0088', '--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:49:42 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/HGsVpuGcQnGqV1d1aW18CA/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0088 --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:49:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j9cIY6cGjj/Render', 14:49:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:49:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:49:42 INFO - 'HOME': '/Users/cltbld', 14:49:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:49:42 INFO - 'LOGNAME': 'cltbld', 14:49:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:49:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:49:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:49:42 INFO - 'MOZ_NO_REMOTE': '1', 14:49:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:49:42 INFO - 'NO_EM_RESTART': '1', 14:49:42 INFO - 'PAGER': '/bin/cat', 14:49:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:49:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:49:42 INFO - 'PWD': '/builds/slave/test', 14:49:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:49:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:49:42 INFO - 'SHELL': '/bin/bash', 14:49:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4bVxM6Ivwh/Listeners', 14:49:42 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 14:49:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:49:42 INFO - 'USER': 'cltbld', 14:49:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:49:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:49:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:49:42 INFO - 'XPC_FLAGS': '0x0', 14:49:42 INFO - 'XPC_SERVICE_NAME': '0', 14:49:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:49:42 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/HGsVpuGcQnGqV1d1aW18CA/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0088', '--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:49:42 INFO - mozversion application_buildid: 20160414135042 14:49:42 INFO - mozversion application_changeset: 73965b0ca49cbeae82ab392a3e12bc2c4024efc5 14:49:42 INFO - mozversion application_display_name: FirefoxDeveloperEdition 14:49:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:49:42 INFO - mozversion application_name: Firefox 14:49:42 INFO - mozversion application_remotingname: firefox-dev 14:49:42 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:49:42 INFO - mozversion application_vendor: Mozilla 14:49:42 INFO - mozversion application_version: 47.0a2 14:49:42 INFO - mozversion platform_buildid: 20160414135042 14:49:42 INFO - mozversion platform_changeset: 73965b0ca49cbeae82ab392a3e12bc2c4024efc5 14:49:42 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:49:42 INFO - mozversion platform_version: 47.0a2 14:49:42 INFO - using testdate: 1460670582 14:49:42 INFO - actual date: 1460670582 14:49:42 INFO - starting webserver on 'localhost:52426' 14:49:42 INFO - SUITE-START | Running 2 tests 14:49:42 INFO - TEST-START | dromaeo_css 14:49:42 INFO - operating with platform_type : mac_ 14:49:42 INFO - Initialising browser for dromaeo_css test... 14:49:42 INFO - TEST-INFO | started process 2268 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/tmp28z2G4/profile http://localhost:52426/getInfo.html) 14:49:45 INFO - PROCESS | 2268 | __metrics Screen width/height:1600/1200 14:49:45 INFO - PROCESS | 2268 | colorDepth:24 14:49:45 INFO - PROCESS | 2268 | Browser inner width/height: 1024/689 14:49:45 INFO - PROCESS | 2268 | __metrics 14:49:45 INFO - PROCESS | 2268 | 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:49:45 INFO - PROCESS | 2268 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:49:45 INFO - TEST-INFO | 2268: exit 0 14:49:45 INFO - Browser initialized. 14:49:45 INFO - Running cycle 1/1 for dromaeo_css test... 14:49:45 INFO - TEST-INFO | started process 2270 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/tmp28z2G4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:49:57 INFO - PROCESS | 2270 | RSS: Main: 207400960 14:49:57 INFO - PROCESS | 2270 | 14:50:48 INFO - PROCESS | 2270 | 1460670648937 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:52:59 INFO - PROCESS | 2270 | Cycle 1(1): loaded http://localhost:52426/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52426/tests/dromaeo/cssquery-ext.html) 14:53:00 INFO - PROCESS | 2270 | RSS: Main: 314757120 14:53:00 INFO - PROCESS | 2270 | 14:56:02 INFO - PROCESS | 2270 | Cycle 1(1): loaded http://localhost:52426/tests/dromaeo/cssquery-ext.html (next: http://localhost:52426/tests/dromaeo/cssquery-jquery.html) 14:56:03 INFO - PROCESS | 2270 | RSS: Main: 348090368 14:56:03 INFO - PROCESS | 2270 | 14:59:05 INFO - PROCESS | 2270 | Cycle 1(1): loaded http://localhost:52426/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52426/tests/dromaeo/cssquery-mootools.html) 14:59:05 INFO - PROCESS | 2270 | RSS: Main: 349315072 14:59:05 INFO - PROCESS | 2270 | 15:02:07 INFO - PROCESS | 2270 | Cycle 1(1): loaded http://localhost:52426/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52426/tests/dromaeo/cssquery-prototype.html) 15:02:08 INFO - PROCESS | 2270 | RSS: Main: 309084160 15:02:08 INFO - PROCESS | 2270 | 15:05:11 INFO - PROCESS | 2270 | Cycle 1(1): loaded http://localhost:52426/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52426/tests/dromaeo/cssquery-yui.html) 15:05:12 INFO - PROCESS | 2270 | RSS: Main: 308559872 15:05:12 INFO - PROCESS | 2270 | 15:08:14 INFO - PROCESS | 2270 | Cycle 1(1): loaded http://localhost:52426/tests/dromaeo/cssquery-yui.html (next: http://localhost:52426/tests/dromaeo/cssquery-dojo.html) 15:08:15 INFO - PROCESS | 2270 | RSS: Main: 355328000 15:08:15 INFO - PROCESS | 2270 | 15:08:15 INFO - PROCESS | 2270 | __start_tp_report 15:08:15 INFO - PROCESS | 2270 | _x_x_mozilla_page_load 15:08:15 INFO - PROCESS | 2270 | _x_x_mozilla_page_load_details 15:08:15 INFO - PROCESS | 2270 | |i|pagename|runs| 15:08:15 INFO - PROCESS | 2270 | |0;dojo.html;3351.2627222011156;3433.742469314802;3437.484377343398;3455.8617655293788;3524.9471257931136;5637.408072152426;5667.518260947819;5669.234653321801;5682.289713785772;5697.173909783079;5660.094384898417;5688.459596338352;5696.829095127153;5706.1726049722765;5718.771249150029;5117;5173.172292433212;5194.740262986845;5280.498352656491;5298.814541491049;5246.239295302179;5268.683878967274;5276;5277.366715994086;5287.365516138049;5183.092958732225;5203.973980130094;5208.140656791625;5222.582193424536;5239.842804715865;4664.346991421204;5165.896682066356;5221.634485586011;5235.712035838027;5286.814961476368;5018.272350509155;5115.462876397962;5177.922331165036;5191.506806853343;5224.373075230958;5557.166425036277;5566.916496252559;5575.107982722786;5599.132134519156;5622.128570071604;6086.786962456293;6163.3836616338;6206.0380641000265;6215.316315205324;6216.129741836146;5232.6860388376435;6176.722047507873;6300.054991751274;6304.148939893102;6341.936580634181;5748.45389687979;5778.42215778423;5787.045137552341;5792.478676919098;5801.3328467225965;5392.89445663638;6205.28639206488;6220.191375121296;6308.526860485482;6326.240851097898;6112.1663500949535;6133.325334213233;6142.355052719489;6147.293061297919;6159.938400615936;6251.187345645037;6251.374862513803;6267.968660156761;6269.96865015672;6272.090546870678;5849.736761845727;5953.702314884238;6170.907436388457;6212.099245609361;6214.316425193225;6115.571909966259;6155.907661385083;6202.317745048041;6208.068789681584;6211.9068213976825;4850.296706977469;4852.684575502581;4855.975720121377;4858.854234372974;4870.464248932614;4338.58783415575;4396.340548917624;4399.1641588098155;4401.581849724271;4423.292273236267;5617.073182924853;5655.632383895034;5679.6876171810945;5702.45826646468;5706.914396284059;6211.65835879031;6226.501879849601;6226.750929962841;6241.188645476059;6247.968760156261;5524.005678977742;5550;5587.05020146576;5614.29821272341;5643.097104463266;4563.908721825611;5293.602979776532;5309.256704061435;5398.271233383522;5398.62209645321;6289;6322.905156422749;6325.430711236103;6328.493720502349;6338.080978258219;6117.188044311166;6270.959757266151;6299.874002520016;6301.4328710416285;6316.178896743578;6242.719077641426;6271;6323.620582765049;6329.8734025320155;6333.20834895638;5327.866803329948;6044.214252147192;6175.938240617536;6194.101855230876;6195.96902015487;6117.84705382369;6149.877002459836;6177.4749146323065;6201.410865967726;6251.835188843992;5962.19510366095;6058.878822423615;6077.6961151943115;6092.8781424372155;6111.960966635772;6038.7282572283475;6040.788572400121;6046.244219472566;6054;6054.091886217103;5760.6255593386295;5830.358660547421;5918.112283157561;5925.822225333247;5994.13085102649;6036.517078633787;6056.121862330026;6069;6080.422359875804;6090.482309003784;5859.648421094748;5863.853403664943;5897.203877476657;5900.173975643327;5931.347551769388 15:08:15 INFO - PROCESS | 2270 | |1;ext.html;495.56637941800926;594.1622312539321;614.2076721029869;658.6311665467339;664.8204984654144;31640.68359316406;39986.400203996964;40275.59724402755;40360.990975225635;40390.394144087826;43274;43829.90425239371;43848.56151438485;43905;44097.11805763883;9606.327557071008;9831.90168098317;9850.950745246264;9853.556589953469;9862.358946668479;9620.941696413389;9745.902540974606;9776.657816976407;9828.312018158696;9867.802643947116;9743;9903.207743380548;9917.950410247975;9919.603215871357;9926.503674816266;9832.508374581279;9891.950540247253;9899.703008909744;9905.158061564704;9919.80160396788;9761.170300524464;9889.703308900744;9892.55483503244;9894.604215831358;9903.306768526134;41994.1601167978;42509.57490425087;42515.574844251474;42525;42534.787326063175;3159.541866429378;3310.2717402171484;3733.328000959826;3806.238752249561;3933.567307596162;28022.299442513897;28336.291592710142;29559.70440295591;29617.851910740308;30245.697543024507;2701.2436517775054;2702.9053983110693;2720;2740.2464322311325;2766.9762187990573;505071;505628.47185764206;506477;506767.99999999633;508348;47934.52065479301;48683.51316486861;67124.6643766778;67568.66215668891;67742;46759.532404676196;47124.76437617859;47212.7639361801;47331.580052598474;47363;16745.665086698198;16889.746653800205;16934.322627094778;16934.49196524106;16934.661306773796;13320.533781317607;13381.933090334485;13407.329633518286;13414.396352164176;13417.262050587311;15263.694726105416;15459.995100318443;15484.380624775109;15496.225188740518;15643.139627320379;5599.796043850511;5643.915341269884;6645.435138013323;6669.366410191121;6766.458683305324;1009.3792317724547;1044.6552637629716;1059;1092.9726756831142;1104.8287515434959;23868.64197037046;30264.54603180954;30467.390652187274;30651;30755.538666920012;1192.7077865922731;1193.8806119387991;1215.6231568213882;1230.390956476532;1260.2690439545042;15097.924510377377;15420.537383878502;15464.458743943907;15628.899394987904;15703.057816531045;427696;533638;545661;549597;550441;61529.69235153885;62344;62436;62473.37526624766;62617;1431.3129697745142;1431.9856801431852;1531.4639876043518;1551.9844801552065;1572.1981789287545;28676.856615716788;29218;29416.852915735286;30273.545896812007;30423;46906;47286.76356618195;47310;47443.76278118588;47495.76252118717;293657;297011;297031.02968970034;297204;298130;29671.55492667655;29998.25004374821;34428.13929651678;34547.309053819285;34958.475622866186;42856.78571607122;43130;44585;44637.776811115735;44996.32505512486;37026.814865925495;37666.43500347552;37756.244875101795;37932.81033594815;38593.807030964665;37636.62363376441;37698;37804.62195378011;38195.85412437631;38477.61522384852;37163;37820.62179378171;38483.230335393695;38494.615053849106;38518.42222366723;27129.321766955985;32467;33512.83243583766;33528.49707254442;33584;25299;25387.492250154522;25405.74594254034;25738.613920791577;25826.741732582435 15:08:15 INFO - PROCESS | 2270 | |2;jquery.html;372.1477815801814;376.15177774119354;378.2491753868572;381.52690663577175;384.2142817937318;20273.493162670922;20644.864532450745;20719.689204661943;21706.99999999996;21749.67375489367;12087.576934807283;12301.815472767916;12419.751604967896;12421.751564968696;12437.378131093432;4788.28175773635;6031.487323577501;6038.396160383971;6040.244969378828;6046.727897244613;5929.673867937262;6016.067509536007;6043.788467403641;6045.03279475284;6053.273607167146;5878.323992740847;5975.0439929611275;5993.760249590011;5998.640081595118;6000.159977603117;5736.1682556029145;5943.435373639497;6036.8188954331;6049.364816694249;6053.969730151365;5329.493698098713;5343.064963631349;5359.356877174725;5362.1420572708175;5370.812021579265;14303.499377521737;14317.57047288583;14318.642033949234;19373;25859.74140258611;2114.3551216878845;2119.448943274744;2304.9308520744403;2442.7068751749725;2694.8787304571147;9832;9866.90133098667;9885.505724713736;10003.148472990593;10574.841377379345;1684.7557104219943;1706.8634509239232;1749.8162692917188;1766.152246921485;1784.9553761155946;86974.13025869723;107367;108647;112014.43992779985;113211.43394283058;9720.173785228193;11364.94317528407;11461.484233209525;11506;11624;14467.348969296407;14505.564833055025;14519.564413067414;14557.490487832874;14575.854241457662;8678.956605216934;8820.911790882137;8836.469811811312;8894.377393582387;8948.597313120925;7022.964885175541;7286.854262914818;8597.312215022783;8652.178043085896;8683.305335573139;3791.431285307226;4487.528809475023;4517.819287228474;4520.434945631796;4528.252838281713;1573.3470609697076;1899.6960486322123;1933.419974007792;1940.9126589303514;1963.6563601369703;876.82901834143;934.1732566678538;967.5231407596435;1013.9239557033104;1028.9794204115876;18504.907475462536;19943.202271908962;20133.19467221324;20158.59682806365;20450.693239601416;979.7011911367101;1009.7021378693303;1017.2370721958531;1026.5226669598671;1034.8344264917578;9174.908250917539;10867.967543083552;11052.944735276273;11061.612843550438;11107.389093599768;644670;660555.37536489;660626.6968665126;663087;668494;73303;97841.51079244558;100463;111623;117242;990.094063931509;997.152420442618;1003.8895721470778;1020.3622735790132;1024.2267088348399;17814.287428502714;17890.55273618118;18016.729749053513;18461.261549537867;18640.627187456445;47729.522704773895;48493.03013939631;50630.74684626701;50797.47607571779;52309;71495;72905;75192.62403688165;75411;75568;8037.557934313435;8238.09380968105;8252.752417427488;8275.793105172419;8573.657053717781;12089.57686480969;13432.328383580978;13648.385822638204;13827.654308641962;13831.239281839193;11527.308361498337;11618.419079046183;11891.46488408041;11900.702482438008;11964.401779910795;10521.316114452537;11365.374904380338;11451.02666273376;11529.020033296929;11777.705557361134;11696.181267311207;12058.80441682937;12128.696782580506;12215.57245496439;12345.259284442962;7023.770840102859;8428.915710842813;8552.144785521397;8556.700515481929;8728.519931403835;8814.691485797966;11366.659000230007;11967;12170.330631815335;12178.39108044612 15:08:15 INFO - PROCESS | 2270 | |3;mootools.html;1470.8382077971428;1590.149270140475;1622.0673112960046;1908.4751693284345;2054.126996026688;8286.83426331473;8941.016488186295;9103.044180361067;9104.95447522763;9120.087991200884;8918.331125165607;9143.862842057375;9148.039455857139;9171.174594286513;9172.678956236534;2260;2477.1453848422266;2535.125381743305;2545.8854351554223;2578.8452692838396;2564.1794625719776;2568.7816535594498;2569.087973769313;2571.0744132112422;2574.5751950928075;1898.8955607441585;2241.1371621925514;2471.4933438645035;2546.898124075035;2557.8721063946728;2407.7832995030444;2502.3869152057773;2506.135383292761;2532.328932832801;2532.8353657012235;2480.131953816168;2516.559602069649;2517.760812722807;2558.283680569425;2562.500312439075;9055.864162037575;9095.863562046574;9117.772055698595;9131.406458580172;9134.08659134088;5595.524380427669;5744.913826292609;5745.48290653839;5756.136579513107;5764.279465066867;3563.5901871284873;3679.779213247187;3712.9628703713156;3735.4957080794015;3743.7753734776;1025.3130402630256;1043.5304113149114;1048.4443245080045;1062.4581463453617;1088.3198001249218;12353.135280530367;12354.75290494194;12365.567205147867;12368.319742414255;12382.195157314745;4930.556249937523;4997.700137991732;5034.395872495324;5039.722815245197;5099.617528685363;10467.110295624958;10529.631462898762;10531.104856087319;10531.736706582244;10543.525541350504;5666.34836993751;5703.08750599902;5713.200151978726;5721.713914304268;5728.427157284238;4332.4367832181615;4537.3647689323525;4707.082118986765;4949.232868905326;5125.487451254845;3945.11234972129;4041.575634558329;4053.8175782089875;4068.959310406917;4108.260513107669;1184.945398595251;1225.1240363140398;1243.608263397027;1331.6471135149193;1345.8586848380976;1121.6747143328507;1171.437709899236;1244.3094082783962;1438.9208593527455;1449.8260208774805;8422.831543369099;8620.439671421338;8631.913680863237;8633.309335253165;8663.306935445149;506.2482213912337;520.4769206946994;522.4279414041619;526.242211215848;528.801699362739;6322.304546499881;6347.270063942706;6396.744130234832;6434;6438.645874476949;10911.290766100337;11272.267302625303;11297.26567773092;11446.255993360404;11452;9891.10980011788;10162.18702503798;11171.38557379352;11194.384308862927;11273.098152147808;8717.869231961526;8755.255803256667;8798.824023519493;8812.955935220283;8816.603252853636;8056.436049476715;8136.959315203623;8195.631196596049;8245.216704413071;8266.090730019814;8308.626111824862;9529.904700952902;9912.553935072794;10117.544710488193;10256.230782691178;10060.39637621745;10303.536340864828;10484.580616775243;10514.001169888848;10534;7708.653110610148;7720.652570634447;7794.064712234342;7794.142644309008;7796.4932279401655;8483.194096560817;8551.657933682833;8569.228769410784;8582.78543036429;8586.313094952264;7504.924950750423;7634.618269086634;7677.846443071219;7789.532628042336;7842.411819113475;7476.25237476248;7486.737964171228;7510.849783004419;7673.2710392514855;7700.922990770021;8002.599870006593;8061.677532898619;8099.676012959416;8103.797405064684;8106.189381062082;7096.893546596909;7358.448116391185;7365.742199023009;7374.852502950018;7392.297731715478;6543.70553325111;6548.508861835287;6974.616396098263;7026.156861176692;7067.893981590383 15:08:15 INFO - PROCESS | 2270 | |4;prototype.html;334.0179871178734;338.168106458113;343.1318763528274;345.35074060765766;349.3624135951887;6708.765193218238;6710.194776626806;6748.831279218022;6763.458923286137;6774.390304872561;6666.799996000128;6694.19669639644;6696.866062678743;6702.195736511613;6739.494537909664;4745.454272758625;4848.53938875808;4868.1237377272255;5000.324956130912;5027.597792176617;4968.925466117993;4977.029479251547;4997.275395067713;5014.247862820588;5042.747862606873;4924.655274130812;4940.555350018498;4952.5047495250365;4962.206047032476;4966.50334966504;3743.644353786386;4008.5590585035625;4690.413698287714;4774.665773395864;4976.353074100344;4265.936010959838;4267.893302667427;4276.615104640566;4279.422277992462;4285.935710964307;6441.903371449432;6452.257990331125;6454.257760357572;6475.158229430191;6496;5888.5289176865745;5892.086726557391;5898.852528686774;5904.143899134644;5904.793332233351;6824.249332573413;7132.643367831588;7133.108361454818;7141.25016873226;7141.678624461912;4778.784954677048;4782.545658162469;4808.471068182497;4810.494898035726;4834.85495435134;7395.593242371668;7487.588182649952;7490.962545187239;7494.962525187339;7508.3993280537425;4873.585745211626;4877.219644856806;4881.780319885543;4884.902301954012;4889.897344142053;4073.7555746655294;4426.44669416323;4683.548100088983;4953.653244272867;4955.801767929314;4779.832705855279;5626.493615574618;5858.531317494675;5863.472287494061;5879.823605291848;7131.322524360177;7156.499045066899;7159.319864612853;7160.606166660779;7193.100862392201;1682.932682692703;1724.4740354192093;1726.18869131508;1726.292220189716;1728.6369862329052;1039.1219419590434;1069.454578165126;1099.8900109988938;1109.7724966381872;1133.8015847226702;6476.5466417350335;6659.700313485808;6661.633610151392;6679.86640267192;6679.933200668028;7441.665125069385;7451.105867295851;7468.477206595595;7482.4388170887405;7485.887711684329;6635.071090047397;6638.477378596199;6642.6014439133805;6643.136392268974;6647.335266473314;3221.8066915985114;3778.8677396290996;3883.3980732986433;3909.8045097745567;3933.704972127102;7630.35142012924;7766.883496747553;7788;7792.961035194788;7794.415418843496;6353.682315884187;7506.361959233417;7643.579603121771;7658.234176582298;7684.154742978268;7736.187700291388;7750.379969602531;7754.457187996899;7774.611269436618;7776.300132988172;6332.70179613169;6838.38454539074;7340.926590734024;7430.591317477374;7461.291177338144;7279.308465695751;7316.634168291458;7326.743563975236;7333.339999400079;7348.742794002184;6333.556651034383;7723.7682869514;7733.4973226740085;7756.146823849259;7768.61156942162;3931.5282166140246;4575.99328147807;4862;4865.440474345495;4872.293517440023;5995.250593675791;7091.432685385053;7367.115946086504;7381.963090184515;7383.593902335423;5824.388439213991;7134.250903655042;7134.643267836691;7134.964325178341;7136.357727804522;7067.363937245673;7100.502964792621;7103.82240443993;7117;7144.214136444883;6931.2375638678695;6937;6941.965290173517;6943.9652801735665;6956.756513522002;6500.869982600416;7010.544314619738;7082.929170708433;7099.716011359695;7109.253528379446;6633.867322653424;6802.14973128359;6898.8620227596175;6940.375366216863;6975.651217439006 15:08:15 INFO - PROCESS | 2270 | |5;yui.html;753.4161025205468;758.4859947093872;776.0958483366877;776.2741836382979;781.992180078199;15310.540683779476;15479.613009674735;15499.690006199884;15542;15560.610984725388;14276.928615356908;15928.601784955383;16365.509034728948;16369.099699516522;16391.918040409782;781.1290411191537;963.9566219520121;975.6487664440796;984.6209209454375;986.3982970388057;785.2971590426565;959.7312752429297;983.233078199006;983.9065288797552;985.5367977050774;1028.542298677089;1029.0481304793075;1031.056583226347;1031.7575369788094;1032.463119178027;799.8720204767209;1013.12364804444;1013.6756238003842;1016.4206402350663;1016.9847452288221;736.9005184300158;747.7607165706976;749.6326799868025;751.0761763031447;752.6951584608242;15568.766468502981;15637.374505019785;15648.608784780472;15669.529914102595;15725.213739312989;12776.04179686518;12804.167729097671;12935.223886566837;13117.278549679764;13249.86750132496;10192.133668638175;10225.744356391075;10362.896371036268;10529.157667386668;10683.252172347939;3433.1760377509218;3468.167639766438;3508.1054331145397;3519.5248641433336;3720.9813950930343;83766.32467350618;85228;85382;85544;85549.14450855536;8094.311983481352;9361.531923403803;9502.952485237529;9513;9867.358621689567;17681.204345804214;21469.78530214709;21730;21739.021744021557;23364.532709345716;7734.92265077342;7747.30274275318;7780.18308077655;7790.5325680459355;8010.719624813103;4128.298189307825;4150.232207041707;4242.44848169736;4252.829886804493;4274.422952901318;3747.2505498900327;3748.700103991674;3794.4118661607495;3797.6392242736897;3799.810009499514;671.5433505216499;676.3777324861139;685.674304705261;686.2588404523103;686.8042607856737;513.3301042140043;592.6562593695646;611.0193140010308;614.3273115938041;628.6196850905228;7000.264972177848;8314.708985185489;8345.374096942754;8375.078741338448;8433.704820331259;526.3525863188299;553.3636318234063;588.620339880783;609.4484491535168;623.9906401403982;3153.936921261562;3732.760343793702;3776.86780962665;3864.806759662006;3898.766074035567;88535.67196492058;88896;89453.55273223593;90021.09978900258;90267;72327.27672723311;72509.63745181345;75044.62477687576;79709.40581188344;81730.13944511913;53079.203811942854;73980.63009684917;74208.62895685487;75286.49427011539;76459.61770191113;7967.442279040411;7974.760757177294;8839.093970549498;8847.292216622525;8851;47359;47862.52137478581;50531.74734126306;51630;52901.735491322295;48714.756426217646;59891.70054149701;69325.30674693189;70840.64579677068;72360.63819681079;10881.564737410416;11282.492287846902;11289.00976733103;11415.543378265109;11554.133439991867;11916.52333906634;12062.879371206527;12066.155369124428;12368.319742414255;12489.812652810397;11642.669604880059;11683.474243659226;11690.783704058013;11732.178747487595;11783.293002419883;11327.155427553778;11788.938995490447;11792.646220613395;11795.528178873092;11837.526498940291;11491.367974761468;11496.712582185512;11539.8269025963;11743.354115523729;11930.22453540517;7822.491538050009;9604.85592716096;9677.177439917405;9705.417674939526;10064.396136231557;9749.853752193581;9903.50482475859;9945.254105941938;9946.651867184903;9973.252006099716 15:08:15 INFO - PROCESS | 2270 | __end_tp_report 15:08:15 INFO - PROCESS | 2270 | __start_cc_report 15:08:15 INFO - PROCESS | 2270 | _x_x_mozilla_cycle_collect,988 15:08:15 INFO - PROCESS | 2270 | __end_cc_report 15:08:15 INFO - PROCESS | 2270 | __startTimestamp1460671695037__endTimestamp 15:08:15 INFO - PROCESS | 2270 | 15:08:15 INFO - PROCESS | 2270 | ------- Summary: start ------- 15:08:15 INFO - PROCESS | 2270 | Number of tests: 6 15:08:15 INFO - PROCESS | 2270 | 15:08:15 INFO - PROCESS | 2270 | [#0] dojo.html Cycles:165 Average:5698.64 Median:5863.85 stddev:636.12 (10.8%) stddev-sans-first:610.84 15:08:15 INFO - PROCESS | 2270 | Values: 3351.3 3433.7 3437.5 3455.9 3524.9 5637.4 5667.5 5669.2 5682.3 5697.2 5660.1 5688.5 5696.8 5706.2 5718.8 5117.0 5173.2 5194.7 5280.5 5298.8 5246.2 5268.7 5276.0 5277.4 5287.4 5183.1 5204.0 5208.1 5222.6 5239.8 4664.3 5165.9 5221.6 5235.7 5286.8 5018.3 5115.5 5177.9 5191.5 5224.4 5557.2 5566.9 5575.1 5599.1 5622.1 6086.8 6163.4 6206.0 6215.3 6216.1 5232.7 6176.7 6300.1 6304.1 6341.9 5748.5 5778.4 5787.0 5792.5 5801.3 5392.9 6205.3 6220.2 6308.5 6326.2 6112.2 6133.3 6142.4 6147.3 6159.9 6251.2 6251.4 6268.0 6270.0 6272.1 5849.7 5953.7 6170.9 6212.1 6214.3 6115.6 6155.9 6202.3 6208.1 6211.9 4850.3 4852.7 4856.0 4858.9 4870.5 4338.6 4396.3 4399.2 4401.6 4423.3 5617.1 5655.6 5679.7 5702.5 5706.9 6211.7 6226.5 6226.8 6241.2 6248.0 5524.0 5550.0 5587.1 5614.3 5643.1 4563.9 5293.6 5309.3 5398.3 5398.6 6289.0 6322.9 6325.4 6328.5 6338.1 6117.2 6271.0 6299.9 6301.4 6316.2 6242.7 6271.0 6323.6 6329.9 6333.2 5327.9 6044.2 6175.9 6194.1 6196.0 6117.8 6149.9 6177.5 6201.4 6251.8 5962.2 6058.9 6077.7 6092.9 6112.0 6038.7 6040.8 6046.2 6054.0 6054.1 5760.6 5830.4 5918.1 5925.8 5994.1 6036.5 6056.1 6069.0 6080.4 6090.5 5859.6 5863.9 5897.2 5900.2 5931.3 15:08:15 INFO - PROCESS | 2270 | 15:08:15 INFO - PROCESS | 2270 | [#1] ext.html Cycles:180 Average:59146.81 Median:38480.42 stddev:120862.38 (314.1%) stddev-sans-first:121121.21 15:08:15 INFO - PROCESS | 2270 | Values: 495.6 594.2 614.2 658.6 664.8 31640.7 39986.4 40275.6 40361.0 40390.4 43274.0 43829.9 43848.6 43905.0 44097.1 9606.3 9831.9 9851.0 9853.6 9862.4 9620.9 9745.9 9776.7 9828.3 9867.8 9743.0 9903.2 9918.0 9919.6 9926.5 9832.5 9892.0 9899.7 9905.2 9919.8 9761.2 9889.7 9892.6 9894.6 9903.3 41994.2 42509.6 42515.6 42525.0 42534.8 3159.5 3310.3 3733.3 3806.2 3933.6 28022.3 28336.3 29559.7 29617.9 30245.7 2701.2 2702.9 2720.0 2740.2 2767.0 505071.0 505628.5 506477.0 506768.0 508348.0 47934.5 48683.5 67124.7 67568.7 67742.0 46759.5 47124.8 47212.8 47331.6 47363.0 16745.7 16889.7 16934.3 16934.5 16934.7 13320.5 13381.9 13407.3 13414.4 13417.3 15263.7 15460.0 15484.4 15496.2 15643.1 5599.8 5643.9 6645.4 6669.4 6766.5 1009.4 1044.7 1059.0 1093.0 1104.8 23868.6 30264.5 30467.4 30651.0 30755.5 1192.7 1193.9 1215.6 1230.4 1260.3 15097.9 15420.5 15464.5 15628.9 15703.1 427696.0 533638.0 545661.0 549597.0 550441.0 61529.7 62344.0 62436.0 62473.4 62617.0 1431.3 1432.0 1531.5 1552.0 1572.2 28676.9 29218.0 29416.9 30273.5 30423.0 46906.0 47286.8 47310.0 47443.8 47495.8 293657.0 297011.0 297031.0 297204.0 298130.0 29671.6 29998.3 34428.1 34547.3 34958.5 42856.8 43130.0 44585.0 44637.8 44996.3 37026.8 37666.4 37756.2 37932.8 38593.8 37636.6 37698.0 37804.6 38195.9 38477.6 37163.0 37820.6 38483.2 38494.6 38518.4 27129.3 32467.0 33512.8 33528.5 33584.0 25299.0 25387.5 25405.7 25738.6 25826.7 15:08:15 INFO - PROCESS | 2270 | 15:08:15 INFO - PROCESS | 2270 | [#2] jquery.html Cycles:180 Average:35019.69 Median:12027.30 stddev:108795.03 (904.6%) stddev-sans-first:109069.12 15:08:15 INFO - PROCESS | 2270 | Values: 372.1 376.2 378.2 381.5 384.2 20273.5 20644.9 20719.7 21707.0 21749.7 12087.6 12301.8 12419.8 12421.8 12437.4 4788.3 6031.5 6038.4 6040.2 6046.7 5929.7 6016.1 6043.8 6045.0 6053.3 5878.3 5975.0 5993.8 5998.6 6000.2 5736.2 5943.4 6036.8 6049.4 6054.0 5329.5 5343.1 5359.4 5362.1 5370.8 14303.5 14317.6 14318.6 19373.0 25859.7 2114.4 2119.4 2304.9 2442.7 2694.9 9832.0 9866.9 9885.5 10003.1 10574.8 1684.8 1706.9 1749.8 1766.2 1785.0 86974.1 107367.0 108647.0 112014.4 113211.4 9720.2 11364.9 11461.5 11506.0 11624.0 14467.3 14505.6 14519.6 14557.5 14575.9 8679.0 8820.9 8836.5 8894.4 8948.6 7023.0 7286.9 8597.3 8652.2 8683.3 3791.4 4487.5 4517.8 4520.4 4528.3 1573.3 1899.7 1933.4 1940.9 1963.7 876.8 934.2 967.5 1013.9 1029.0 18504.9 19943.2 20133.2 20158.6 20450.7 979.7 1009.7 1017.2 1026.5 1034.8 9174.9 10868.0 11052.9 11061.6 11107.4 644670.0 660555.4 660626.7 663087.0 668494.0 73303.0 97841.5 100463.0 111623.0 117242.0 990.1 997.2 1003.9 1020.4 1024.2 17814.3 17890.6 18016.7 18461.3 18640.6 47729.5 48493.0 50630.7 50797.5 52309.0 71495.0 72905.0 75192.6 75411.0 75568.0 8037.6 8238.1 8252.8 8275.8 8573.7 12089.6 13432.3 13648.4 13827.7 13831.2 11527.3 11618.4 11891.5 11900.7 11964.4 10521.3 11365.4 11451.0 11529.0 11777.7 11696.2 12058.8 12128.7 12215.6 12345.3 7023.8 8428.9 8552.1 8556.7 8728.5 8814.7 11366.7 11967.0 12170.3 12178.4 15:08:15 INFO - PROCESS | 2270 | 15:08:15 INFO - PROCESS | 2270 | [#3] mootools.html Cycles:180 Average:6248.80 Median:5112.55 stddev:3353.93 (65.6%) stddev-sans-first:3344.11 15:08:15 INFO - PROCESS | 2270 | Values: 1470.8 1590.1 1622.1 1908.5 2054.1 8286.8 8941.0 9103.0 9105.0 9120.1 8918.3 9143.9 9148.0 9171.2 9172.7 2260.0 2477.1 2535.1 2545.9 2578.8 2564.2 2568.8 2569.1 2571.1 2574.6 1898.9 2241.1 2471.5 2546.9 2557.9 2407.8 2502.4 2506.1 2532.3 2532.8 2480.1 2516.6 2517.8 2558.3 2562.5 9055.9 9095.9 9117.8 9131.4 9134.1 5595.5 5744.9 5745.5 5756.1 5764.3 3563.6 3679.8 3713.0 3735.5 3743.8 1025.3 1043.5 1048.4 1062.5 1088.3 12353.1 12354.8 12365.6 12368.3 12382.2 4930.6 4997.7 5034.4 5039.7 5099.6 10467.1 10529.6 10531.1 10531.7 10543.5 5666.3 5703.1 5713.2 5721.7 5728.4 4332.4 4537.4 4707.1 4949.2 5125.5 3945.1 4041.6 4053.8 4069.0 4108.3 1184.9 1225.1 1243.6 1331.6 1345.9 1121.7 1171.4 1244.3 1438.9 1449.8 8422.8 8620.4 8631.9 8633.3 8663.3 506.2 520.5 522.4 526.2 528.8 6322.3 6347.3 6396.7 6434.0 6438.6 10911.3 11272.3 11297.3 11446.3 11452.0 9891.1 10162.2 11171.4 11194.4 11273.1 8717.9 8755.3 8798.8 8813.0 8816.6 8056.4 8137.0 8195.6 8245.2 8266.1 8308.6 9529.9 9912.6 10117.5 10256.2 10060.4 10303.5 10484.6 10514.0 10534.0 7708.7 7720.7 7794.1 7794.1 7796.5 8483.2 8551.7 8569.2 8582.8 8586.3 7504.9 7634.6 7677.8 7789.5 7842.4 7476.3 7486.7 7510.8 7673.3 7700.9 8002.6 8061.7 8099.7 8103.8 8106.2 7096.9 7358.4 7365.7 7374.9 7392.3 6543.7 6548.5 6974.6 7026.2 7067.9 15:08:15 INFO - PROCESS | 2270 | 15:08:15 INFO - PROCESS | 2270 | [#4] prototype.html Cycles:180 Average:5826.61 Median:6642.87 stddev:1858.21 (28.0%) stddev-sans-first:1817.11 15:08:15 INFO - PROCESS | 2270 | Values: 334.0 338.2 343.1 345.4 349.4 6708.8 6710.2 6748.8 6763.5 6774.4 6666.8 6694.2 6696.9 6702.2 6739.5 4745.5 4848.5 4868.1 5000.3 5027.6 4968.9 4977.0 4997.3 5014.2 5042.7 4924.7 4940.6 4952.5 4962.2 4966.5 3743.6 4008.6 4690.4 4774.7 4976.4 4265.9 4267.9 4276.6 4279.4 4285.9 6441.9 6452.3 6454.3 6475.2 6496.0 5888.5 5892.1 5898.9 5904.1 5904.8 6824.2 7132.6 7133.1 7141.3 7141.7 4778.8 4782.5 4808.5 4810.5 4834.9 7395.6 7487.6 7491.0 7495.0 7508.4 4873.6 4877.2 4881.8 4884.9 4889.9 4073.8 4426.4 4683.5 4953.7 4955.8 4779.8 5626.5 5858.5 5863.5 5879.8 7131.3 7156.5 7159.3 7160.6 7193.1 1682.9 1724.5 1726.2 1726.3 1728.6 1039.1 1069.5 1099.9 1109.8 1133.8 6476.5 6659.7 6661.6 6679.9 6679.9 7441.7 7451.1 7468.5 7482.4 7485.9 6635.1 6638.5 6642.6 6643.1 6647.3 3221.8 3778.9 3883.4 3909.8 3933.7 7630.4 7766.9 7788.0 7793.0 7794.4 6353.7 7506.4 7643.6 7658.2 7684.2 7736.2 7750.4 7754.5 7774.6 7776.3 6332.7 6838.4 7340.9 7430.6 7461.3 7279.3 7316.6 7326.7 7333.3 7348.7 6333.6 7723.8 7733.5 7756.1 7768.6 3931.5 4576.0 4862.0 4865.4 4872.3 5995.3 7091.4 7367.1 7382.0 7383.6 5824.4 7134.3 7134.6 7135.0 7136.4 7067.4 7100.5 7103.8 7117.0 7144.2 6931.2 6937.0 6942.0 6944.0 6956.8 6500.9 7010.5 7082.9 7099.7 7109.3 6633.9 6802.1 6898.9 6940.4 6975.7 15:08:15 INFO - PROCESS | 2270 | 15:08:15 INFO - PROCESS | 2270 | [#5] yui.html Cycles:180 Average:18365.81 Median:26078.18 stddev:25562.49 (98.0%) stddev-sans-first:25599.99 15:08:15 INFO - PROCESS | 2270 | Values: 753.4 758.5 776.1 776.3 782.0 15310.5 15479.6 15499.7 15542.0 15560.6 14276.9 15928.6 16365.5 16369.1 16391.9 781.1 964.0 975.6 984.6 986.4 785.3 959.7 983.2 983.9 985.5 1028.5 1029.0 1031.1 1031.8 1032.5 799.9 1013.1 1013.7 1016.4 1017.0 736.9 747.8 749.6 751.1 752.7 15568.8 15637.4 15648.6 15669.5 15725.2 12776.0 12804.2 12935.2 13117.3 13249.9 10192.1 10225.7 10362.9 10529.2 10683.3 3433.2 3468.2 3508.1 3519.5 3721.0 83766.3 85228.0 85382.0 85544.0 85549.1 8094.3 9361.5 9503.0 9513.0 9867.4 17681.2 21469.8 21730.0 21739.0 23364.5 7734.9 7747.3 7780.2 7790.5 8010.7 4128.3 4150.2 4242.4 4252.8 4274.4 3747.3 3748.7 3794.4 3797.6 3799.8 671.5 676.4 685.7 686.3 686.8 513.3 592.7 611.0 614.3 628.6 7000.3 8314.7 8345.4 8375.1 8433.7 526.4 553.4 588.6 609.4 624.0 3153.9 3732.8 3776.9 3864.8 3898.8 88535.7 88896.0 89453.6 90021.1 90267.0 72327.3 72509.6 75044.6 79709.4 81730.1 53079.2 73980.6 74208.6 75286.5 76459.6 7967.4 7974.8 8839.1 8847.3 8851.0 47359.0 47862.5 50531.7 51630.0 52901.7 48714.8 59891.7 69325.3 70840.6 72360.6 10881.6 11282.5 11289.0 11415.5 11554.1 11916.5 12062.9 12066.2 12368.3 12489.8 11642.7 11683.5 11690.8 11732.2 11783.3 11327.2 11788.9 11792.6 11795.5 11837.5 11491.4 11496.7 11539.8 11743.4 11930.2 7822.5 9604.9 9677.2 9705.4 10064.4 9749.9 9903.5 9945.3 9946.7 9973.3 15:08:15 INFO - PROCESS | 2270 | -------- Summary: end -------- 15:08:15 INFO - PROCESS | 2270 | 15:08:15 INFO - TEST-INFO | 2270: exit 0 15:08:15 INFO - TEST-OK | dromaeo_css | took 1112723ms 15:08:15 INFO - TEST-START | kraken 15:08:15 INFO - operating with platform_type : mac_ 15:08:15 INFO - Initialising browser for kraken test... 15:08:15 INFO - TEST-INFO | started process 2273 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/tmp4cQL3r/profile http://localhost:52426/getInfo.html) 15:08:17 INFO - PROCESS | 2273 | __metrics Screen width/height:1600/1200 15:08:17 INFO - PROCESS | 2273 | colorDepth:24 15:08:17 INFO - PROCESS | 2273 | Browser inner width/height: 1024/689 15:08:17 INFO - PROCESS | 2273 | __metrics 15:08:18 INFO - PROCESS | 2273 | 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" 15:08:18 INFO - PROCESS | 2273 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:08:18 INFO - TEST-INFO | 2273: exit 0 15:08:18 INFO - Browser initialized. 15:08:18 INFO - Running cycle 1/1 for kraken test... 15:08:18 INFO - TEST-INFO | started process 2275 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/tmp4cQL3r/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:08:30 INFO - PROCESS | 2275 | RSS: Main: 207360000 15:08:30 INFO - PROCESS | 2275 | 15:08:33 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:52426/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52426/tests/kraken/driver.html?testName=audio-beat-detection) 15:08:33 INFO - PROCESS | 2275 | RSS: Main: 263925760 15:08:33 INFO - PROCESS | 2275 | 15:08:36 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:52426/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52426/tests/kraken/driver.html?testName=audio-dft) 15:08:37 INFO - PROCESS | 2275 | RSS: Main: 274784256 15:08:37 INFO - PROCESS | 2275 | 15:08:40 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:52426/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52426/tests/kraken/driver.html?testName=audio-fft) 15:08:40 INFO - PROCESS | 2275 | RSS: Main: 262520832 15:08:40 INFO - PROCESS | 2275 | 15:08:42 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:52426/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52426/tests/kraken/driver.html?testName=audio-oscillator) 15:08:43 INFO - PROCESS | 2275 | RSS: Main: 264646656 15:08:43 INFO - PROCESS | 2275 | 15:08:45 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:52426/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52426/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:08:46 INFO - PROCESS | 2275 | RSS: Main: 264122368 15:08:46 INFO - PROCESS | 2275 | 15:08:48 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:52426/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52426/tests/kraken/driver.html?testName=imaging-darkroom) 15:08:49 INFO - PROCESS | 2275 | RSS: Main: 264179712 15:08:49 INFO - PROCESS | 2275 | 15:08:52 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:52426/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52426/tests/kraken/driver.html?testName=imaging-desaturate) 15:08:52 INFO - PROCESS | 2275 | RSS: Main: 264208384 15:08:52 INFO - PROCESS | 2275 | 15:08:55 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:52426/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52426/tests/kraken/driver.html?testName=json-parse-financial) 15:08:56 INFO - PROCESS | 2275 | RSS: Main: 264691712 15:08:56 INFO - PROCESS | 2275 | 15:08:57 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:52426/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52426/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:08:58 INFO - PROCESS | 2275 | RSS: Main: 298266624 15:08:58 INFO - PROCESS | 2275 | 15:09:00 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:52426/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52426/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:09:00 INFO - PROCESS | 2275 | RSS: Main: 299085824 15:09:00 INFO - PROCESS | 2275 | 15:09:02 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:52426/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52426/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:09:03 INFO - PROCESS | 2275 | RSS: Main: 305266688 15:09:03 INFO - PROCESS | 2275 | 15:09:05 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:52426/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52426/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:09:06 INFO - PROCESS | 2275 | RSS: Main: 307150848 15:09:06 INFO - PROCESS | 2275 | 15:09:09 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:52426/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52426/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:09:09 INFO - PROCESS | 2275 | RSS: Main: 306950144 15:09:09 INFO - PROCESS | 2275 | 15:09:11 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:52426/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52426/tests/kraken/driver.html?testName=ai-astar) 15:09:11 INFO - PROCESS | 2275 | RSS: Main: 369520640 15:09:11 INFO - PROCESS | 2275 | 15:09:11 INFO - PROCESS | 2275 | __start_tp_report 15:09:11 INFO - PROCESS | 2275 | _x_x_mozilla_page_load 15:09:11 INFO - PROCESS | 2275 | _x_x_mozilla_page_load_details 15:09:11 INFO - PROCESS | 2275 | |i|pagename|runs| 15:09:11 INFO - PROCESS | 2275 | |0;ai-astar;114;150;162;114;113;112;121;112;110;111 15:09:11 INFO - PROCESS | 2275 | |1;audio-beat-detection;154;120;158;148;117;137;119;150;171;146 15:09:11 INFO - PROCESS | 2275 | |2;audio-dft;186;183;187;184;188;188;183;185;185;189 15:09:11 INFO - PROCESS | 2275 | |3;audio-fft;74;75;75;121;57;57;61;58;122;61 15:09:11 INFO - PROCESS | 2275 | |4;audio-oscillator;93;94;138;80;77;85;122;77;79;122 15:09:11 INFO - PROCESS | 2275 | |5;imaging-gaussian-blur;88;85;88;85;88;83;88;86;88;85 15:09:11 INFO - PROCESS | 2275 | |6;imaging-darkroom;92;93;94;93;93;93;93;93;93;109 15:09:11 INFO - PROCESS | 2275 | |7;imaging-desaturate;85;87;90;85;88;86;84;84;84;83 15:09:11 INFO - PROCESS | 2275 | |8;json-parse-financial;60;61;61;58;59;59;59;70;52;54 15:09:11 INFO - PROCESS | 2275 | |9;json-stringify-tinderbox;61;58;68;78;69;69;67;65;65;68 15:09:11 INFO - PROCESS | 2275 | |10;stanford-crypto-aes;81;80;81;82;80;79;79;80;80;84 15:09:11 INFO - PROCESS | 2275 | |11;stanford-crypto-ccm;124;143;131;118;119;118;128;117;139;107 15:09:11 INFO - PROCESS | 2275 | |12;stanford-crypto-pbkdf2;137;121;135;133;130;105;155;170;130;134 15:09:11 INFO - PROCESS | 2275 | |13;stanford-crypto-sha256-iterative;66;67;65;66;65;65;65;66;65;66 15:09:11 INFO - PROCESS | 2275 | __end_tp_report 15:09:11 INFO - PROCESS | 2275 | __start_cc_report 15:09:11 INFO - PROCESS | 2275 | _x_x_mozilla_cycle_collect,1307 15:09:11 INFO - PROCESS | 2275 | __end_cc_report 15:09:11 INFO - PROCESS | 2275 | __startTimestamp1460671751584__endTimestamp 15:09:11 INFO - PROCESS | 2275 | 15:09:11 INFO - PROCESS | 2275 | ------- Summary: start ------- 15:09:11 INFO - PROCESS | 2275 | Number of tests: 14 15:09:11 INFO - PROCESS | 2275 | 15:09:11 INFO - PROCESS | 2275 | [#0] ai-astar Cycles:10 Average:121.90 Median:114.00 stddev:18.44 (16.2%) stddev-sans-first:19.33 15:09:11 INFO - PROCESS | 2275 | Values: 114.0 150.0 162.0 114.0 113.0 112.0 121.0 112.0 110.0 111.0 15:09:11 INFO - PROCESS | 2275 | 15:09:11 INFO - PROCESS | 2275 | [#1] audio-beat-detection Cycles:10 Average:142.00 Median:149.00 stddev:18.32 (12.3%) stddev-sans-first:18.91 15:09:11 INFO - PROCESS | 2275 | Values: 154.0 120.0 158.0 148.0 117.0 137.0 119.0 150.0 171.0 146.0 15:09:11 INFO - PROCESS | 2275 | 15:09:11 INFO - PROCESS | 2275 | [#2] audio-dft Cycles:10 Average:185.80 Median:186.50 stddev:2.15 (1.2%) stddev-sans-first:2.28 15:09:11 INFO - PROCESS | 2275 | Values: 186.0 183.0 187.0 184.0 188.0 188.0 183.0 185.0 185.0 189.0 15:09:11 INFO - PROCESS | 2275 | 15:09:11 INFO - PROCESS | 2275 | [#3] audio-fft Cycles:10 Average:76.10 Median:61.00 stddev:25.04 (41.0%) stddev-sans-first:26.55 15:09:11 INFO - PROCESS | 2275 | Values: 74.0 75.0 75.0 121.0 57.0 57.0 61.0 58.0 122.0 61.0 15:09:11 INFO - PROCESS | 2275 | 15:09:11 INFO - PROCESS | 2275 | [#4] audio-oscillator Cycles:10 Average:96.70 Median:79.50 stddev:22.39 (28.2%) stddev-sans-first:23.71 15:09:11 INFO - PROCESS | 2275 | Values: 93.0 94.0 138.0 80.0 77.0 85.0 122.0 77.0 79.0 122.0 15:09:11 INFO - PROCESS | 2275 | 15:09:11 INFO - PROCESS | 2275 | [#5] imaging-gaussian-blur Cycles:10 Average:86.40 Median:88.00 stddev:1.84 (2.1%) stddev-sans-first:1.86 15:09:11 INFO - PROCESS | 2275 | Values: 88.0 85.0 88.0 85.0 88.0 83.0 88.0 86.0 88.0 85.0 15:09:11 INFO - PROCESS | 2275 | 15:09:11 INFO - PROCESS | 2275 | [#6] imaging-darkroom Cycles:10 Average:94.60 Median:93.00 stddev:5.08 (5.5%) stddev-sans-first:5.30 15:09:11 INFO - PROCESS | 2275 | Values: 92.0 93.0 94.0 93.0 93.0 93.0 93.0 93.0 93.0 109.0 15:09:11 INFO - PROCESS | 2275 | 15:09:11 INFO - PROCESS | 2275 | [#7] imaging-desaturate Cycles:10 Average:85.60 Median:85.50 stddev:2.17 (2.5%) stddev-sans-first:2.29 15:09:11 INFO - PROCESS | 2275 | Values: 85.0 87.0 90.0 85.0 88.0 86.0 84.0 84.0 84.0 83.0 15:09:11 INFO - PROCESS | 2275 | 15:09:11 INFO - PROCESS | 2275 | [#8] json-parse-financial Cycles:10 Average:59.30 Median:59.50 stddev:4.76 (8.0%) stddev-sans-first:5.04 15:09:11 INFO - PROCESS | 2275 | Values: 60.0 61.0 61.0 58.0 59.0 59.0 59.0 70.0 52.0 54.0 15:09:11 INFO - PROCESS | 2275 | 15:09:11 INFO - PROCESS | 2275 | [#9] json-stringify-tinderbox Cycles:10 Average:66.80 Median:68.00 stddev:5.33 (7.8%) stddev-sans-first:5.22 15:09:11 INFO - PROCESS | 2275 | Values: 61.0 58.0 68.0 78.0 69.0 69.0 67.0 65.0 65.0 68.0 15:09:11 INFO - PROCESS | 2275 | 15:09:11 INFO - PROCESS | 2275 | [#10] stanford-crypto-aes Cycles:10 Average:80.60 Median:80.50 stddev:1.51 (1.9%) stddev-sans-first:1.59 15:09:11 INFO - PROCESS | 2275 | Values: 81.0 80.0 81.0 82.0 80.0 79.0 79.0 80.0 80.0 84.0 15:09:11 INFO - PROCESS | 2275 | 15:09:11 INFO - PROCESS | 2275 | [#11] stanford-crypto-ccm Cycles:10 Average:124.40 Median:126.00 stddev:10.98 (8.7%) stddev-sans-first:11.64 15:09:11 INFO - PROCESS | 2275 | Values: 124.0 143.0 131.0 118.0 119.0 118.0 128.0 117.0 139.0 107.0 15:09:11 INFO - PROCESS | 2275 | 15:09:11 INFO - PROCESS | 2275 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:135.00 Median:134.50 stddev:17.58 (13.1%) stddev-sans-first:18.63 15:09:11 INFO - PROCESS | 2275 | Values: 137.0 121.0 135.0 133.0 130.0 105.0 155.0 170.0 130.0 134.0 15:09:11 INFO - PROCESS | 2275 | 15:09:11 INFO - PROCESS | 2275 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:66.00 stddev:0.70 (1.1%) stddev-sans-first:0.73 15:09:11 INFO - PROCESS | 2275 | Values: 66.0 67.0 65.0 66.0 65.0 65.0 65.0 66.0 65.0 66.0 15:09:11 INFO - PROCESS | 2275 | -------- Summary: end -------- 15:09:11 INFO - PROCESS | 2275 | 15:09:12 INFO - TEST-INFO | 2275: exit 0 15:09:12 INFO - TEST-OK | kraken | took 56618ms 15:09:12 INFO - SUITE-END | took 1169s 15:09:12 INFO - Completed test suite (00:19:29) 15:09:12 INFO - javascript benchmark 15:09:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3351.2627222011156, 3433.742469314802, 3437.484377343398, 3455.8617655293788, 3524.9471257931136, 5637.408072152426, 5667.518260947819, 5669.234653321801, 5682.289713785772, 5697.173909783079, 5660.094384898417, 5688.459596338352, 5696.829095127153, 5706.1726049722765, 5718.771249150029, 5117.0, 5173.172292433212, 5194.740262986845, 5280.498352656491, 5298.814541491049, 5246.239295302179, 5268.683878967274, 5276.0, 5277.366715994086, 5287.365516138049, 5183.092958732225, 5203.973980130094, 5208.140656791625, 5222.582193424536, 5239.842804715865, 4664.346991421204, 5165.896682066356, 5221.634485586011, 5235.712035838027, 5286.814961476368, 5018.272350509155, 5115.462876397962, 5177.922331165036, 5191.506806853343, 5224.373075230958, 5557.166425036277, 5566.916496252559, 5575.107982722786, 5599.132134519156, 5622.128570071604, 6086.786962456293, 6163.3836616338, 6206.0380641000265, 6215.316315205324, 6216.129741836146, 5232.6860388376435, 6176.722047507873, 6300.054991751274, 6304.148939893102, 6341.936580634181, 5748.45389687979, 5778.42215778423, 5787.045137552341, 5792.478676919098, 5801.3328467225965, 5392.89445663638, 6205.28639206488, 6220.191375121296, 6308.526860485482, 6326.240851097898, 6112.1663500949535, 6133.325334213233, 6142.355052719489, 6147.293061297919, 6159.938400615936, 6251.187345645037, 6251.374862513803, 6267.968660156761, 6269.96865015672, 6272.090546870678, 5849.736761845727, 5953.702314884238, 6170.907436388457, 6212.099245609361, 6214.316425193225, 6115.571909966259, 6155.907661385083, 6202.317745048041, 6208.068789681584, 6211.9068213976825, 4850.296706977469, 4852.684575502581, 4855.975720121377, 4858.854234372974, 4870.464248932614, 4338.58783415575, 4396.340548917624, 4399.1641588098155, 4401.581849724271, 4423.292273236267, 5617.073182924853, 5655.632383895034, 5679.6876171810945, 5702.45826646468, 5706.914396284059, 6211.65835879031, 6226.501879849601, 6226.750929962841, 6241.188645476059, 6247.968760156261, 5524.005678977742, 5550.0, 5587.05020146576, 5614.29821272341, 5643.097104463266, 4563.908721825611, 5293.602979776532, 5309.256704061435, 5398.271233383522, 5398.62209645321, 6289.0, 6322.905156422749, 6325.430711236103, 6328.493720502349, 6338.080978258219, 6117.188044311166, 6270.959757266151, 6299.874002520016, 6301.4328710416285, 6316.178896743578, 6242.719077641426, 6271.0, 6323.620582765049, 6329.8734025320155, 6333.20834895638, 5327.866803329948, 6044.214252147192, 6175.938240617536, 6194.101855230876, 6195.96902015487, 6117.84705382369, 6149.877002459836, 6177.4749146323065, 6201.410865967726, 6251.835188843992, 5962.19510366095, 6058.878822423615, 6077.6961151943115, 6092.8781424372155, 6111.960966635772, 6038.7282572283475, 6040.788572400121, 6046.244219472566, 6054.0, 6054.091886217103, 5760.6255593386295, 5830.358660547421, 5918.112283157561, 5925.822225333247, 5994.13085102649, 6036.517078633787, 6056.121862330026, 6069.0, 6080.422359875804, 6090.482309003784, 5859.648421094748, 5863.853403664943, 5897.203877476657, 5900.173975643327, 5931.347551769388], "unit": "score", "name": "dojo.html", "value": 5659.21770171626}, {"lowerIsBetter": false, "replicates": [495.56637941800926, 594.1622312539321, 614.2076721029869, 658.6311665467339, 664.8204984654144, 31640.68359316406, 39986.400203996964, 40275.59724402755, 40360.990975225635, 40390.394144087826, 43274.0, 43829.90425239371, 43848.56151438485, 43905.0, 44097.11805763883, 9606.327557071008, 9831.90168098317, 9850.950745246264, 9853.556589953469, 9862.358946668479, 9620.941696413389, 9745.902540974606, 9776.657816976407, 9828.312018158696, 9867.802643947116, 9743.0, 9903.207743380548, 9917.950410247975, 9919.603215871357, 9926.503674816266, 9832.508374581279, 9891.950540247253, 9899.703008909744, 9905.158061564704, 9919.80160396788, 9761.170300524464, 9889.703308900744, 9892.55483503244, 9894.604215831358, 9903.306768526134, 41994.1601167978, 42509.57490425087, 42515.574844251474, 42525.0, 42534.787326063175, 3159.541866429378, 3310.2717402171484, 3733.328000959826, 3806.238752249561, 3933.567307596162, 28022.299442513897, 28336.291592710142, 29559.70440295591, 29617.851910740308, 30245.697543024507, 2701.2436517775054, 2702.9053983110693, 2720.0, 2740.2464322311325, 2766.9762187990573, 505071.0, 505628.47185764206, 506477.0, 506767.99999999633, 508348.0, 47934.52065479301, 48683.51316486861, 67124.6643766778, 67568.66215668891, 67742.0, 46759.532404676196, 47124.76437617859, 47212.7639361801, 47331.580052598474, 47363.0, 16745.665086698198, 16889.746653800205, 16934.322627094778, 16934.49196524106, 16934.661306773796, 13320.533781317607, 13381.933090334485, 13407.329633518286, 13414.396352164176, 13417.262050587311, 15263.694726105416, 15459.995100318443, 15484.380624775109, 15496.225188740518, 15643.139627320379, 5599.796043850511, 5643.915341269884, 6645.435138013323, 6669.366410191121, 6766.458683305324, 1009.3792317724547, 1044.6552637629716, 1059.0, 1092.9726756831142, 1104.8287515434959, 23868.64197037046, 30264.54603180954, 30467.390652187274, 30651.0, 30755.538666920012, 1192.7077865922731, 1193.8806119387991, 1215.6231568213882, 1230.390956476532, 1260.2690439545042, 15097.924510377377, 15420.537383878502, 15464.458743943907, 15628.899394987904, 15703.057816531045, 427696.0, 533638.0, 545661.0, 549597.0, 550441.0, 61529.69235153885, 62344.0, 62436.0, 62473.37526624766, 62617.0, 1431.3129697745142, 1431.9856801431852, 1531.4639876043518, 1551.9844801552065, 1572.1981789287545, 28676.856615716788, 29218.0, 29416.852915735286, 30273.545896812007, 30423.0, 46906.0, 47286.76356618195, 47310.0, 47443.76278118588, 47495.76252118717, 293657.0, 297011.0, 297031.02968970034, 297204.0, 298130.0, 29671.55492667655, 29998.25004374821, 34428.13929651678, 34547.309053819285, 34958.475622866186, 42856.78571607122, 43130.0, 44585.0, 44637.776811115735, 44996.32505512486, 37026.814865925495, 37666.43500347552, 37756.244875101795, 37932.81033594815, 38593.807030964665, 37636.62363376441, 37698.0, 37804.62195378011, 38195.85412437631, 38477.61522384852, 37163.0, 37820.62179378171, 38483.230335393695, 38494.615053849106, 38518.42222366723, 27129.321766955985, 32467.0, 33512.83243583766, 33528.49707254442, 33584.0, 25299.0, 25387.492250154522, 25405.74594254034, 25738.613920791577, 25826.741732582435], "unit": "score", "name": "ext.html", "value": 19495.590513743355}, {"lowerIsBetter": false, "replicates": [372.1477815801814, 376.15177774119354, 378.2491753868572, 381.52690663577175, 384.2142817937318, 20273.493162670922, 20644.864532450745, 20719.689204661943, 21706.99999999996, 21749.67375489367, 12087.576934807283, 12301.815472767916, 12419.751604967896, 12421.751564968696, 12437.378131093432, 4788.28175773635, 6031.487323577501, 6038.396160383971, 6040.244969378828, 6046.727897244613, 5929.673867937262, 6016.067509536007, 6043.788467403641, 6045.03279475284, 6053.273607167146, 5878.323992740847, 5975.0439929611275, 5993.760249590011, 5998.640081595118, 6000.159977603117, 5736.1682556029145, 5943.435373639497, 6036.8188954331, 6049.364816694249, 6053.969730151365, 5329.493698098713, 5343.064963631349, 5359.356877174725, 5362.1420572708175, 5370.812021579265, 14303.499377521737, 14317.57047288583, 14318.642033949234, 19373.0, 25859.74140258611, 2114.3551216878845, 2119.448943274744, 2304.9308520744403, 2442.7068751749725, 2694.8787304571147, 9832.0, 9866.90133098667, 9885.505724713736, 10003.148472990593, 10574.841377379345, 1684.7557104219943, 1706.8634509239232, 1749.8162692917188, 1766.152246921485, 1784.9553761155946, 86974.13025869723, 107367.0, 108647.0, 112014.43992779985, 113211.43394283058, 9720.173785228193, 11364.94317528407, 11461.484233209525, 11506.0, 11624.0, 14467.348969296407, 14505.564833055025, 14519.564413067414, 14557.490487832874, 14575.854241457662, 8678.956605216934, 8820.911790882137, 8836.469811811312, 8894.377393582387, 8948.597313120925, 7022.964885175541, 7286.854262914818, 8597.312215022783, 8652.178043085896, 8683.305335573139, 3791.431285307226, 4487.528809475023, 4517.819287228474, 4520.434945631796, 4528.252838281713, 1573.3470609697076, 1899.6960486322123, 1933.419974007792, 1940.9126589303514, 1963.6563601369703, 876.82901834143, 934.1732566678538, 967.5231407596435, 1013.9239557033104, 1028.9794204115876, 18504.907475462536, 19943.202271908962, 20133.19467221324, 20158.59682806365, 20450.693239601416, 979.7011911367101, 1009.7021378693303, 1017.2370721958531, 1026.5226669598671, 1034.8344264917578, 9174.908250917539, 10867.967543083552, 11052.944735276273, 11061.612843550438, 11107.389093599768, 644670.0, 660555.37536489, 660626.6968665126, 663087.0, 668494.0, 73303.0, 97841.51079244558, 100463.0, 111623.0, 117242.0, 990.094063931509, 997.152420442618, 1003.8895721470778, 1020.3622735790132, 1024.2267088348399, 17814.287428502714, 17890.55273618118, 18016.729749053513, 18461.261549537867, 18640.627187456445, 47729.522704773895, 48493.03013939631, 50630.74684626701, 50797.47607571779, 52309.0, 71495.0, 72905.0, 75192.62403688165, 75411.0, 75568.0, 8037.557934313435, 8238.09380968105, 8252.752417427488, 8275.793105172419, 8573.657053717781, 12089.57686480969, 13432.328383580978, 13648.385822638204, 13827.654308641962, 13831.239281839193, 11527.308361498337, 11618.419079046183, 11891.46488408041, 11900.702482438008, 11964.401779910795, 10521.316114452537, 11365.374904380338, 11451.02666273376, 11529.020033296929, 11777.705557361134, 11696.181267311207, 12058.80441682937, 12128.696782580506, 12215.57245496439, 12345.259284442962, 7023.770840102859, 8428.915710842813, 8552.144785521397, 8556.700515481929, 8728.519931403835, 8814.691485797966, 11366.659000230007, 11967.0, 12170.330631815335, 12178.39108044612], "unit": "score", "name": "jquery.html", "value": 9293.809297143505}, {"lowerIsBetter": false, "replicates": [1470.8382077971428, 1590.149270140475, 1622.0673112960046, 1908.4751693284345, 2054.126996026688, 8286.83426331473, 8941.016488186295, 9103.044180361067, 9104.95447522763, 9120.087991200884, 8918.331125165607, 9143.862842057375, 9148.039455857139, 9171.174594286513, 9172.678956236534, 2260.0, 2477.1453848422266, 2535.125381743305, 2545.8854351554223, 2578.8452692838396, 2564.1794625719776, 2568.7816535594498, 2569.087973769313, 2571.0744132112422, 2574.5751950928075, 1898.8955607441585, 2241.1371621925514, 2471.4933438645035, 2546.898124075035, 2557.8721063946728, 2407.7832995030444, 2502.3869152057773, 2506.135383292761, 2532.328932832801, 2532.8353657012235, 2480.131953816168, 2516.559602069649, 2517.760812722807, 2558.283680569425, 2562.500312439075, 9055.864162037575, 9095.863562046574, 9117.772055698595, 9131.406458580172, 9134.08659134088, 5595.524380427669, 5744.913826292609, 5745.48290653839, 5756.136579513107, 5764.279465066867, 3563.5901871284873, 3679.779213247187, 3712.9628703713156, 3735.4957080794015, 3743.7753734776, 1025.3130402630256, 1043.5304113149114, 1048.4443245080045, 1062.4581463453617, 1088.3198001249218, 12353.135280530367, 12354.75290494194, 12365.567205147867, 12368.319742414255, 12382.195157314745, 4930.556249937523, 4997.700137991732, 5034.395872495324, 5039.722815245197, 5099.617528685363, 10467.110295624958, 10529.631462898762, 10531.104856087319, 10531.736706582244, 10543.525541350504, 5666.34836993751, 5703.08750599902, 5713.200151978726, 5721.713914304268, 5728.427157284238, 4332.4367832181615, 4537.3647689323525, 4707.082118986765, 4949.232868905326, 5125.487451254845, 3945.11234972129, 4041.575634558329, 4053.8175782089875, 4068.959310406917, 4108.260513107669, 1184.945398595251, 1225.1240363140398, 1243.608263397027, 1331.6471135149193, 1345.8586848380976, 1121.6747143328507, 1171.437709899236, 1244.3094082783962, 1438.9208593527455, 1449.8260208774805, 8422.831543369099, 8620.439671421338, 8631.913680863237, 8633.309335253165, 8663.306935445149, 506.2482213912337, 520.4769206946994, 522.4279414041619, 526.242211215848, 528.801699362739, 6322.304546499881, 6347.270063942706, 6396.744130234832, 6434.0, 6438.645874476949, 10911.290766100337, 11272.267302625303, 11297.26567773092, 11446.255993360404, 11452.0, 9891.10980011788, 10162.18702503798, 11171.38557379352, 11194.384308862927, 11273.098152147808, 8717.869231961526, 8755.255803256667, 8798.824023519493, 8812.955935220283, 8816.603252853636, 8056.436049476715, 8136.959315203623, 8195.631196596049, 8245.216704413071, 8266.090730019814, 8308.626111824862, 9529.904700952902, 9912.553935072794, 10117.544710488193, 10256.230782691178, 10060.39637621745, 10303.536340864828, 10484.580616775243, 10514.001169888848, 10534.0, 7708.653110610148, 7720.652570634447, 7794.064712234342, 7794.142644309008, 7796.4932279401655, 8483.194096560817, 8551.657933682833, 8569.228769410784, 8582.78543036429, 8586.313094952264, 7504.924950750423, 7634.618269086634, 7677.846443071219, 7789.532628042336, 7842.411819113475, 7476.25237476248, 7486.737964171228, 7510.849783004419, 7673.2710392514855, 7700.922990770021, 8002.599870006593, 8061.677532898619, 8099.676012959416, 8103.797405064684, 8106.189381062082, 7096.893546596909, 7358.448116391185, 7365.742199023009, 7374.852502950018, 7392.297731715478, 6543.70553325111, 6548.508861835287, 6974.616396098263, 7026.156861176692, 7067.893981590383], "unit": "score", "name": "mootools.html", "value": 4974.160588346926}, {"lowerIsBetter": false, "replicates": [334.0179871178734, 338.168106458113, 343.1318763528274, 345.35074060765766, 349.3624135951887, 6708.765193218238, 6710.194776626806, 6748.831279218022, 6763.458923286137, 6774.390304872561, 6666.799996000128, 6694.19669639644, 6696.866062678743, 6702.195736511613, 6739.494537909664, 4745.454272758625, 4848.53938875808, 4868.1237377272255, 5000.324956130912, 5027.597792176617, 4968.925466117993, 4977.029479251547, 4997.275395067713, 5014.247862820588, 5042.747862606873, 4924.655274130812, 4940.555350018498, 4952.5047495250365, 4962.206047032476, 4966.50334966504, 3743.644353786386, 4008.5590585035625, 4690.413698287714, 4774.665773395864, 4976.353074100344, 4265.936010959838, 4267.893302667427, 4276.615104640566, 4279.422277992462, 4285.935710964307, 6441.903371449432, 6452.257990331125, 6454.257760357572, 6475.158229430191, 6496.0, 5888.5289176865745, 5892.086726557391, 5898.852528686774, 5904.143899134644, 5904.793332233351, 6824.249332573413, 7132.643367831588, 7133.108361454818, 7141.25016873226, 7141.678624461912, 4778.784954677048, 4782.545658162469, 4808.471068182497, 4810.494898035726, 4834.85495435134, 7395.593242371668, 7487.588182649952, 7490.962545187239, 7494.962525187339, 7508.3993280537425, 4873.585745211626, 4877.219644856806, 4881.780319885543, 4884.902301954012, 4889.897344142053, 4073.7555746655294, 4426.44669416323, 4683.548100088983, 4953.653244272867, 4955.801767929314, 4779.832705855279, 5626.493615574618, 5858.531317494675, 5863.472287494061, 5879.823605291848, 7131.322524360177, 7156.499045066899, 7159.319864612853, 7160.606166660779, 7193.100862392201, 1682.932682692703, 1724.4740354192093, 1726.18869131508, 1726.292220189716, 1728.6369862329052, 1039.1219419590434, 1069.454578165126, 1099.8900109988938, 1109.7724966381872, 1133.8015847226702, 6476.5466417350335, 6659.700313485808, 6661.633610151392, 6679.86640267192, 6679.933200668028, 7441.665125069385, 7451.105867295851, 7468.477206595595, 7482.4388170887405, 7485.887711684329, 6635.071090047397, 6638.477378596199, 6642.6014439133805, 6643.136392268974, 6647.335266473314, 3221.8066915985114, 3778.8677396290996, 3883.3980732986433, 3909.8045097745567, 3933.704972127102, 7630.35142012924, 7766.883496747553, 7788.0, 7792.961035194788, 7794.415418843496, 6353.682315884187, 7506.361959233417, 7643.579603121771, 7658.234176582298, 7684.154742978268, 7736.187700291388, 7750.379969602531, 7754.457187996899, 7774.611269436618, 7776.300132988172, 6332.70179613169, 6838.38454539074, 7340.926590734024, 7430.591317477374, 7461.291177338144, 7279.308465695751, 7316.634168291458, 7326.743563975236, 7333.339999400079, 7348.742794002184, 6333.556651034383, 7723.7682869514, 7733.4973226740085, 7756.146823849259, 7768.61156942162, 3931.5282166140246, 4575.99328147807, 4862.0, 4865.440474345495, 4872.293517440023, 5995.250593675791, 7091.432685385053, 7367.115946086504, 7381.963090184515, 7383.593902335423, 5824.388439213991, 7134.250903655042, 7134.643267836691, 7134.964325178341, 7136.357727804522, 7067.363937245673, 7100.502964792621, 7103.82240443993, 7117.0, 7144.214136444883, 6931.2375638678695, 6937.0, 6941.965290173517, 6943.9652801735665, 6956.756513522002, 6500.869982600416, 7010.544314619738, 7082.929170708433, 7099.716011359695, 7109.253528379446, 6633.867322653424, 6802.14973128359, 6898.8620227596175, 6940.375366216863, 6975.651217439006], "unit": "score", "name": "prototype.html", "value": 5212.2082053968}, {"lowerIsBetter": false, "replicates": [753.4161025205468, 758.4859947093872, 776.0958483366877, 776.2741836382979, 781.992180078199, 15310.540683779476, 15479.613009674735, 15499.690006199884, 15542.0, 15560.610984725388, 14276.928615356908, 15928.601784955383, 16365.509034728948, 16369.099699516522, 16391.918040409782, 781.1290411191537, 963.9566219520121, 975.6487664440796, 984.6209209454375, 986.3982970388057, 785.2971590426565, 959.7312752429297, 983.233078199006, 983.9065288797552, 985.5367977050774, 1028.542298677089, 1029.0481304793075, 1031.056583226347, 1031.7575369788094, 1032.463119178027, 799.8720204767209, 1013.12364804444, 1013.6756238003842, 1016.4206402350663, 1016.9847452288221, 736.9005184300158, 747.7607165706976, 749.6326799868025, 751.0761763031447, 752.6951584608242, 15568.766468502981, 15637.374505019785, 15648.608784780472, 15669.529914102595, 15725.213739312989, 12776.04179686518, 12804.167729097671, 12935.223886566837, 13117.278549679764, 13249.86750132496, 10192.133668638175, 10225.744356391075, 10362.896371036268, 10529.157667386668, 10683.252172347939, 3433.1760377509218, 3468.167639766438, 3508.1054331145397, 3519.5248641433336, 3720.9813950930343, 83766.32467350618, 85228.0, 85382.0, 85544.0, 85549.14450855536, 8094.311983481352, 9361.531923403803, 9502.952485237529, 9513.0, 9867.358621689567, 17681.204345804214, 21469.78530214709, 21730.0, 21739.021744021557, 23364.532709345716, 7734.92265077342, 7747.30274275318, 7780.18308077655, 7790.5325680459355, 8010.719624813103, 4128.298189307825, 4150.232207041707, 4242.44848169736, 4252.829886804493, 4274.422952901318, 3747.2505498900327, 3748.700103991674, 3794.4118661607495, 3797.6392242736897, 3799.810009499514, 671.5433505216499, 676.3777324861139, 685.674304705261, 686.2588404523103, 686.8042607856737, 513.3301042140043, 592.6562593695646, 611.0193140010308, 614.3273115938041, 628.6196850905228, 7000.264972177848, 8314.708985185489, 8345.374096942754, 8375.078741338448, 8433.704820331259, 526.3525863188299, 553.3636318234063, 588.620339880783, 609.4484491535168, 623.9906401403982, 3153.936921261562, 3732.760343793702, 3776.86780962665, 3864.806759662006, 3898.766074035567, 88535.67196492058, 88896.0, 89453.55273223593, 90021.09978900258, 90267.0, 72327.27672723311, 72509.63745181345, 75044.62477687576, 79709.40581188344, 81730.13944511913, 53079.203811942854, 73980.63009684917, 74208.62895685487, 75286.49427011539, 76459.61770191113, 7967.442279040411, 7974.760757177294, 8839.093970549498, 8847.292216622525, 8851.0, 47359.0, 47862.52137478581, 50531.74734126306, 51630.0, 52901.735491322295, 48714.756426217646, 59891.70054149701, 69325.30674693189, 70840.64579677068, 72360.63819681079, 10881.564737410416, 11282.492287846902, 11289.00976733103, 11415.543378265109, 11554.133439991867, 11916.52333906634, 12062.879371206527, 12066.155369124428, 12368.319742414255, 12489.812652810397, 11642.669604880059, 11683.474243659226, 11690.783704058013, 11732.178747487595, 11783.293002419883, 11327.155427553778, 11788.938995490447, 11792.646220613395, 11795.528178873092, 11837.526498940291, 11491.367974761468, 11496.712582185512, 11539.8269025963, 11743.354115523729, 11930.22453540517, 7822.491538050009, 9604.85592716096, 9677.177439917405, 9705.417674939526, 10064.396136231557, 9749.853752193581, 9903.50482475859, 9945.254105941938, 9946.651867184903, 9973.252006099716], "unit": "score", "name": "yui.html", "value": 7062.12287624224}], "name": "dromaeo_css", "value": 7567.158241601783}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [114.0, 150.0, 162.0, 114.0, 113.0, 112.0, 121.0, 112.0, 110.0, 111.0], "unit": "score", "name": "ai-astar", "value": 121.9}, {"lowerIsBetter": true, "replicates": [154.0, 120.0, 158.0, 148.0, 117.0, 137.0, 119.0, 150.0, 171.0, 146.0], "unit": "score", "name": "audio-beat-detection", "value": 142.0}, {"lowerIsBetter": true, "replicates": [186.0, 183.0, 187.0, 184.0, 188.0, 188.0, 183.0, 185.0, 185.0, 189.0], "unit": "score", "name": "audio-dft", "value": 185.8}, {"lowerIsBetter": true, "replicates": [74.0, 75.0, 75.0, 121.0, 57.0, 57.0, 61.0, 58.0, 122.0, 61.0], "unit": "score", "name": "audio-fft", "value": 76.1}, {"lowerIsBetter": true, "replicates": [93.0, 94.0, 138.0, 80.0, 77.0, 85.0, 122.0, 77.0, 79.0, 122.0], "unit": "score", "name": "audio-oscillator", "value": 96.7}, {"lowerIsBetter": true, "replicates": [88.0, 85.0, 88.0, 85.0, 88.0, 83.0, 88.0, 86.0, 88.0, 85.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 86.4}, {"lowerIsBetter": true, "replicates": [92.0, 93.0, 94.0, 93.0, 93.0, 93.0, 93.0, 93.0, 93.0, 109.0], "unit": "score", "name": "imaging-darkroom", "value": 94.6}, {"lowerIsBetter": true, "replicates": [85.0, 87.0, 90.0, 85.0, 88.0, 86.0, 84.0, 84.0, 84.0, 83.0], "unit": "score", "name": "imaging-desaturate", "value": 85.6}, {"lowerIsBetter": true, "replicates": [60.0, 61.0, 61.0, 58.0, 59.0, 59.0, 59.0, 70.0, 52.0, 54.0], "unit": "score", "name": "json-parse-financial", "value": 59.3}, {"lowerIsBetter": true, "replicates": [61.0, 58.0, 68.0, 78.0, 69.0, 69.0, 67.0, 65.0, 65.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 66.8}, {"lowerIsBetter": true, "replicates": [81.0, 80.0, 81.0, 82.0, 80.0, 79.0, 79.0, 80.0, 80.0, 84.0], "unit": "score", "name": "stanford-crypto-aes", "value": 80.6}, {"lowerIsBetter": true, "replicates": [124.0, 143.0, 131.0, 118.0, 119.0, 118.0, 128.0, 117.0, 139.0, 107.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 124.4}, {"lowerIsBetter": true, "replicates": [137.0, 121.0, 135.0, 133.0, 130.0, 105.0, 155.0, 170.0, 130.0, 134.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 135.0}, {"lowerIsBetter": true, "replicates": [66.0, 67.0, 65.0, 66.0, 65.0, 65.0, 65.0, 66.0, 65.0, 66.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 65.6}], "name": "kraken", "value": 1420.8}]} 15:09:12 INFO - Return code: 0 15:09:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 15:09:12 INFO - # TBPL SUCCESS # 15:09:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:09:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:09:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:09:12 INFO - 2016-04-14 15:09:12.893 screenresolution[2279:20292] starting screenresolution argv=screenresolution get 15:09:12 INFO - 2016-04-14 15:09:12.895 screenresolution[2279:20292] Display 0: 1600x1200x32@60 15:09:12 INFO - 2016-04-14 15:09:12.901 screenresolution[2280:20295] starting screenresolution argv=screenresolution list 15:09:12 INFO - Available Modes on Display 0 15:09:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:09:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:09:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:09:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:09:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:09:13 INFO - Intel Iris: 15:09:13 INFO - Chipset Model: Intel Iris 15:09:13 INFO - Type: GPU 15:09:13 INFO - Bus: Built-In 15:09:13 INFO - VRAM (Dynamic, Max): 1536 MB 15:09:13 INFO - Vendor: Intel (0x8086) 15:09:13 INFO - Device ID: 0x0a2e 15:09:13 INFO - Revision ID: 0x0009 15:09:13 INFO - Displays: 15:09:13 INFO - Display: 15:09:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:09:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:09:13 INFO - Display Serial Number: 1600x1200 60 15:09:13 INFO - Main Display: Yes 15:09:13 INFO - Mirror: Off 15:09:13 INFO - Online: Yes 15:09:13 INFO - Rotation: Supported 15:09:13 INFO - Return code: 0 15:09:13 INFO - Running post-action listener: _resource_record_post_action 15:09:13 INFO - Running post-run listener: _resource_record_post_run 15:09:14 INFO - Total resource usage - Wall time: 1204s; CPU: 52.0%; Read bytes: 393216; Write bytes: 664244736; Read time: 7; Write time: 1915 15:09:14 INFO - install - Wall time: 33s; CPU: 55.0%; Read bytes: 351416832; Write bytes: 353220096; Read time: 27432; Write time: 683 15:09:14 INFO - run-tests - Wall time: 1172s; CPU: 52.0%; Read bytes: 290816; Write bytes: 299551744; Read time: 4; Write time: 1201 15:09:14 INFO - Running post-run listener: _upload_blobber_files 15:09:14 INFO - Blob upload gear active. 15:09:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:09:14 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 15:09:14 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'] 15:09:14 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 15:09:14 INFO - (blobuploader) - INFO - Open directory for files ... 15:09:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:09:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:09:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:09:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:09:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:09:15 INFO - (blobuploader) - INFO - Done attempting. 15:09:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:09:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:09:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:09:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:09:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:09:16 INFO - (blobuploader) - INFO - Done attempting. 15:09:16 INFO - (blobuploader) - INFO - Iteration through files over. 15:09:16 INFO - Return code: 0 15:09:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:09:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:09:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8983ec1b460182855925a625be24b8476bf43843c39ab37682c7c2e07be0c350c98e13025c1df7d8d1fc7611aa6fe07b20ce5234087a355d9cacf925a0951ea2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a78afd8fecdcd238dc12201965a18777a65c95cb1f2e32946fc422ddbde0ec1de6b683180ed01b6a21ba79970f2c114d0e8ced7c0b195dd428edf5b240e1f428"} 15:09:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:09:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:09:16 INFO - Contents: 15:09:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8983ec1b460182855925a625be24b8476bf43843c39ab37682c7c2e07be0c350c98e13025c1df7d8d1fc7611aa6fe07b20ce5234087a355d9cacf925a0951ea2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a78afd8fecdcd238dc12201965a18777a65c95cb1f2e32946fc422ddbde0ec1de6b683180ed01b6a21ba79970f2c114d0e8ced7c0b195dd428edf5b240e1f428"} 15:09:16 INFO - Running post-run listener: copy_logs_to_upload_dir 15:09:16 INFO - Copying logs to upload dir... 15:09:16 INFO - mkdir: /builds/slave/test/build/upload/logs 15:09:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.051728 ========= master_lag: 11.95 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-04-14 15:09:28.879562) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 7 secs) (at 2016-04-14 15:09:28.886661) ========= 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.j9cIY6cGjj/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.4bVxM6Ivwh/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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/8983ec1b460182855925a625be24b8476bf43843c39ab37682c7c2e07be0c350c98e13025c1df7d8d1fc7611aa6fe07b20ce5234087a355d9cacf925a0951ea2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a78afd8fecdcd238dc12201965a18777a65c95cb1f2e32946fc422ddbde0ec1de6b683180ed01b6a21ba79970f2c114d0e8ced7c0b195dd428edf5b240e1f428"} build_url:https://queue.taskcluster.net/v1/task/HGsVpuGcQnGqV1d1aW18CA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011309 build_url: 'https://queue.taskcluster.net/v1/task/HGsVpuGcQnGqV1d1aW18CA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8983ec1b460182855925a625be24b8476bf43843c39ab37682c7c2e07be0c350c98e13025c1df7d8d1fc7611aa6fe07b20ce5234087a355d9cacf925a0951ea2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a78afd8fecdcd238dc12201965a18777a65c95cb1f2e32946fc422ddbde0ec1de6b683180ed01b6a21ba79970f2c114d0e8ced7c0b195dd428edf5b240e1f428"}' ========= master_lag: 7.87 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 7 secs) (at 2016-04-14 15:09:36.763954) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 13 secs) (at 2016-04-14 15:09:36.764269) ========= 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.j9cIY6cGjj/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.4bVxM6Ivwh/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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.005437 ========= master_lag: 13.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 13 secs) (at 2016-04-14 15:09:49.815077) ========= ========= Started reboot skipped (results: 3, elapsed: 2 secs) (at 2016-04-14 15:09:49.815402) ========= ========= Finished reboot skipped (results: 3, elapsed: 2 secs) (at 2016-04-14 15:09:51.946766) ========= ========= Total master_lag: 35.02 =========