builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0164 starttime: 1464140773.01 results: success (0) buildid: 20160524172038 builduid: 9fc0ea4b20ba4ca598a90d24ce8c98d6 revision: 14079be66b19b1fa8c60fa34239bcff57ca51ab0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 18:46:13.011274) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 18:46:13.011676) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 18:46:13.011946) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 18:46:13.056256) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 18:46:13.056623) ========= 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.r1zm1UOQFL/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.FPXQB8jzpw/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-24 18:46:12-- 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% 19.2M=0.001s 2016-05-24 18:46:13 (19.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.562260 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 18:46:13.637973) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 18:46:13.638285) ========= 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.r1zm1UOQFL/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.FPXQB8jzpw/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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.020779 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 18:46:13.686748) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 18:46:13.687051) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 14079be66b19b1fa8c60fa34239bcff57ca51ab0 --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 14079be66b19b1fa8c60fa34239bcff57ca51ab0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.r1zm1UOQFL/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.FPXQB8jzpw/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-24 18:46:13,166 truncating revision to first 12 chars 2016-05-24 18:46:13,166 Setting DEBUG logging. 2016-05-24 18:46:13,166 attempt 1/10 2016-05-24 18:46:13,166 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/14079be66b19?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-24 18:46:14,387 unpacking tar archive at: mozilla-aurora-14079be66b19/testing/mozharness/ program finished with exit code 0 elapsedTime=1.471885 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 18:46:15.178928) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 18:46:15.181737) ========= script_repo_revision: 14079be66b19b1fa8c60fa34239bcff57ca51ab0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 18:46:15.182166) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 18:46:15.182495) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 18:46:15.198421) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 33 secs) (at 2016-05-24 18:46:15.198693) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.r1zm1UOQFL/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.FPXQB8jzpw/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 18:46:14 INFO - MultiFileLogger online at 20160524 18:46:14 in /builds/slave/test 18:46:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 18:46:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:46:14 INFO - {'append_to_log': False, 18:46:14 INFO - 'base_work_dir': '/builds/slave/test', 18:46:14 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 18:46:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:46:14 INFO - 'branch': 'Mozilla-Aurora', 18:46:14 INFO - 'buildbot_json_path': 'buildprops.json', 18:46:14 INFO - 'config_files': ('talos/mac_config.py',), 18:46:14 INFO - 'default_actions': ('clobber', 18:46:14 INFO - 'read-buildbot-config', 18:46:14 INFO - 'download-and-extract', 18:46:14 INFO - 'populate-webroot', 18:46:14 INFO - 'create-virtualenv', 18:46:14 INFO - 'install', 18:46:14 INFO - 'run-tests'), 18:46:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:46:14 INFO - 'download_minidump_stackwalk': True, 18:46:14 INFO - 'download_symbols': 'ondemand', 18:46:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:46:14 INFO - 'tooltool.py': '/tools/tooltool.py', 18:46:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:46:14 INFO - '/tools/misc-python/virtualenv.py')}, 18:46:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:46:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:46:14 INFO - 'installer_path': 'installer.exe', 18:46:14 INFO - 'log_level': 'info', 18:46:14 INFO - 'log_name': 'talos', 18:46:14 INFO - 'log_to_console': True, 18:46:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:46:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:46:14 INFO - 'opt_config_files': (), 18:46:14 INFO - 'pip_index': False, 18:46:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:46:14 INFO - 'cmd': ('bash', 18:46:14 INFO - '-c', 18:46:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:46:14 INFO - 'enabled': True, 18:46:14 INFO - 'halt_on_failure': False, 18:46:14 INFO - 'name': 'check_screen_resolution'},), 18:46:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:46:14 INFO - 'cmd': ('bash', 18:46:14 INFO - '-c', 18:46:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:46:14 INFO - 'enabled': True, 18:46:14 INFO - 'halt_on_failure': False, 18:46:14 INFO - 'name': 'check_screen_resolution'},), 18:46:14 INFO - 'run_cmd_checks_enabled': True, 18:46:14 INFO - 'sps_profile': False, 18:46:14 INFO - 'sps_profile_interval': 0, 18:46:14 INFO - 'suite': 'dromaeojs-e10s', 18:46:14 INFO - 'system_bits': '32', 18:46:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:46:14 INFO - 'title': 't-yosemite-r7-0164', 18:46:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:46:14 INFO - 'use_talos_json': True, 18:46:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:46:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:46:14 INFO - 'work_dir': 'build'} 18:46:14 INFO - ##### 18:46:14 INFO - ##### Running clobber step. 18:46:14 INFO - ##### 18:46:14 INFO - Running pre-action listener: _resource_record_pre_action 18:46:14 INFO - Running main action method: clobber 18:46:14 INFO - rmtree: /builds/slave/test/build 18:46:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:46:16 INFO - Running post-action listener: _resource_record_post_action 18:46:16 INFO - ##### 18:46:16 INFO - ##### Running read-buildbot-config step. 18:46:16 INFO - ##### 18:46:16 INFO - Running pre-action listener: _resource_record_pre_action 18:46:16 INFO - Running main action method: read_buildbot_config 18:46:16 INFO - Using buildbot properties: 18:46:16 INFO - { 18:46:16 INFO - "project": "", 18:46:16 INFO - "product": "firefox", 18:46:16 INFO - "script_repo_revision": "production", 18:46:16 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 18:46:16 INFO - "repository": "", 18:46:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 18:46:16 INFO - "buildid": "20160524172038", 18:46:16 INFO - "pgo_build": "False", 18:46:16 INFO - "basedir": "/builds/slave/test", 18:46:16 INFO - "buildnumber": 336, 18:46:16 INFO - "slavename": "t-yosemite-r7-0164", 18:46:16 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 18:46:16 INFO - "platform": "yosemite_r7", 18:46:16 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 18:46:16 INFO - "branch": "mozilla-aurora", 18:46:16 INFO - "repo_path": "releases/mozilla-aurora", 18:46:16 INFO - "revision": "14079be66b19b1fa8c60fa34239bcff57ca51ab0", 18:46:16 INFO - "stage_platform": "macosx64", 18:46:16 INFO - "builduid": "9fc0ea4b20ba4ca598a90d24ce8c98d6", 18:46:16 INFO - "slavebuilddir": "test" 18:46:16 INFO - } 18:46:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 18:46:16 INFO - Running post-action listener: _resource_record_post_action 18:46:16 INFO - ##### 18:46:16 INFO - ##### Running download-and-extract step. 18:46:16 INFO - ##### 18:46:16 INFO - Running pre-action listener: _resource_record_pre_action 18:46:16 INFO - Running main action method: download_and_extract 18:46:16 INFO - mkdir: /builds/slave/test/build/tests 18:46:16 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')]} 18:46:16 INFO - https://queue.taskcluster.net/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 18:46:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 18:46:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 18:46:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 18:46:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 18:46:46 INFO - Downloaded 1199 bytes. 18:46:46 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 18:46:46 INFO - Using the following test package requirements: 18:46:46 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 18:46:46 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:46:46 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 18:46:46 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:46:46 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 18:46:46 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:46:46 INFO - u'jsshell-mac.zip'], 18:46:46 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:46:46 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 18:46:46 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 18:46:46 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:46:46 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 18:46:46 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:46:46 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 18:46:46 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:46:46 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 18:46:46 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:46:46 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 18:46:46 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 18:46:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:46:46 INFO - https://queue.taskcluster.net/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:46:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:46:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:46:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:46:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 18:46:47 INFO - Downloaded 18425352 bytes. 18:46:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:46:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 18:46:50 INFO - Return code: 0 18:46:50 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 18:46: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')]} 18:46:50 INFO - https://queue.taskcluster.net/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:46:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:46:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:46:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:46:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 18:46:52 INFO - Downloaded 18425352 bytes. 18:46:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:46:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 18:46:55 INFO - Return code: 0 18:46: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')]} 18:46:55 INFO - https://queue.taskcluster.net/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 18:46:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 18:46:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 18:46:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 18:46:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 18:46:56 INFO - Downloaded 10840145 bytes. 18:46:56 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:46:56 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 18:46:56 INFO - Return code: 0 18:46:56 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')]} 18:46:56 INFO - https://queue.taskcluster.net/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 18:46:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 18:46:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 18:46:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 18:46:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 18:47:03 INFO - Downloaded 114137712 bytes. 18:47:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 18:47:03 INFO - mkdir: /builds/slave/test/properties 18:47:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:47:03 INFO - Writing to file /builds/slave/test/properties/build_url 18:47:03 INFO - Contents: 18:47:03 INFO - build_url:https://queue.taskcluster.net/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 18:47:03 INFO - Running post-action listener: _resource_record_post_action 18:47:03 INFO - Running post-action listener: set_extra_try_arguments 18:47:03 INFO - ##### 18:47:03 INFO - ##### Running populate-webroot step. 18:47:03 INFO - ##### 18:47:03 INFO - Running pre-action listener: _resource_record_pre_action 18:47:03 INFO - Running main action method: populate_webroot 18:47:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:47:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:47:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:47:03 INFO - '--tppagecycles', 18:47:03 INFO - '7'], 18:47:03 INFO - 'tests': ['tsvgm']}}, 18:47:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:47:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:47:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:47:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:47:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:47:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:47:03 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:47:03 INFO - 'talos_options': ['--disable-e10s'], 18:47:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:47:03 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:47:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:47:03 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:47:03 INFO - 'talos_options': ['--disable-e10s'], 18:47:03 INFO - 'tests': ['damp', 'tps']}, 18:47:03 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:47:03 INFO - 'tests': ['damp', 'tps']}, 18:47:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:47:03 INFO - 'tests': ['dromaeo_dom']}, 18:47:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:47:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:47:03 INFO - 'tests': ['a11yr', 18:47:03 INFO - 'ts_paint', 18:47:03 INFO - 'tpaint', 18:47:03 INFO - 'sessionrestore', 18:47:03 INFO - 'sessionrestore_no_auto_restore', 18:47:03 INFO - 'tabpaint']}, 18:47:03 INFO - 'other-e10s': {'tests': ['a11yr', 18:47:03 INFO - 'ts_paint', 18:47:03 INFO - 'tpaint', 18:47:03 INFO - 'sessionrestore', 18:47:03 INFO - 'sessionrestore_no_auto_restore', 18:47:03 INFO - 'tabpaint']}, 18:47:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:47:03 INFO - 'ts_paint', 18:47:03 INFO - 'tpaint', 18:47:03 INFO - 'sessionrestore', 18:47:03 INFO - 'sessionrestore_no_auto_restore', 18:47:03 INFO - 'tabpaint']}, 18:47:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:47:03 INFO - 'ts_paint', 18:47:03 INFO - 'tpaint', 18:47:03 INFO - 'sessionrestore', 18:47:03 INFO - 'sessionrestore_no_auto_restore', 18:47:03 INFO - 'tabpaint']}, 18:47:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:47:03 INFO - 'tests': ['a11yr', 18:47:03 INFO - 'ts_paint', 18:47:03 INFO - 'tpaint', 18:47:03 INFO - 'sessionrestore', 18:47:03 INFO - 'sessionrestore_no_auto_restore', 18:47:03 INFO - 'tabpaint']}, 18:47:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:47:03 INFO - 'tests': ['a11yr', 18:47:03 INFO - 'ts_paint', 18:47:03 INFO - 'tpaint', 18:47:03 INFO - 'sessionrestore', 18:47:03 INFO - 'sessionrestore_no_auto_restore', 18:47:03 INFO - 'tabpaint']}, 18:47:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:47:03 INFO - 'tests': ['tsvgx', 18:47:03 INFO - 'tsvgr_opacity', 18:47:03 INFO - 'tart', 18:47:03 INFO - 'tscrollx', 18:47:03 INFO - 'cart']}, 18:47:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:47:03 INFO - 'tsvgr_opacity', 18:47:03 INFO - 'tart', 18:47:03 INFO - 'tscrollx', 18:47:03 INFO - 'cart']}, 18:47:03 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:47:03 INFO - 'talos_options': ['--disable-e10s'], 18:47:03 INFO - 'tests': ['tp5o']}, 18:47:03 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:47:03 INFO - 'tests': ['tp5o']}, 18:47:03 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:47:03 INFO - 'talos_options': ['--disable-e10s', 18:47:03 INFO - '--xperf_path', 18:47:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:47:03 INFO - 'tests': ['tp5n']}, 18:47:03 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:47:03 INFO - 'talos_options': ['--xperf_path', 18:47:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:47:03 INFO - 'tests': ['tp5n']}}, 18:47:03 INFO - 'talos.zip': {'path': '', 18:47:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:47:03 INFO - Running post-action listener: _resource_record_post_action 18:47:03 INFO - ##### 18:47:03 INFO - ##### Running create-virtualenv step. 18:47:03 INFO - ##### 18:47:03 INFO - Running pre-action listener: _resource_record_pre_action 18:47:03 INFO - Running main action method: create_virtualenv 18:47:03 INFO - Creating virtualenv /builds/slave/test/build/venv 18:47:03 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 18:47:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:47:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:47:03 INFO - Using real prefix '/tools/python27' 18:47:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:47:04 INFO - Installing distribute.............................................................................................................................................................................................done. 18:47:07 INFO - Installing pip.................done. 18:47:07 INFO - Return code: 0 18:47:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:47:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:47:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:47:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:07 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 0x103ea3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eb3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc91b587380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d42e00>, '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 0x103babdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d3db20>, '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.r1zm1UOQFL/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.FPXQB8jzpw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 18:47:07 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 18:47:07 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 18:47:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r1zm1UOQFL/Render', 18:47:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:47:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:47:07 INFO - 'HOME': '/Users/cltbld', 18:47:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:47:07 INFO - 'LOGNAME': 'cltbld', 18:47:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:07 INFO - 'MOZ_NO_REMOTE': '1', 18:47:07 INFO - 'NO_EM_RESTART': '1', 18:47:07 INFO - 'PAGER': '/bin/cat', 18:47:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:47:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:07 INFO - 'PWD': '/builds/slave/test', 18:47:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:47:07 INFO - 'SHELL': '/bin/bash', 18:47:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FPXQB8jzpw/Listeners', 18:47:07 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 18:47:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:47:07 INFO - 'USER': 'cltbld', 18:47:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:47:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:47:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:07 INFO - 'XPC_FLAGS': '0x0', 18:47:07 INFO - 'XPC_SERVICE_NAME': '0', 18:47:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:47:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:07 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 18:47:07 INFO - Cleaning up... 18:47:07 INFO - Return code: 0 18:47:07 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 18:47:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:47:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:47:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:07 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 0x103ea3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eb3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc91b587380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d42e00>, '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 0x103babdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d3db20>, '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.r1zm1UOQFL/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.FPXQB8jzpw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 18:47:07 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 18:47:07 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 18:47:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r1zm1UOQFL/Render', 18:47:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:47:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:47:07 INFO - 'HOME': '/Users/cltbld', 18:47:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:47:07 INFO - 'LOGNAME': 'cltbld', 18:47:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:07 INFO - 'MOZ_NO_REMOTE': '1', 18:47:07 INFO - 'NO_EM_RESTART': '1', 18:47:07 INFO - 'PAGER': '/bin/cat', 18:47:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:47:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:07 INFO - 'PWD': '/builds/slave/test', 18:47:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:47:07 INFO - 'SHELL': '/bin/bash', 18:47:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FPXQB8jzpw/Listeners', 18:47:07 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 18:47:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:47:07 INFO - 'USER': 'cltbld', 18:47:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:47:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:47:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:07 INFO - 'XPC_FLAGS': '0x0', 18:47:07 INFO - 'XPC_SERVICE_NAME': '0', 18:47:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:47:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:08 INFO - Downloading/unpacking psutil>=0.7.1 18:47:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:47:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:47:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:47:11 INFO - Installing collected packages: psutil 18:47:11 INFO - Running setup.py install for psutil 18:47:11 INFO - building 'psutil._psutil_osx' extension 18:47:11 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 18:47:11 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 18:47:11 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 18:47:11 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 18:47:11 INFO - building 'psutil._psutil_posix' extension 18:47:11 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 18:47:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:47:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:47:11 INFO - ^ 18:47:11 INFO - 1 warning generated. 18:47:11 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 18:47:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:47:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:47:11 INFO - Successfully installed psutil 18:47:11 INFO - Cleaning up... 18:47:11 INFO - Return code: 0 18:47:11 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 18:47:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:47:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:47:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:11 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 0x103ea3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eb3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc91b587380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d42e00>, '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 0x103babdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d3db20>, '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.r1zm1UOQFL/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.FPXQB8jzpw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 18:47:11 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 18:47:11 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 18:47:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r1zm1UOQFL/Render', 18:47:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:47:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:47:11 INFO - 'HOME': '/Users/cltbld', 18:47:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:47:11 INFO - 'LOGNAME': 'cltbld', 18:47:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:11 INFO - 'MOZ_NO_REMOTE': '1', 18:47:11 INFO - 'NO_EM_RESTART': '1', 18:47:11 INFO - 'PAGER': '/bin/cat', 18:47:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:47:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:11 INFO - 'PWD': '/builds/slave/test', 18:47:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:47:11 INFO - 'SHELL': '/bin/bash', 18:47:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FPXQB8jzpw/Listeners', 18:47:11 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 18:47:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:47:11 INFO - 'USER': 'cltbld', 18:47:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:47:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:47:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:11 INFO - 'XPC_FLAGS': '0x0', 18:47:11 INFO - 'XPC_SERVICE_NAME': '0', 18:47:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:47:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:12 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:47:12 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 18:47:12 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 18:47:12 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 18:47:12 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 18:47:12 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 18:47:12 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 18:47:14 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:47:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:47:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:47:14 INFO - Installing collected packages: mozsystemmonitor 18:47:14 INFO - Running setup.py install for mozsystemmonitor 18:47:14 INFO - Successfully installed mozsystemmonitor 18:47:14 INFO - Cleaning up... 18:47:14 INFO - Return code: 0 18:47:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:47:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:47:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:47:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103ea3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eb3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc91b587380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d42e00>, '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 0x103babdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d3db20>, '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.r1zm1UOQFL/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.FPXQB8jzpw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 18:47:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:47:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:47:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r1zm1UOQFL/Render', 18:47:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:47:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:47:14 INFO - 'HOME': '/Users/cltbld', 18:47:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:47:14 INFO - 'LOGNAME': 'cltbld', 18:47:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:14 INFO - 'MOZ_NO_REMOTE': '1', 18:47:14 INFO - 'NO_EM_RESTART': '1', 18:47:14 INFO - 'PAGER': '/bin/cat', 18:47:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:47:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:14 INFO - 'PWD': '/builds/slave/test', 18:47:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:47:14 INFO - 'SHELL': '/bin/bash', 18:47:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FPXQB8jzpw/Listeners', 18:47:14 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 18:47:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:47:14 INFO - 'USER': 'cltbld', 18:47:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:47:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:47:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:14 INFO - 'XPC_FLAGS': '0x0', 18:47:14 INFO - 'XPC_SERVICE_NAME': '0', 18:47:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:47:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:15 INFO - Downloading/unpacking blobuploader==1.2.4 18:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:17 INFO - Downloading blobuploader-1.2.4.tar.gz 18:47:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:47:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:47:18 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 18:47:18 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 18:47:18 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 18:47:18 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 18:47:18 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 18:47:18 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 18:47:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:47:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:47:18 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 18:47:18 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 18:47:18 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 18:47:18 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 18:47:18 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 18:47:18 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 18:47:19 INFO - Downloading docopt-0.6.1.tar.gz 18:47:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:47:19 INFO - Installing collected packages: blobuploader, requests, docopt 18:47:19 INFO - Running setup.py install for blobuploader 18:47:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:47:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:47:19 INFO - Running setup.py install for requests 18:47:19 INFO - Running setup.py install for docopt 18:47:19 INFO - Successfully installed blobuploader requests docopt 18:47:19 INFO - Cleaning up... 18:47:20 INFO - Return code: 0 18:47:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:47:20 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')]} 18:47:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:20 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')]} 18:47:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:20 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 0x103ea3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eb3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc91b587380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d42e00>, '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 0x103babdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d3db20>, '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.r1zm1UOQFL/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.FPXQB8jzpw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 18:47:20 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 18:47:20 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 18:47:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r1zm1UOQFL/Render', 18:47:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:47:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:47:20 INFO - 'HOME': '/Users/cltbld', 18:47:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:47:20 INFO - 'LOGNAME': 'cltbld', 18:47:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:20 INFO - 'MOZ_NO_REMOTE': '1', 18:47:20 INFO - 'NO_EM_RESTART': '1', 18:47:20 INFO - 'PAGER': '/bin/cat', 18:47:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:47:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:20 INFO - 'PWD': '/builds/slave/test', 18:47:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:47:20 INFO - 'SHELL': '/bin/bash', 18:47:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FPXQB8jzpw/Listeners', 18:47:20 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 18:47:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:47:20 INFO - 'USER': 'cltbld', 18:47:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:47:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:47:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:20 INFO - 'XPC_FLAGS': '0x0', 18:47:20 INFO - 'XPC_SERVICE_NAME': '0', 18:47:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:47:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:47:20 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-viqokY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:47:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:47:20 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-sU4aK9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:47:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:47:20 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-WgXkY_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:47:21 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-3LpyEA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:47:21 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-9ZY676-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:47:21 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-v52K2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:47:21 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-nLLF28-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:47:21 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-ypJYz2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:47:21 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-hNr9jW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:47:21 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-TNumET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:47:21 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-v89OMP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:47:21 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-ZSMNTh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:47:22 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-MCHu92-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:47:22 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-M5D1XW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:47:22 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-0FH5K9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:47:22 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-nVFhZD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:47:22 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-rqIRfL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:47:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:47:22 INFO - Running setup.py install for manifestparser 18:47:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:47:22 INFO - Running setup.py install for mozcrash 18:47:22 INFO - Running setup.py install for mozdebug 18:47:22 INFO - Running setup.py install for mozdevice 18:47:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:47:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:47:23 INFO - Running setup.py install for mozfile 18:47:23 INFO - Running setup.py install for mozhttpd 18:47:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:47:23 INFO - Running setup.py install for mozinfo 18:47:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:47:23 INFO - Running setup.py install for mozInstall 18:47:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:47:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:47:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:47:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:47:23 INFO - Running setup.py install for mozleak 18:47:23 INFO - Running setup.py install for mozlog 18:47:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:47:24 INFO - Running setup.py install for moznetwork 18:47:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:47:24 INFO - Running setup.py install for mozprocess 18:47:24 INFO - Running setup.py install for mozprofile 18:47:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:47:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:47:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:47:24 INFO - Running setup.py install for mozrunner 18:47:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:47:24 INFO - Running setup.py install for mozscreenshot 18:47:24 INFO - Running setup.py install for moztest 18:47:24 INFO - Running setup.py install for mozversion 18:47:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:47:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:47:25 INFO - Cleaning up... 18:47:25 INFO - Return code: 0 18:47:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:47:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:47:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:47:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:25 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 0x103ea3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eb3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc91b587380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d42e00>, '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 0x103babdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d3db20>, '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.r1zm1UOQFL/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.FPXQB8jzpw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 18:47:25 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 18:47:25 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 18:47:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r1zm1UOQFL/Render', 18:47:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:47:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:47:25 INFO - 'HOME': '/Users/cltbld', 18:47:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:47:25 INFO - 'LOGNAME': 'cltbld', 18:47:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:25 INFO - 'MOZ_NO_REMOTE': '1', 18:47:25 INFO - 'NO_EM_RESTART': '1', 18:47:25 INFO - 'PAGER': '/bin/cat', 18:47:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:47:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:25 INFO - 'PWD': '/builds/slave/test', 18:47:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:47:25 INFO - 'SHELL': '/bin/bash', 18:47:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FPXQB8jzpw/Listeners', 18:47:25 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 18:47:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:47:25 INFO - 'USER': 'cltbld', 18:47:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:47:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:47:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:25 INFO - 'XPC_FLAGS': '0x0', 18:47:25 INFO - 'XPC_SERVICE_NAME': '0', 18:47:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:47:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:47:25 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-vu9k3T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:47:25 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)) 18:47:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:47:25 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-pwFUoR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:47:25 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)) 18:47:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:47:25 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-bZbp3V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:47:25 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)) 18:47:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:47:25 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-M9iCXB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:47:25 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)) 18:47:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:47:25 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-npLpbO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:47:25 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)) 18:47:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:47:25 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-T9q8SW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:47:26 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)) 18:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:47:26 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-paPtO2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:47:26 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)) 18:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:47:26 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-ynNa57-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:47:26 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)) 18:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:47:26 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-Ol_mf8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:47:26 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)) 18:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:47:26 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-cX6Q1r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:47:26 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)) 18:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:47:26 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-OmIScr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:47:26 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)) 18:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:47:26 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-r2m4Ut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:47:26 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)) 18:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:47:26 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-oJ73KK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:47:26 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)) 18:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:47:26 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-2bZLX_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:47:26 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)) 18:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:47:26 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-_olJq7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:47:27 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)) 18:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:47:27 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-wyY4Eh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:47:27 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)) 18:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:47:27 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-rSlM5m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:47:27 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)) 18:47:27 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)) 18:47:27 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)) 18:47:27 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)) 18:47:27 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)) 18:47:27 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)) 18:47:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:47:27 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 18:47:27 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 18:47:27 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 18:47:27 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 18:47:27 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 18:47:27 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 18:47:29 INFO - Downloading blessings-1.6.tar.gz 18:47:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:47:29 INFO - Installing collected packages: blessings 18:47:29 INFO - Running setup.py install for blessings 18:47:29 INFO - Successfully installed blessings 18:47:29 INFO - Cleaning up... 18:47:30 INFO - Return code: 0 18:47:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:47:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:47:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:47:30 INFO - Reading from file tmpfile_stdout 18:47:30 INFO - Current package versions: 18:47:30 INFO - blessings == 1.6 18:47:30 INFO - blobuploader == 1.2.4 18:47:30 INFO - docopt == 0.6.1 18:47:30 INFO - manifestparser == 1.1 18:47:30 INFO - mozInstall == 1.12 18:47:30 INFO - mozcrash == 0.17 18:47:30 INFO - mozdebug == 0.1 18:47:30 INFO - mozdevice == 0.48 18:47:30 INFO - mozfile == 1.2 18:47:30 INFO - mozhttpd == 0.7 18:47:30 INFO - mozinfo == 0.9 18:47:30 INFO - mozleak == 0.1 18:47:30 INFO - mozlog == 3.1 18:47:30 INFO - moznetwork == 0.27 18:47:30 INFO - mozprocess == 0.22 18:47:30 INFO - mozprofile == 0.28 18:47:30 INFO - mozrunner == 6.11 18:47:30 INFO - mozscreenshot == 0.1 18:47:30 INFO - mozsystemmonitor == 0.0 18:47:30 INFO - moztest == 0.7 18:47:30 INFO - mozversion == 1.4 18:47:30 INFO - psutil == 3.1.1 18:47:30 INFO - requests == 1.2.3 18:47:30 INFO - wsgiref == 0.1.2 18:47:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:47:30 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')]} 18:47:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:30 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')]} 18:47:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:30 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 0x103ea3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eb3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc91b587380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d42e00>, '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 0x103babdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d3db20>, '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.r1zm1UOQFL/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.FPXQB8jzpw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 18:47:30 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 18:47:30 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 18:47:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r1zm1UOQFL/Render', 18:47:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:47:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:47:30 INFO - 'HOME': '/Users/cltbld', 18:47:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:47:30 INFO - 'LOGNAME': 'cltbld', 18:47:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:30 INFO - 'MOZ_NO_REMOTE': '1', 18:47:30 INFO - 'NO_EM_RESTART': '1', 18:47:30 INFO - 'PAGER': '/bin/cat', 18:47:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:47:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:30 INFO - 'PWD': '/builds/slave/test', 18:47:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:47:30 INFO - 'SHELL': '/bin/bash', 18:47:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FPXQB8jzpw/Listeners', 18:47:30 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 18:47:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:47:30 INFO - 'USER': 'cltbld', 18:47:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:47:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:47:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:30 INFO - 'XPC_FLAGS': '0x0', 18:47:30 INFO - 'XPC_SERVICE_NAME': '0', 18:47:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:47:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:30 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)) 18:47:30 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)) 18:47:30 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)) 18:47:30 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)) 18:47:30 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)) 18:47:30 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)) 18:47:30 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)) 18:47:30 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)) 18:47:30 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)) 18:47:30 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)) 18:47:30 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)) 18:47:30 INFO - Cleaning up... 18:47:30 INFO - Return code: 0 18:47:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:47:30 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')]} 18:47:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:30 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')]} 18:47:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:30 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 0x103ea3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eb3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc91b587380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d42e00>, '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 0x103babdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d3db20>, '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.r1zm1UOQFL/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.FPXQB8jzpw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 18:47:30 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 18:47:30 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 18:47:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r1zm1UOQFL/Render', 18:47:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:47:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:47:30 INFO - 'HOME': '/Users/cltbld', 18:47:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:47:30 INFO - 'LOGNAME': 'cltbld', 18:47:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:30 INFO - 'MOZ_NO_REMOTE': '1', 18:47:30 INFO - 'NO_EM_RESTART': '1', 18:47:30 INFO - 'PAGER': '/bin/cat', 18:47:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:47:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:30 INFO - 'PWD': '/builds/slave/test', 18:47:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:47:30 INFO - 'SHELL': '/bin/bash', 18:47:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FPXQB8jzpw/Listeners', 18:47:30 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 18:47:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:47:30 INFO - 'USER': 'cltbld', 18:47:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:47:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:47:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:30 INFO - 'XPC_FLAGS': '0x0', 18:47:30 INFO - 'XPC_SERVICE_NAME': '0', 18:47:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:47:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:30 INFO - Downloading/unpacking jsonschema 18:47:30 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 18:47:30 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 18:47:30 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 18:47:30 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 18:47:30 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 18:47:30 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 18:47:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:47:33 INFO - Downloading/unpacking functools32 (from jsonschema) 18:47:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:33 INFO - Downloading functools32-3.2.3-2.tar.gz 18:47:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:47:33 INFO - warning: no files found matching '*.txt' 18:47:33 INFO - no previously-included directories found matching 'build' 18:47:33 INFO - no previously-included directories found matching 'dist' 18:47:33 INFO - no previously-included directories found matching '.git*' 18:47:33 INFO - Installing collected packages: jsonschema, functools32 18:47:33 INFO - Running setup.py install for functools32 18:47:34 INFO - warning: no files found matching '*.txt' 18:47:34 INFO - no previously-included directories found matching 'build' 18:47:34 INFO - no previously-included directories found matching 'dist' 18:47:34 INFO - no previously-included directories found matching '.git*' 18:47:34 INFO - Successfully installed jsonschema functools32 18:47:34 INFO - Cleaning up... 18:47:34 INFO - Return code: 0 18:47:34 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:47:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:47:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:47:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:34 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 0x103ea3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eb3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc91b587380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d42e00>, '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 0x103babdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d3db20>, '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.r1zm1UOQFL/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.FPXQB8jzpw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 18:47:34 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 18:47:34 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 18:47:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r1zm1UOQFL/Render', 18:47:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:47:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:47:34 INFO - 'HOME': '/Users/cltbld', 18:47:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:47:34 INFO - 'LOGNAME': 'cltbld', 18:47:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:34 INFO - 'MOZ_NO_REMOTE': '1', 18:47:34 INFO - 'NO_EM_RESTART': '1', 18:47:34 INFO - 'PAGER': '/bin/cat', 18:47:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:47:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:34 INFO - 'PWD': '/builds/slave/test', 18:47:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:47:34 INFO - 'SHELL': '/bin/bash', 18:47:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FPXQB8jzpw/Listeners', 18:47:34 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 18:47:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:47:34 INFO - 'USER': 'cltbld', 18:47:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:47:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:47:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:34 INFO - 'XPC_FLAGS': '0x0', 18:47:34 INFO - 'XPC_SERVICE_NAME': '0', 18:47:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:47:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:34 INFO - Downloading/unpacking flake8 18:47:34 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 18:47:34 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 18:47:34 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 18:47:34 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 18:47:34 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 18:47:34 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 18:47:36 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:47:36 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:47:36 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 18:47:36 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 18:47:36 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 18:47:36 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 18:47:36 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 18:47:36 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 18:47:37 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:47:37 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:47:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:37 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:47:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:47:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:47:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:47:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:47:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:47:38 INFO - no previously-included directories found matching 'docs/_build' 18:47:38 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:47:38 INFO - Running setup.py install for pep8 18:47:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:47:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:47:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:47:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:47:38 INFO - no previously-included directories found matching 'docs/_build' 18:47:38 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:47:38 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:47:38 INFO - Cleaning up... 18:47:38 INFO - Return code: 0 18:47:38 INFO - Running post-action listener: _resource_record_post_action 18:47:38 INFO - Running post-action listener: _start_resource_monitoring 18:47:38 INFO - Starting resource monitoring. 18:47:38 INFO - ##### 18:47:38 INFO - ##### Running install step. 18:47:38 INFO - ##### 18:47:38 INFO - Running pre-action listener: _resource_record_pre_action 18:47:38 INFO - Running main action method: install 18:47:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:47:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:47:38 INFO - Reading from file tmpfile_stdout 18:47:38 INFO - Detecting whether we're running mozinstall >=1.0... 18:47:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:47:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:47:38 INFO - Reading from file tmpfile_stdout 18:47:38 INFO - Output received: 18:47:38 INFO - Usage: mozinstall [options] installer 18:47:38 INFO - Options: 18:47:38 INFO - -h, --help show this help message and exit 18:47:38 INFO - -d DEST, --destination=DEST 18:47:38 INFO - Directory to install application into. [default: 18:47:38 INFO - "/builds/slave/test"] 18:47:38 INFO - --app=APP Application being installed. [default: firefox] 18:47:38 INFO - mkdir: /builds/slave/test/build/application 18:47:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:47:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 18:48:10 INFO - Reading from file tmpfile_stdout 18:48:10 INFO - Output received: 18:48:10 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 18:48:10 INFO - Running post-action listener: _resource_record_post_action 18:48:10 INFO - ##### 18:48:10 INFO - ##### Running run-tests step. 18:48:10 INFO - ##### 18:48:10 INFO - Running pre-action listener: _resource_record_pre_action 18:48:10 INFO - Running main action method: run_tests 18:48:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:48:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:48:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:48:10 INFO - 2016-05-24 18:48:10.577 screenresolution[2206:63358] starting screenresolution argv=screenresolution get 18:48:10 INFO - 2016-05-24 18:48:10.580 screenresolution[2206:63358] Display 0: 1600x1200x32@60 18:48:10 INFO - 2016-05-24 18:48:10.586 screenresolution[2207:63360] starting screenresolution argv=screenresolution list 18:48:10 INFO - Available Modes on Display 0 18:48:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:48:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:48:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:48:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:48:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:48:10 INFO - Intel Iris: 18:48:10 INFO - Chipset Model: Intel Iris 18:48:10 INFO - Type: GPU 18:48:10 INFO - Bus: Built-In 18:48:10 INFO - VRAM (Dynamic, Max): 1536 MB 18:48:10 INFO - Vendor: Intel (0x8086) 18:48:10 INFO - Device ID: 0x0a2e 18:48:10 INFO - Revision ID: 0x0009 18:48:10 INFO - Displays: 18:48:10 INFO - Display: 18:48:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:48:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:48:10 INFO - Display Serial Number: 1600x1200 60 18:48:10 INFO - Main Display: Yes 18:48:10 INFO - Mirror: Off 18:48:10 INFO - Online: Yes 18:48:10 INFO - Rotation: Supported 18:48:10 INFO - Return code: 0 18:48:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:48:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:48:10 INFO - Python 2.7.3 18:48:10 INFO - Return code: 0 18:48:10 INFO - grabbing minidump binary from tooltool 18:48:10 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')]} 18:48:10 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 0x103d42e00>, '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 0x103babdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d3db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:48:10 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 18:48:10 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 18:48:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:48:10 INFO - Return code: 0 18:48:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:48:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:48:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:48:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:48:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:48:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:48:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:48:12 INFO - Return code: 0 18:48:12 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-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0164', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 18:48:12 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-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0164 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 18:48:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r1zm1UOQFL/Render', 18:48:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:48:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:48:12 INFO - 'HOME': '/Users/cltbld', 18:48:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:48:12 INFO - 'LOGNAME': 'cltbld', 18:48:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:48:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:48:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:12 INFO - 'MOZ_NO_REMOTE': '1', 18:48:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:48:12 INFO - 'NO_EM_RESTART': '1', 18:48:12 INFO - 'PAGER': '/bin/cat', 18:48:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:48:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:12 INFO - 'PWD': '/builds/slave/test', 18:48:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:48:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:48:12 INFO - 'SHELL': '/bin/bash', 18:48:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FPXQB8jzpw/Listeners', 18:48:12 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 18:48:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:48:12 INFO - 'USER': 'cltbld', 18:48:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:48:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:48:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:12 INFO - 'XPC_FLAGS': '0x0', 18:48:12 INFO - 'XPC_SERVICE_NAME': '0', 18:48:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:48:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0164', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 18:48:12 INFO - mozversion application_buildid: 20160524172038 18:48:12 INFO - mozversion application_changeset: 14079be66b19b1fa8c60fa34239bcff57ca51ab0 18:48:12 INFO - mozversion application_display_name: FirefoxDeveloperEdition 18:48:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:48:12 INFO - mozversion application_name: Firefox 18:48:12 INFO - mozversion application_remotingname: firefox-dev 18:48:12 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:48:12 INFO - mozversion application_vendor: Mozilla 18:48:12 INFO - mozversion application_version: 48.0a2 18:48:12 INFO - mozversion platform_buildid: 20160524172038 18:48:12 INFO - mozversion platform_changeset: 14079be66b19b1fa8c60fa34239bcff57ca51ab0 18:48:12 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:48:12 INFO - mozversion platform_version: 48.0a2 18:48:12 INFO - using testdate: 1464140892 18:48:12 INFO - actual date: 1464140892 18:48:12 INFO - starting webserver on 'localhost:50782' 18:48:12 INFO - SUITE-START | Running 2 tests 18:48:12 INFO - TEST-START | dromaeo_css 18:48:12 INFO - operating with platform_type : mac_ 18:48:12 INFO - Initialising browser for dromaeo_css test... 18:48:12 INFO - TEST-INFO | started process 2225 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmp9iIO3j/profile http://localhost:50782/getInfo.html) 18:48:15 INFO - PROCESS | 2225 | __metrics Screen width/height:1600/1200 18:48:15 INFO - PROCESS | 2225 | colorDepth:24 18:48:15 INFO - PROCESS | 2225 | Browser inner width/height: 1024/689 18:48:15 INFO - PROCESS | 2225 | __metrics 18:48:15 ERROR - PROCESS | 2225 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 18:48:15 INFO - TEST-INFO | 2225: exit 0 18:48:15 INFO - Browser initialized. 18:48:15 INFO - Running cycle 1/1 for dromaeo_css test... 18:48:15 INFO - TEST-INFO | started process 2228 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmp9iIO3j/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:48:27 INFO - PROCESS | 2228 | RSS: Main: 221085696 18:48:27 INFO - PROCESS | 2228 | 18:49:17 INFO - PROCESS | 2228 | 1464140957637 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] 18:51:28 INFO - PROCESS | 2228 | Cycle 1(1): loaded http://localhost:50782/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50782/tests/dromaeo/cssquery-ext.html) 18:51:30 INFO - PROCESS | 2228 | RSS: Main: 231739392 18:51:30 INFO - PROCESS | 2228 | 18:54:32 INFO - PROCESS | 2228 | Cycle 1(1): loaded http://localhost:50782/tests/dromaeo/cssquery-ext.html (next: http://localhost:50782/tests/dromaeo/cssquery-jquery.html) 18:54:32 INFO - PROCESS | 2228 | RSS: Main: 243834880 18:54:32 INFO - PROCESS | 2228 | 18:57:34 INFO - PROCESS | 2228 | Cycle 1(1): loaded http://localhost:50782/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50782/tests/dromaeo/cssquery-mootools.html) 18:57:35 INFO - PROCESS | 2228 | RSS: Main: 245243904 18:57:35 INFO - PROCESS | 2228 | 19:00:36 INFO - PROCESS | 2228 | Cycle 1(1): loaded http://localhost:50782/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50782/tests/dromaeo/cssquery-prototype.html) 19:00:37 INFO - PROCESS | 2228 | RSS: Main: 246996992 19:00:37 INFO - PROCESS | 2228 | 19:03:40 INFO - PROCESS | 2228 | Cycle 1(1): loaded http://localhost:50782/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50782/tests/dromaeo/cssquery-yui.html) 19:03:40 INFO - PROCESS | 2228 | RSS: Main: 249626624 19:03:40 INFO - PROCESS | 2228 | 19:06:43 INFO - PROCESS | 2228 | Cycle 1(1): loaded http://localhost:50782/tests/dromaeo/cssquery-yui.html (next: http://localhost:50782/tests/dromaeo/cssquery-dojo.html) 19:06:43 INFO - PROCESS | 2228 | RSS: Main: 251011072 19:06:43 INFO - PROCESS | 2228 | 19:06:43 INFO - PROCESS | 2228 | __start_tp_report 19:06:43 INFO - PROCESS | 2228 | _x_x_mozilla_page_load 19:06:43 INFO - PROCESS | 2228 | _x_x_mozilla_page_load_details 19:06:43 INFO - PROCESS | 2228 | |i|pagename|runs| 19:06:43 INFO - PROCESS | 2228 | |0;dojo.html;3208.454562724337;3469.1673998240412;3503.6496350364982;3516.929661406772;3533.081398836302;5726.3700992890745;5756.625819321752;5765.827025189245;5771.451712087355;5812.854678633036;5708.857278568027;5725.427457254273;5748.5975981681295;5771.595988280822;5805.303363596364;4467.709598876079;5209.088409528317;5243.475652434755;5324.787008519655;5324.840254792343;4575.084983003413;5150.635081703352;5203.55769759569;5332.840014799563;5356.330458692663;5296.735163241822;5312.681239125645;5318.627696061278;5323.467653234665;5331.653442526223;5232.843014709546;5298.629095963284;5339.466053394649;5361.303030605996;5386.353637563518;5174.249733788617;5282.3925248596515;5283.207518872162;5290.55030322423;5320.600954928396;5574.749136288877;5619.241402410663;5649.830505084854;5709.3434255060365;5722.313322401338;6195.783147589813;6196.318404975495;6222.408871157233;6267.091271765614;6288.245410550763;6237.719302631393;6256.343083976162;6278.968605156991;6284.27730810958;6292.4966002719675;5742;5827.475527202572;5856.063029915235;5882.617629854046;5885.352611212806;6323.399277068671;6382.393672601095;6395.968020159962;6403.711832967434;6415.518836087312;6206.882761103045;6221.471174950089;6222.688865556704;6231.781887633911;6241.687915604201;6123.892747182667;6148.1085242639565;6242.719077641517;6278.905816412757;6280.05799130125;6168.0439531872635;6177.2587289525545;6180.536459765535;6186.597871138361;6190.0405437157315;6155.538334624832;6214.689265536705;6215.223097112861;6220.688965551704;6224.128621992924;4822.083804077214;4947.6289278304275;4968.205087186033;4992.90014199714;5043.36957880265;4422.181896349186;4422.469303683514;4448.332750087513;4458.509563948028;4488.147252022105;5763.106718458645;5763.682997435181;5790.913136302959;5800.303963524404;5805.767769289266;6162.691865406801;6174.845628859314;6235.282942461584;6244.781432649836;6297;5741.62679425836;5756.7121643917635;5768.307803063659;5778.479936805707;5784.248047753849;4678.883027924261;5333.7066461345;5436.91844622331;5445.019896418683;5446.291982042309;6373.808785736435;6375.585586936927;6412.903806443001;6439.517036222208;6488.480921526361;6228.688565571612;6260.248770147429;6263.404976527223;6275.152854364603;6278.560500764903;6313.842153946188;6385.904211436925;6402.711877965409;6403.455706264834;6415.871682566416;5093.363329583802;6138.232720909887;6167.938320616737;6193.783217587363;6213.720382582705;4459.620932220798;4882.804687812448;5863.560232982458;6075.969620151871;6238.157848690369;6046.848828779316;6056.424639659225;6061.242344706911;6067.757289708362;6072.787452439144;6109.908351374822;6118.741008805849;6125.165117264519;6135.851732731318;6146.832101900624;5950.583459157991;5999.52003839683;6052;6056.90914636272;6062.030075187949;6023.247094113236;6118.632882027093;6164.599301045418;6174.475169610633;6180.783672571439;5875.412458754261;5876.08920617305;5940.435658612425;5941.405859414024;5974.701264936823 19:06:43 INFO - PROCESS | 2228 | |1;ext.html;479.76971053894107;495.0766819880921;532.8055259830162;537.9542738867196;547.8082671065125;38072.61927380726;39608.80195599025;39620.40569391462;39703.60296397042;39780.80657580274;42503;43067.92330191739;43206.703798886;43245.7837710811;43347.56652433475;9438.764030899214;9599.568019439108;9640;9711.902880971205;9726.951365243163;9546.856797148048;9623.37448065877;9650.662226822064;9683.22534197266;9699.321047526675;9649.324547281693;9655.662051828187;9681.080297371773;9700.320977531575;9740.902590974107;8737.737867863974;9631.903680963205;9700.805983880353;9720.368176068532;9721.951390243074;9598.90401095987;9680.75798105046;9680.75798105046;9698.9515052425;9714.9514252429;39111;39458;39930.20139597221;39959.60040399617;40055.59944400577;3116.3144108296365;3298.967010329914;3377.0037838837357;3389.440742277521;3697.556293244828;28552;28745.56881646777;29241.707582924322;29261.853690731623;29948;2716.483868065081;2749.7112803155583;2774.819636723627;2795.7204279572084;2829.547272436421;463604.09187724424;467156;470561;471184;471222.6438867818;54185.64535886634;55972;63000.99999999908;64267;64753.67623161854;63139;65947.01079483812;68478;70139.64930175316;70146.89559313229;14004.929975350058;17576.648467030587;17693.20380582877;17697.911510442624;17744.91127544354;13222.537211197561;13365.26491043002;13438.798418023738;13441.126326788728;13494.583068777833;16073.517794465952;17655.117244137742;19315.323963661205;19576.90211548933;19718.70421943672;6578.76974305897;6899.275576064541;7034.015237866703;7382.335589796944;7997.280244777998;924.902885197058;979.9608015679293;1059.433203236265;1073.3077165228383;1081.8755843078147;30700.385992280477;30748.923787667325;31056.844715776275;31190.376192476022;31264.4044342014;1012.8733908261468;1094.7372630568766;1128.5711429656678;1154.9201496600706;1184.490669012333;14376;15503.844961550465;15889;16102.19489025544;16137.838621613868;608430;616114;618916;619460;631246;60632.696836516414;61791;62769.68615156986;62867.6856615714;63029.05456418158;1289.8903593194684;1485.2202593638426;1512.5840393891767;1513.3341329815066;1525.5042111313867;29900.551491728078;30597.847010764803;30938.381232375676;31166.844165779024;31206.531902021034;55372.89254214974;55389.44610553843;55795.72102139463;55872.161917570455;56070;551664;553686;557952;558764;559189;27711.584326235527;28099.157025289274;28116.156515304574;28146.85926570354;28279.575806363333;47185.58443246708;47247.29129063136;48247.5175248243;48256.793580160775;48811;37460.81269593634;39111.60888391193;39124.804375979074;39688.80155599204;40595.39106913458;37665.81167094257;38721.80639096787;38740.41889371605;38742.031449213995;38755.61244387633;37834.81082594569;38271.85184444471;38546.6145338543;38696.613033870424;39315.410268845415;29655.40689186161;34015.48976765301;34411.82794086014;34783.4782478258;34825.65174348224;20747;25589.616155758053;25653.97384104615;25993.870030649727;26009.739902600733 19:06:43 INFO - PROCESS | 2228 | |2;jquery.html;367.5057048270078;382.911930256041;391.74340806771556;399.0781295208069;415.0184812917451;19276.3253286135;20648.690269645966;21420.25029123981;21529.461763455904;21674.916254187287;16831;19779.208831646753;20710.27514037009;20935.581288374222;21151.259705910295;4604.953950460503;5286.841394758154;5471.753771080311;5475.397706252309;5479.397266300703;5556.444355564431;5903.409659034084;5903.557233207505;5907.202527658754;5913.14259432382;5811.709414529278;5856.970715146418;6437.935620643781;6644.734210631593;6644.833879153036;6555.6394398308075;6571.769988050395;6600.306967768363;6617.602943823362;6626.105475760759;5223.2165175223645;5280.785419353532;5639.8590035249035;5681.659100453986;5797.971010144965;13155.276459794708;13442;13607.115537490032;21442.678359824615;26205.60691589628;2181.967270490944;2184.3010236724253;2214.9889250553806;2242.473018840571;2754.1462146734575;9762.853557196648;9912.504374781232;9927.453990030546;10022.3485473444;10276.89723102767;1632.526567295483;1676.270822192338;1725.3271224222563;1769.6903041967605;1809.80092189859;106053.93946060518;107319;110838;111497.44251278773;111994.44002779934;9570.90429095714;11264.38045907483;11438.485268162953;11723.358729777838;11808;15169.938104332812;15322.15728134941;15356.308966096552;15395.692086158215;15396.230188490754;9147.496887671108;9177.403468774508;9202.30982676302;9229.815403692022;9240.445573265626;8118;8845.115488451103;9180.586873590704;9245.029271926485;9287.303452241109;4746.620270378361;4784.354112194878;4787.329773831666;4820.060088282821;4826.203676393356;1779.0215381540102;1994.2920263306607;2043.928462503835;2061.721667574888;2072.191845180381;837.5058715357968;933.4259430450224;946.370663508759;978.858065580501;1033.204432586904;20353.185872565227;21126.154953801983;21187.258445954318;21225.575488490143;21269.574608507744;974.4348277998744;981.7987312601042;983.2527279267808;1051.9316244444087;1061.1192710050639;10900.84102482111;11327.037201837771;11328.490217940047;11396.601118960962;11409.859014098689;548884;550456;550774;557010;562512;91926;108216.4589177049;110365.44817276023;119795.40102299607;121010.3949480247;955.527014128011;966.9033096690415;989.366805244644;990.1435258501344;993.1310103659298;14412.630800073975;17045.48863534096;17828.10859457048;17937.730934036263;18061.819381806014;35745.64254357527;48006.039879202915;50148.247776284115;51385.48614513807;52106.47893521168;68400;69968.60062798613;75735.62132189304;76414.61792691001;76533.46933061419;7858.332041776284;7874.055113386201;8003.83992320162;8050.235227653364;8105.148959359182;9968.50157492108;11325.15061370384;12007.879921201025;12181.695457613276;12389.876101238871;10297.845532316871;10556.683299501028;10714.303570267908;10739.677809665722;10807.297525660806;10143;10268.58925642966;10460.68617941463;10695.411752353384;10759.623413180501;3999.540052893838;5598.692071936082;9911.702648920544;10579.62971296001;11120.665520137636;7949.92050079515;7983.20167983197;8009.078955920144;8128.0246370435925;8133.7966550834335;8060.677572897019;9775.21798256152;10902.18233632465;10998.06516446079;11028.503717332578 19:06:43 INFO - PROCESS | 2228 | |3;mootools.html;1365.6858922447836;1580.652256503569;1749.5101371615945;1828.5702859827938;1938;7685.654145563449;8291.668333266678;8435;8627.654893804249;8636.611352489144;8295.91704082959;8348.91651083489;8389.538575378352;8391.958040209805;8418.95790521048;2215.656573231148;2389.8528706221023;2440.3411079008656;2441.7192022917325;2443.30365845734;2122;2397.5924092904247;2446.388402899275;2448.2532827487607;2449.510097980402;2389.4026493376655;2411.8794060297005;2412.7828495435406;2457.5207834472194;2464.9260522184272;2405.338531903723;2432.282476669378;2435.9634605480933;2436.196055301746;2450.142450142445;2051.7640471345835;2399.208261273793;2408.915687950931;2409.4337830609966;2444.2545023767743;8524.977002759708;8554.48673079611;8559.486430814171;8575.271101956341;8700.608472618747;5181.818636347747;5310.60170487215;5341.412444631087;5351.7056561889085;5359.785608575652;3401.710854577339;3408.5057666638445;3453.740969427303;3496.283261931298;3537.168765340143;1016.8525563793208;1019.4851599942056;1038.693585392311;1039.0285083447002;1042.5517027678095;11351.886481135165;11447.370394628291;11475.426228688531;11491.65525034242;11532.711682207928;4268.530461649216;4513.390692256569;4622.2604383299185;4765.761711914391;4770.308305295713;9887.604495820087;9910.504474776233;10000.79998400028;10005.04952029556;10005.649802256887;5206;5305.151175811851;5365.919511207335;5366.892662146814;5373.978944000705;4158.875233742992;4339.284018136972;4555.362249285103;4857.975710121497;4870.805167793319;3383.543221665044;3478.8434520446135;4019.376996565537;4027.899302517402;4118.814653340607;1337.7592033434075;1348.3797453171455;1348.8179095822134;1352.2562590575144;1352.905296629246;815.8205194857122;857.4683696108452;863.935204859638;955.9761005974767;965.165132160676;4744.857654270378;5098.566621837112;5857.824265272048;5898.705064746831;6029.155918171459;873.2664561768145;1050.4747626186906;1084.8318510630866;1091.9945400272948;1094.6059418609295;3094.6131733533307;3764.3976963686227;3805.353089974711;3853.807309634507;3891.124496988155;10065.34575252621;10288.2798204125;10363.844542331872;10417.218708596885;10434.37393756377;10233.539490722935;10316.587336506605;10319.742006449898;10391.84412233817;10646;8104.149064348277;8212.46618969765;8232.917670823215;8251.463654862415;8273.917260827435;5550.639208451437;5637.718114094197;5712.400197979152;5800.436302782096;5864.560157988253;9251.629934802533;9395.812083758423;9402.905970940477;9472.810543789223;9498.00270971538;8719.912800872164;9264.258859291105;9623.326367154232;9675.467849268358;9723.562439690373;6345.8730825384155;6624.337566243337;6781.6270105144695;6904.758333458305;7057.470689698396;7957.5225486471;7981.92018079835;8036.357091432788;8054.275115239657;8065.516069035877;7085.326893945067;7248.456365772483;7250.637468126678;7272.636368181464;7365.300296471826;7104.502684812013;7137.10786151731;7137.179224389261;7158;7173.390261827803;6692.431143352675;6910.2398736137975;6911.136107986502;6943.132108486439;6948.096747422803;4681.625469962463;4694.2254528002495;4731.148393289241;4834.685745426536;4895.118878601886;4825.372701548777;4866.951330486791;4873.269009648581;4927.211646136601;4929.605631549539 19:06:43 INFO - PROCESS | 2228 | |4;prototype.html;316.1101499279528;316.12433559041443;326.31311090155225;328.59090432411654;328.9144822346189;4666.253399456088;5285.546474719454;6479.092926990225;6585.078089067522;6715.194176698791;5328.733563321838;5342.519173274404;6640.369164929336;6662.400383965442;6706.295838936914;4225.070484493406;4368.301071828508;4450.955490445086;4986.551210391064;4998.850034498971;4977.228529577903;4985.775640096204;4996.125678006334;4998.475160108191;5012.147934851084;4922.827701030465;4934.555889969902;4937.136001199776;4945.455999840033;4951.356323677916;4630.953690463086;4923.950760492403;4953.504649535018;4956.231784073457;4975.577075948549;4005.399190121476;4067.3085575452246;4069.694772892045;4071.145059537471;4086.2849001424634;6287;6318.2733985591785;6444.838879028015;6453.451456626192;6482.513811464159;5677.858053548653;5681.545476361881;5695.117256825199;5699;5705.117311287266;7026.824329391755;7084.752033678848;7130.358267755875;7131.144262688511;7132.465065120085;4580.198465268598;4586.65600079992;4587.24310488769;4591.632669386441;4597.333386658949;7366.77899663011;7430.034095567596;7473.51422157558;7474.4767866249385;7479;4787.40157480315;4794.2808578712775;4795.496472870368;4801.759912004386;4807.95192048075;4865.732384718804;4876.561109500091;4882.414110306715;4882.609391248691;4887.340209071801;5804.88390232193;5816.1566572846705;5817.301923769175;5818.156367326714;5834;5766.596338256281;6888.690008949509;7180.7845764627145;7190.820229494305;7199.784006479814;1701.3789966662328;1708.633093525175;1729.9394521191698;1730.8528775054262;1736.0451751536605;1089.6022951622601;1104.9723756906142;1120.3334016260312;1122.4836575175348;1124.6794663520857;6400.487960963112;6461.19235095613;6585.506087043491;6648.268690444047;6675.332466753286;7464.029676142067;7472.14070381913;7475.70097196117;7504.887426688604;7524.209957954446;6638.4689224861895;6641.369069938348;6642.933570664328;6659.9001014984815;6665.333466653393;3920.8039598019986;3926.332523471017;3961.425593288957;3973.0663294125857;3974.6224108709625;7676.769696909102;7981.047012605373;7989.121196668362;7999.120096789348;8025.07711613172;7781;7791.142974272825;7812.648430820626;7820.491668041559;7831.099423566363;7783.221677832172;7935.722249721348;7940.6029698515995;7955.7215497458465;7981.401394895406;7181.569105853666;7425.071866016748;7551.358134558624;7573.053368328959;7599.65801538921;7269.7819065426;7313.963430182815;7322.352470694264;7339.155997060193;7404.111506619241;6489.8702025960165;7740.071191457061;7918.881216781868;7926.722564710208;7931.968844050237;4644.953550464452;4680.368150299666;4689.179393606173;4705.764761738147;4714.66997310185;5832.822279979317;5911;6358.1734374531015;6762.087118238975;7374.557526548424;7142.892856607259;7185.748498802517;7219.45854060937;7221.494495385273;7227.7831665050135;5738.483536481736;7182.497225194186;7186.7125314986815;7209.170945341143;7210.351068403869;5816.883662326645;6129.172561704291;6482.513811464065;6984.6158461283085;7002;6912.377885990084;7018.859622807618;7108.218096009538;7160.749373771893;7231.746888858865;5439.836804895859;6141.662208578571;6300.558960872695;7003.54476958996;7074.327938845801 19:06:43 INFO - PROCESS | 2228 | |5;yui.html;623.9555419315924;654.744649586661;680.0343512212658;706.2654838967474;710.2187593646992;13713.794293085612;14560.927195364022;14760.557183284493;14990.475333363334;15000.399984000627;13602.523911663095;13731.107478013922;13742.587722368346;13754.449822007107;13797.51708690196;743.2418932688654;900.7838118851494;912.110692075228;925.0749250749251;937.8921424036255;916.2303664921465;918.5085979001236;921.3780698028837;924.3113880159291;924.3529529329462;942.5240253671886;971.4996776660025;973.4305431322684;974.1816873825985;978.3885071830106;931.8462453695132;932.6549176804558;934.2105920497169;949.5299826585815;970.3450171134491;695.8469136789948;716.1513606376409;721.1238345410352;727.0875051809944;728.3117454005967;14630.634234144125;14725.926370368186;14748;14882.925585372004;14962.326695298738;11425.371604561746;11949.641510754604;12552.874471255262;12580.370981450891;12858.164219325714;8382.49705017701;10059.39643621385;10088.142507886838;10174.643887463977;10201.43892085935;3458.3083383323183;3502.807345595991;3521.2781379817075;3612.710983121344;3758.361078616642;74833.62583187103;77107;80970.19029809744;81087.18912810914;81199;7778.766637000899;9438.858417123749;9448.055194480497;9531.809363812685;9695.951520242354;21083.68374474384;21419.57160856774;21437.78562214358;21438.892805535874;21452.570948580942;7319.377852882458;7408.518446301081;7447;7536.623168841646;7663.693452261959;4121.690873184523;4163.479565054368;4173.332266837291;4174.290370636999;4175.624193822509;3541.610422853484;3582.4089025311055;3584.5877724061547;3604.333198358312;3606.441001644749;535.3415299182028;536.0993530868179;543.741722681731;549.9825323152137;556.8023351710085;547.2284079448038;580.4746704232676;587.9647221166744;592.7125344208056;603.6830663901399;6163.8150855474405;7970.442069055226;8274.875876861852;8394.874076888851;8426.199511046441;528.9497497737709;593.0866465642862;610.2493932463007;614.7725341623628;626.2266101364811;3704.8888533344043;3843.7693738375788;3844.519435070616;3911.9437751807423;4012.538558065801;53328;76469;78058;88833;89755;68658;69532;71322.64338678378;71446;72063;64276.07171784854;64404.67797660982;65057.67471162614;65492;65804;7131.557739948754;8276.958615206886;8277.255047045795;8328.292095171735;8391.244787969112;44112.55887441213;44464.55535444734;45029.774851125534;45322.77338613286;46499;44255;62268;62611.37388626238;63263.94962392827;64104.679476602316;10708.03627673482;10734.355938643706;10996.175286853359;11078.667639970814;11355.602553910574;11631.243969141979;11701.356425396685;11861.881381186422;11935.641930742091;11965.880341196476;11003.394813285346;11204.83192752093;11268.15488838324;11269.323840569592;11319;11243;11298.587676540432;11342;11613.3612651305;11624.5350185995;11160.832587511031;11166.776664466828;11209.55161793519;11416.543338266376;11456.828147577626;9617.663381781604;9667.419954802734;9716.3198576099;9852.113309802153;10044.547995340374;7739.690412383667;9323.906760932305;9495.66765163216;9615.807683846144;9724.173445257233 19:06:43 INFO - PROCESS | 2228 | __end_tp_report 19:06:43 INFO - PROCESS | 2228 | __start_cc_report 19:06:43 INFO - PROCESS | 2228 | _x_x_mozilla_cycle_collect,417 19:06:43 INFO - PROCESS | 2228 | __end_cc_report 19:06:43 INFO - PROCESS | 2228 | __startTimestamp1464142003264__endTimestamp 19:06:43 INFO - PROCESS | 2228 | 19:06:43 INFO - PROCESS | 2228 | ------- Summary: start ------- 19:06:43 INFO - PROCESS | 2228 | Number of tests: 6 19:06:43 INFO - PROCESS | 2228 | 19:06:43 INFO - PROCESS | 2228 | [#0] dojo.html Cycles:165 Average:5744.28 Median:5941.41 stddev:652.55 (11.0%) stddev-sans-first:623.49 19:06:43 INFO - PROCESS | 2228 | Values: 3208.5 3469.2 3503.6 3516.9 3533.1 5726.4 5756.6 5765.8 5771.5 5812.9 5708.9 5725.4 5748.6 5771.6 5805.3 4467.7 5209.1 5243.5 5324.8 5324.8 4575.1 5150.6 5203.6 5332.8 5356.3 5296.7 5312.7 5318.6 5323.5 5331.7 5232.8 5298.6 5339.5 5361.3 5386.4 5174.2 5282.4 5283.2 5290.6 5320.6 5574.7 5619.2 5649.8 5709.3 5722.3 6195.8 6196.3 6222.4 6267.1 6288.2 6237.7 6256.3 6279.0 6284.3 6292.5 5742.0 5827.5 5856.1 5882.6 5885.4 6323.4 6382.4 6396.0 6403.7 6415.5 6206.9 6221.5 6222.7 6231.8 6241.7 6123.9 6148.1 6242.7 6278.9 6280.1 6168.0 6177.3 6180.5 6186.6 6190.0 6155.5 6214.7 6215.2 6220.7 6224.1 4822.1 4947.6 4968.2 4992.9 5043.4 4422.2 4422.5 4448.3 4458.5 4488.1 5763.1 5763.7 5790.9 5800.3 5805.8 6162.7 6174.8 6235.3 6244.8 6297.0 5741.6 5756.7 5768.3 5778.5 5784.2 4678.9 5333.7 5436.9 5445.0 5446.3 6373.8 6375.6 6412.9 6439.5 6488.5 6228.7 6260.2 6263.4 6275.2 6278.6 6313.8 6385.9 6402.7 6403.5 6415.9 5093.4 6138.2 6167.9 6193.8 6213.7 4459.6 4882.8 5863.6 6076.0 6238.2 6046.8 6056.4 6061.2 6067.8 6072.8 6109.9 6118.7 6125.2 6135.9 6146.8 5950.6 5999.5 6052.0 6056.9 6062.0 6023.2 6118.6 6164.6 6174.5 6180.8 5875.4 5876.1 5940.4 5941.4 5974.7 19:06:43 INFO - PROCESS | 2228 | 19:06:43 INFO - PROCESS | 2228 | [#1] ext.html Cycles:180 Average:68982.98 Median:39654.60 stddev:147108.77 (371.0%) stddev-sans-first:147431.53 19:06:43 INFO - PROCESS | 2228 | Values: 479.8 495.1 532.8 538.0 547.8 38072.6 39608.8 39620.4 39703.6 39780.8 42503.0 43067.9 43206.7 43245.8 43347.6 9438.8 9599.6 9640.0 9711.9 9727.0 9546.9 9623.4 9650.7 9683.2 9699.3 9649.3 9655.7 9681.1 9700.3 9740.9 8737.7 9631.9 9700.8 9720.4 9722.0 9598.9 9680.8 9680.8 9699.0 9715.0 39111.0 39458.0 39930.2 39959.6 40055.6 3116.3 3299.0 3377.0 3389.4 3697.6 28552.0 28745.6 29241.7 29261.9 29948.0 2716.5 2749.7 2774.8 2795.7 2829.5 463604.1 467156.0 470561.0 471184.0 471222.6 54185.6 55972.0 63001.0 64267.0 64753.7 63139.0 65947.0 68478.0 70139.6 70146.9 14004.9 17576.6 17693.2 17697.9 17744.9 13222.5 13365.3 13438.8 13441.1 13494.6 16073.5 17655.1 19315.3 19576.9 19718.7 6578.8 6899.3 7034.0 7382.3 7997.3 924.9 980.0 1059.4 1073.3 1081.9 30700.4 30748.9 31056.8 31190.4 31264.4 1012.9 1094.7 1128.6 1154.9 1184.5 14376.0 15503.8 15889.0 16102.2 16137.8 608430.0 616114.0 618916.0 619460.0 631246.0 60632.7 61791.0 62769.7 62867.7 63029.1 1289.9 1485.2 1512.6 1513.3 1525.5 29900.6 30597.8 30938.4 31166.8 31206.5 55372.9 55389.4 55795.7 55872.2 56070.0 551664.0 553686.0 557952.0 558764.0 559189.0 27711.6 28099.2 28116.2 28146.9 28279.6 47185.6 47247.3 48247.5 48256.8 48811.0 37460.8 39111.6 39124.8 39688.8 40595.4 37665.8 38721.8 38740.4 38742.0 38755.6 37834.8 38271.9 38546.6 38696.6 39315.4 29655.4 34015.5 34411.8 34783.5 34825.7 20747.0 25589.6 25654.0 25993.9 26009.7 19:06:43 INFO - PROCESS | 2228 | 19:06:43 INFO - PROCESS | 2228 | [#2] jquery.html Cycles:180 Average:32435.32 Median:395.41 stddev:92224.45 (23323.7%) stddev-sans-first:92451.73 19:06:43 INFO - PROCESS | 2228 | Values: 367.5 382.9 391.7 399.1 415.0 19276.3 20648.7 21420.3 21529.5 21674.9 16831.0 19779.2 20710.3 20935.6 21151.3 4605.0 5286.8 5471.8 5475.4 5479.4 5556.4 5903.4 5903.6 5907.2 5913.1 5811.7 5857.0 6437.9 6644.7 6644.8 6555.6 6571.8 6600.3 6617.6 6626.1 5223.2 5280.8 5639.9 5681.7 5798.0 13155.3 13442.0 13607.1 21442.7 26205.6 2182.0 2184.3 2215.0 2242.5 2754.1 9762.9 9912.5 9927.5 10022.3 10276.9 1632.5 1676.3 1725.3 1769.7 1809.8 106053.9 107319.0 110838.0 111497.4 111994.4 9570.9 11264.4 11438.5 11723.4 11808.0 15169.9 15322.2 15356.3 15395.7 15396.2 9147.5 9177.4 9202.3 9229.8 9240.4 8118.0 8845.1 9180.6 9245.0 9287.3 4746.6 4784.4 4787.3 4820.1 4826.2 1779.0 1994.3 2043.9 2061.7 2072.2 837.5 933.4 946.4 978.9 1033.2 20353.2 21126.2 21187.3 21225.6 21269.6 974.4 981.8 983.3 1051.9 1061.1 10900.8 11327.0 11328.5 11396.6 11409.9 548884.0 550456.0 550774.0 557010.0 562512.0 91926.0 108216.5 110365.4 119795.4 121010.4 955.5 966.9 989.4 990.1 993.1 14412.6 17045.5 17828.1 17937.7 18061.8 35745.6 48006.0 50148.2 51385.5 52106.5 68400.0 69968.6 75735.6 76414.6 76533.5 7858.3 7874.1 8003.8 8050.2 8105.1 9968.5 11325.2 12007.9 12181.7 12389.9 10297.8 10556.7 10714.3 10739.7 10807.3 10143.0 10268.6 10460.7 10695.4 10759.6 3999.5 5598.7 9911.7 10579.6 11120.7 7949.9 7983.2 8009.1 8128.0 8133.8 8060.7 9775.2 10902.2 10998.1 11028.5 19:06:43 INFO - PROCESS | 2228 | 19:06:43 INFO - PROCESS | 2228 | [#3] mootools.html Cycles:180 Average:5556.47 Median:4846.33 stddev:3067.14 (63.3%) stddev-sans-first:3059.58 19:06:43 INFO - PROCESS | 2228 | Values: 1365.7 1580.7 1749.5 1828.6 1938.0 7685.7 8291.7 8435.0 8627.7 8636.6 8295.9 8348.9 8389.5 8392.0 8419.0 2215.7 2389.9 2440.3 2441.7 2443.3 2122.0 2397.6 2446.4 2448.3 2449.5 2389.4 2411.9 2412.8 2457.5 2464.9 2405.3 2432.3 2436.0 2436.2 2450.1 2051.8 2399.2 2408.9 2409.4 2444.3 8525.0 8554.5 8559.5 8575.3 8700.6 5181.8 5310.6 5341.4 5351.7 5359.8 3401.7 3408.5 3453.7 3496.3 3537.2 1016.9 1019.5 1038.7 1039.0 1042.6 11351.9 11447.4 11475.4 11491.7 11532.7 4268.5 4513.4 4622.3 4765.8 4770.3 9887.6 9910.5 10000.8 10005.0 10005.6 5206.0 5305.2 5365.9 5366.9 5374.0 4158.9 4339.3 4555.4 4858.0 4870.8 3383.5 3478.8 4019.4 4027.9 4118.8 1337.8 1348.4 1348.8 1352.3 1352.9 815.8 857.5 863.9 956.0 965.2 4744.9 5098.6 5857.8 5898.7 6029.2 873.3 1050.5 1084.8 1092.0 1094.6 3094.6 3764.4 3805.4 3853.8 3891.1 10065.3 10288.3 10363.8 10417.2 10434.4 10233.5 10316.6 10319.7 10391.8 10646.0 8104.1 8212.5 8232.9 8251.5 8273.9 5550.6 5637.7 5712.4 5800.4 5864.6 9251.6 9395.8 9402.9 9472.8 9498.0 8719.9 9264.3 9623.3 9675.5 9723.6 6345.9 6624.3 6781.6 6904.8 7057.5 7957.5 7981.9 8036.4 8054.3 8065.5 7085.3 7248.5 7250.6 7272.6 7365.3 7104.5 7137.1 7137.2 7158.0 7173.4 6692.4 6910.2 6911.1 6943.1 6948.1 4681.6 4694.2 4731.1 4834.7 4895.1 4825.4 4867.0 4873.3 4927.2 4929.6 19:06:43 INFO - PROCESS | 2228 | 19:06:43 INFO - PROCESS | 2228 | [#4] prototype.html Cycles:180 Average:5783.09 Median:6379.33 stddev:1861.60 (29.2%) stddev-sans-first:1821.04 19:06:43 INFO - PROCESS | 2228 | Values: 316.1 316.1 326.3 328.6 328.9 4666.3 5285.5 6479.1 6585.1 6715.2 5328.7 5342.5 6640.4 6662.4 6706.3 4225.1 4368.3 4451.0 4986.6 4998.9 4977.2 4985.8 4996.1 4998.5 5012.1 4922.8 4934.6 4937.1 4945.5 4951.4 4631.0 4924.0 4953.5 4956.2 4975.6 4005.4 4067.3 4069.7 4071.1 4086.3 6287.0 6318.3 6444.8 6453.5 6482.5 5677.9 5681.5 5695.1 5699.0 5705.1 7026.8 7084.8 7130.4 7131.1 7132.5 4580.2 4586.7 4587.2 4591.6 4597.3 7366.8 7430.0 7473.5 7474.5 7479.0 4787.4 4794.3 4795.5 4801.8 4808.0 4865.7 4876.6 4882.4 4882.6 4887.3 5804.9 5816.2 5817.3 5818.2 5834.0 5766.6 6888.7 7180.8 7190.8 7199.8 1701.4 1708.6 1729.9 1730.9 1736.0 1089.6 1105.0 1120.3 1122.5 1124.7 6400.5 6461.2 6585.5 6648.3 6675.3 7464.0 7472.1 7475.7 7504.9 7524.2 6638.5 6641.4 6642.9 6659.9 6665.3 3920.8 3926.3 3961.4 3973.1 3974.6 7676.8 7981.0 7989.1 7999.1 8025.1 7781.0 7791.1 7812.6 7820.5 7831.1 7783.2 7935.7 7940.6 7955.7 7981.4 7181.6 7425.1 7551.4 7573.1 7599.7 7269.8 7314.0 7322.4 7339.2 7404.1 6489.9 7740.1 7918.9 7926.7 7932.0 4645.0 4680.4 4689.2 4705.8 4714.7 5832.8 5911.0 6358.2 6762.1 7374.6 7142.9 7185.7 7219.5 7221.5 7227.8 5738.5 7182.5 7186.7 7209.2 7210.4 5816.9 6129.2 6482.5 6984.6 7002.0 6912.4 7018.9 7108.2 7160.7 7231.7 5439.8 6141.7 6300.6 7003.5 7074.3 19:06:43 INFO - PROCESS | 2228 | 19:06:43 INFO - PROCESS | 2228 | [#5] yui.html Cycles:180 Average:17031.42 Median:590.34 stddev:23193.39 (3928.8%) stddev-sans-first:23225.73 19:06:43 INFO - PROCESS | 2228 | Values: 624.0 654.7 680.0 706.3 710.2 13713.8 14560.9 14760.6 14990.5 15000.4 13602.5 13731.1 13742.6 13754.4 13797.5 743.2 900.8 912.1 925.1 937.9 916.2 918.5 921.4 924.3 924.4 942.5 971.5 973.4 974.2 978.4 931.8 932.7 934.2 949.5 970.3 695.8 716.2 721.1 727.1 728.3 14630.6 14725.9 14748.0 14882.9 14962.3 11425.4 11949.6 12552.9 12580.4 12858.2 8382.5 10059.4 10088.1 10174.6 10201.4 3458.3 3502.8 3521.3 3612.7 3758.4 74833.6 77107.0 80970.2 81087.2 81199.0 7778.8 9438.9 9448.1 9531.8 9696.0 21083.7 21419.6 21437.8 21438.9 21452.6 7319.4 7408.5 7447.0 7536.6 7663.7 4121.7 4163.5 4173.3 4174.3 4175.6 3541.6 3582.4 3584.6 3604.3 3606.4 535.3 536.1 543.7 550.0 556.8 547.2 580.5 588.0 592.7 603.7 6163.8 7970.4 8274.9 8394.9 8426.2 528.9 593.1 610.2 614.8 626.2 3704.9 3843.8 3844.5 3911.9 4012.5 53328.0 76469.0 78058.0 88833.0 89755.0 68658.0 69532.0 71322.6 71446.0 72063.0 64276.1 64404.7 65057.7 65492.0 65804.0 7131.6 8277.0 8277.3 8328.3 8391.2 44112.6 44464.6 45029.8 45322.8 46499.0 44255.0 62268.0 62611.4 63263.9 64104.7 10708.0 10734.4 10996.2 11078.7 11355.6 11631.2 11701.4 11861.9 11935.6 11965.9 11003.4 11204.8 11268.2 11269.3 11319.0 11243.0 11298.6 11342.0 11613.4 11624.5 11160.8 11166.8 11209.6 11416.5 11456.8 9617.7 9667.4 9716.3 9852.1 10044.5 7739.7 9323.9 9495.7 9615.8 9724.2 19:06:43 INFO - PROCESS | 2228 | -------- Summary: end -------- 19:06:43 INFO - PROCESS | 2228 | 19:06:43 INFO - TEST-INFO | 2228: exit 0 19:06:43 INFO - TEST-OK | dromaeo_css | took 1111576ms 19:06:43 INFO - TEST-START | kraken 19:06:43 INFO - operating with platform_type : mac_ 19:06:43 INFO - Initialising browser for kraken test... 19:06:43 INFO - TEST-INFO | started process 2232 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmpnN2SeS/profile http://localhost:50782/getInfo.html) 19:06:46 INFO - PROCESS | 2232 | __metrics Screen width/height:1600/1200 19:06:46 INFO - PROCESS | 2232 | colorDepth:24 19:06:46 INFO - PROCESS | 2232 | Browser inner width/height: 1024/689 19:06:46 INFO - PROCESS | 2232 | __metrics 19:06:46 ERROR - PROCESS | 2232 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 19:06:47 INFO - TEST-INFO | 2232: exit 0 19:06:47 INFO - Browser initialized. 19:06:47 INFO - Running cycle 1/1 for kraken test... 19:06:47 INFO - TEST-INFO | started process 2235 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmpnN2SeS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:06:59 INFO - PROCESS | 2235 | RSS: Main: 221155328 19:06:59 INFO - PROCESS | 2235 | 19:07:02 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:50782/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50782/tests/kraken/driver.html?testName=audio-beat-detection) 19:07:03 INFO - PROCESS | 2235 | RSS: Main: 232894464 19:07:03 INFO - PROCESS | 2235 | 19:07:05 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:50782/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50782/tests/kraken/driver.html?testName=audio-dft) 19:07:06 INFO - PROCESS | 2235 | RSS: Main: 241836032 19:07:06 INFO - PROCESS | 2235 | 19:07:09 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:50782/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50782/tests/kraken/driver.html?testName=audio-fft) 19:07:10 INFO - PROCESS | 2235 | RSS: Main: 245780480 19:07:10 INFO - PROCESS | 2235 | 19:07:12 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:50782/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50782/tests/kraken/driver.html?testName=audio-oscillator) 19:07:12 INFO - PROCESS | 2235 | RSS: Main: 247123968 19:07:12 INFO - PROCESS | 2235 | 19:07:14 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:50782/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50782/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:07:15 INFO - PROCESS | 2235 | RSS: Main: 247349248 19:07:15 INFO - PROCESS | 2235 | 19:07:18 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:50782/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50782/tests/kraken/driver.html?testName=imaging-darkroom) 19:07:18 INFO - PROCESS | 2235 | RSS: Main: 247549952 19:07:18 INFO - PROCESS | 2235 | 19:07:21 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:50782/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50782/tests/kraken/driver.html?testName=imaging-desaturate) 19:07:22 INFO - PROCESS | 2235 | RSS: Main: 247652352 19:07:22 INFO - PROCESS | 2235 | 19:07:25 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:50782/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50782/tests/kraken/driver.html?testName=json-parse-financial) 19:07:26 INFO - PROCESS | 2235 | RSS: Main: 247967744 19:07:26 INFO - PROCESS | 2235 | 19:07:27 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:50782/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50782/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:07:28 INFO - PROCESS | 2235 | RSS: Main: 247103488 19:07:28 INFO - PROCESS | 2235 | 19:07:30 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:50782/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50782/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:07:30 INFO - PROCESS | 2235 | RSS: Main: 247304192 19:07:30 INFO - PROCESS | 2235 | 19:07:32 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:50782/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50782/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:07:33 INFO - PROCESS | 2235 | RSS: Main: 247435264 19:07:33 INFO - PROCESS | 2235 | 19:07:36 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:50782/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50782/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:07:36 INFO - PROCESS | 2235 | RSS: Main: 247529472 19:07:36 INFO - PROCESS | 2235 | 19:07:39 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:50782/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50782/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:07:40 INFO - PROCESS | 2235 | RSS: Main: 247558144 19:07:40 INFO - PROCESS | 2235 | 19:07:42 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:50782/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50782/tests/kraken/driver.html?testName=ai-astar) 19:07:42 INFO - PROCESS | 2235 | RSS: Main: 249372672 19:07:42 INFO - PROCESS | 2235 | 19:07:42 INFO - PROCESS | 2235 | __start_tp_report 19:07:42 INFO - PROCESS | 2235 | _x_x_mozilla_page_load 19:07:42 INFO - PROCESS | 2235 | _x_x_mozilla_page_load_details 19:07:42 INFO - PROCESS | 2235 | |i|pagename|runs| 19:07:42 INFO - PROCESS | 2235 | |0;ai-astar;114;119;110;117;118;116;107;126;173;164 19:07:42 INFO - PROCESS | 2235 | |1;audio-beat-detection;154;122;158;151;121;146;140;124;172;123 19:07:42 INFO - PROCESS | 2235 | |2;audio-dft;200;195;197;197;201;192;197;199;200;201 19:07:42 INFO - PROCESS | 2235 | |3;audio-fft;62;115;89;148;57;56;57;66;152;65 19:07:42 INFO - PROCESS | 2235 | |4;audio-oscillator;78;83;130;76;93;119;123;82;82;130 19:07:42 INFO - PROCESS | 2235 | |5;imaging-gaussian-blur;90;87;89;86;90;87;90;87;89;105 19:07:42 INFO - PROCESS | 2235 | |6;imaging-darkroom;95;96;96;97;121;116;95;95;108;151 19:07:42 INFO - PROCESS | 2235 | |7;imaging-desaturate;91;95;90;98;95;92;92;95;92;90 19:07:42 INFO - PROCESS | 2235 | |8;json-parse-financial;62;65;66;66;66;66;68;82;64;62 19:07:42 INFO - PROCESS | 2235 | |9;json-stringify-tinderbox;65;68;72;71;74;77;76;69;69;71 19:07:42 INFO - PROCESS | 2235 | |10;stanford-crypto-aes;81;80;81;82;83;80;81;84;82;84 19:07:42 INFO - PROCESS | 2235 | |11;stanford-crypto-ccm;130;137;119;128;146;134;153;117;169;175 19:07:42 INFO - PROCESS | 2235 | |12;stanford-crypto-pbkdf2;170;165;171;167;168;164;168;167;169;162 19:07:42 INFO - PROCESS | 2235 | |13;stanford-crypto-sha256-iterative;70;66;69;85;86;91;96;99;103;60 19:07:42 INFO - PROCESS | 2235 | __end_tp_report 19:07:42 INFO - PROCESS | 2235 | __start_cc_report 19:07:42 INFO - PROCESS | 2235 | _x_x_mozilla_cycle_collect,1097 19:07:42 INFO - PROCESS | 2235 | __end_cc_report 19:07:42 INFO - PROCESS | 2235 | __startTimestamp1464142062471__endTimestamp 19:07:42 INFO - PROCESS | 2235 | 19:07:42 INFO - PROCESS | 2235 | ------- Summary: start ------- 19:07:42 INFO - PROCESS | 2235 | Number of tests: 14 19:07:42 INFO - PROCESS | 2235 | 19:07:42 INFO - PROCESS | 2235 | [#0] ai-astar Cycles:10 Average:126.40 Median:118.50 stddev:22.87 (19.3%) stddev-sans-first:23.81 19:07:42 INFO - PROCESS | 2235 | Values: 114.0 119.0 110.0 117.0 118.0 116.0 107.0 126.0 173.0 164.0 19:07:42 INFO - PROCESS | 2235 | 19:07:42 INFO - PROCESS | 2235 | [#1] audio-beat-detection Cycles:10 Average:141.10 Median:148.50 stddev:18.01 (12.1%) stddev-sans-first:18.49 19:07:42 INFO - PROCESS | 2235 | Values: 154.0 122.0 158.0 151.0 121.0 146.0 140.0 124.0 172.0 123.0 19:07:42 INFO - PROCESS | 2235 | 19:07:42 INFO - PROCESS | 2235 | [#2] audio-dft Cycles:10 Average:197.90 Median:199.50 stddev:2.88 (1.4%) stddev-sans-first:2.96 19:07:42 INFO - PROCESS | 2235 | Values: 200.0 195.0 197.0 197.0 201.0 192.0 197.0 199.0 200.0 201.0 19:07:42 INFO - PROCESS | 2235 | 19:07:42 INFO - PROCESS | 2235 | [#3] audio-fft Cycles:10 Average:86.70 Median:59.50 stddev:38.07 (64.0%) stddev-sans-first:39.32 19:07:42 INFO - PROCESS | 2235 | Values: 62.0 115.0 89.0 148.0 57.0 56.0 57.0 66.0 152.0 65.0 19:07:42 INFO - PROCESS | 2235 | 19:07:42 INFO - PROCESS | 2235 | [#4] audio-oscillator Cycles:10 Average:99.60 Median:80.00 stddev:22.94 (28.7%) stddev-sans-first:22.96 19:07:42 INFO - PROCESS | 2235 | Values: 78.0 83.0 130.0 76.0 93.0 119.0 123.0 82.0 82.0 130.0 19:07:42 INFO - PROCESS | 2235 | 19:07:42 INFO - PROCESS | 2235 | [#5] imaging-gaussian-blur Cycles:10 Average:90.00 Median:89.00 stddev:5.48 (6.2%) stddev-sans-first:5.81 19:07:42 INFO - PROCESS | 2235 | Values: 90.0 87.0 89.0 86.0 90.0 87.0 90.0 87.0 89.0 105.0 19:07:42 INFO - PROCESS | 2235 | 19:07:42 INFO - PROCESS | 2235 | [#6] imaging-darkroom Cycles:10 Average:107.00 Median:95.00 stddev:18.22 (19.2%) stddev-sans-first:18.80 19:07:42 INFO - PROCESS | 2235 | Values: 95.0 96.0 96.0 97.0 121.0 116.0 95.0 95.0 108.0 151.0 19:07:42 INFO - PROCESS | 2235 | 19:07:42 INFO - PROCESS | 2235 | [#7] imaging-desaturate Cycles:10 Average:93.00 Median:93.50 stddev:2.62 (2.8%) stddev-sans-first:2.68 19:07:42 INFO - PROCESS | 2235 | Values: 91.0 95.0 90.0 98.0 95.0 92.0 92.0 95.0 92.0 90.0 19:07:42 INFO - PROCESS | 2235 | 19:07:42 INFO - PROCESS | 2235 | [#8] json-parse-financial Cycles:10 Average:66.70 Median:66.00 stddev:5.70 (8.6%) stddev-sans-first:5.78 19:07:42 INFO - PROCESS | 2235 | Values: 62.0 65.0 66.0 66.0 66.0 66.0 68.0 82.0 64.0 62.0 19:07:42 INFO - PROCESS | 2235 | 19:07:42 INFO - PROCESS | 2235 | [#9] json-stringify-tinderbox Cycles:10 Average:71.20 Median:71.50 stddev:3.71 (5.2%) stddev-sans-first:3.18 19:07:42 INFO - PROCESS | 2235 | Values: 65.0 68.0 72.0 71.0 74.0 77.0 76.0 69.0 69.0 71.0 19:07:42 INFO - PROCESS | 2235 | 19:07:42 INFO - PROCESS | 2235 | [#10] stanford-crypto-aes Cycles:10 Average:81.80 Median:82.00 stddev:1.48 (1.8%) stddev-sans-first:1.54 19:07:42 INFO - PROCESS | 2235 | Values: 81.0 80.0 81.0 82.0 83.0 80.0 81.0 84.0 82.0 84.0 19:07:42 INFO - PROCESS | 2235 | 19:07:42 INFO - PROCESS | 2235 | [#11] stanford-crypto-ccm Cycles:10 Average:140.80 Median:141.50 stddev:19.79 (14.0%) stddev-sans-first:20.60 19:07:42 INFO - PROCESS | 2235 | Values: 130.0 137.0 119.0 128.0 146.0 134.0 153.0 117.0 169.0 175.0 19:07:42 INFO - PROCESS | 2235 | 19:07:42 INFO - PROCESS | 2235 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.10 Median:168.00 stddev:2.77 (1.6%) stddev-sans-first:2.73 19:07:42 INFO - PROCESS | 2235 | Values: 170.0 165.0 171.0 167.0 168.0 164.0 168.0 167.0 169.0 162.0 19:07:42 INFO - PROCESS | 2235 | 19:07:42 INFO - PROCESS | 2235 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:82.50 Median:85.50 stddev:15.21 (17.8%) stddev-sans-first:15.45 19:07:42 INFO - PROCESS | 2235 | Values: 70.0 66.0 69.0 85.0 86.0 91.0 96.0 99.0 103.0 60.0 19:07:42 INFO - PROCESS | 2235 | -------- Summary: end -------- 19:07:42 INFO - PROCESS | 2235 | 19:07:43 INFO - TEST-INFO | 2235: exit 0 19:07:43 INFO - TEST-OK | kraken | took 59466ms 19:07:43 INFO - SUITE-END | took 1171s 19:07:43 INFO - Completed test suite (00:19:31) 19:07:43 INFO - javascript benchmark 19:07:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3208.454562724337, 3469.1673998240412, 3503.6496350364982, 3516.929661406772, 3533.081398836302, 5726.3700992890745, 5756.625819321752, 5765.827025189245, 5771.451712087355, 5812.854678633036, 5708.857278568027, 5725.427457254273, 5748.5975981681295, 5771.595988280822, 5805.303363596364, 4467.709598876079, 5209.088409528317, 5243.475652434755, 5324.787008519655, 5324.840254792343, 4575.084983003413, 5150.635081703352, 5203.55769759569, 5332.840014799563, 5356.330458692663, 5296.735163241822, 5312.681239125645, 5318.627696061278, 5323.467653234665, 5331.653442526223, 5232.843014709546, 5298.629095963284, 5339.466053394649, 5361.303030605996, 5386.353637563518, 5174.249733788617, 5282.3925248596515, 5283.207518872162, 5290.55030322423, 5320.600954928396, 5574.749136288877, 5619.241402410663, 5649.830505084854, 5709.3434255060365, 5722.313322401338, 6195.783147589813, 6196.318404975495, 6222.408871157233, 6267.091271765614, 6288.245410550763, 6237.719302631393, 6256.343083976162, 6278.968605156991, 6284.27730810958, 6292.4966002719675, 5742.0, 5827.475527202572, 5856.063029915235, 5882.617629854046, 5885.352611212806, 6323.399277068671, 6382.393672601095, 6395.968020159962, 6403.711832967434, 6415.518836087312, 6206.882761103045, 6221.471174950089, 6222.688865556704, 6231.781887633911, 6241.687915604201, 6123.892747182667, 6148.1085242639565, 6242.719077641517, 6278.905816412757, 6280.05799130125, 6168.0439531872635, 6177.2587289525545, 6180.536459765535, 6186.597871138361, 6190.0405437157315, 6155.538334624832, 6214.689265536705, 6215.223097112861, 6220.688965551704, 6224.128621992924, 4822.083804077214, 4947.6289278304275, 4968.205087186033, 4992.90014199714, 5043.36957880265, 4422.181896349186, 4422.469303683514, 4448.332750087513, 4458.509563948028, 4488.147252022105, 5763.106718458645, 5763.682997435181, 5790.913136302959, 5800.303963524404, 5805.767769289266, 6162.691865406801, 6174.845628859314, 6235.282942461584, 6244.781432649836, 6297.0, 5741.62679425836, 5756.7121643917635, 5768.307803063659, 5778.479936805707, 5784.248047753849, 4678.883027924261, 5333.7066461345, 5436.91844622331, 5445.019896418683, 5446.291982042309, 6373.808785736435, 6375.585586936927, 6412.903806443001, 6439.517036222208, 6488.480921526361, 6228.688565571612, 6260.248770147429, 6263.404976527223, 6275.152854364603, 6278.560500764903, 6313.842153946188, 6385.904211436925, 6402.711877965409, 6403.455706264834, 6415.871682566416, 5093.363329583802, 6138.232720909887, 6167.938320616737, 6193.783217587363, 6213.720382582705, 4459.620932220798, 4882.804687812448, 5863.560232982458, 6075.969620151871, 6238.157848690369, 6046.848828779316, 6056.424639659225, 6061.242344706911, 6067.757289708362, 6072.787452439144, 6109.908351374822, 6118.741008805849, 6125.165117264519, 6135.851732731318, 6146.832101900624, 5950.583459157991, 5999.52003839683, 6052.0, 6056.90914636272, 6062.030075187949, 6023.247094113236, 6118.632882027093, 6164.599301045418, 6174.475169610633, 6180.783672571439, 5875.412458754261, 5876.08920617305, 5940.435658612425, 5941.405859414024, 5974.701264936823], "value": 5704.186691887054, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [479.76971053894107, 495.0766819880921, 532.8055259830162, 537.9542738867196, 547.8082671065125, 38072.61927380726, 39608.80195599025, 39620.40569391462, 39703.60296397042, 39780.80657580274, 42503.0, 43067.92330191739, 43206.703798886, 43245.7837710811, 43347.56652433475, 9438.764030899214, 9599.568019439108, 9640.0, 9711.902880971205, 9726.951365243163, 9546.856797148048, 9623.37448065877, 9650.662226822064, 9683.22534197266, 9699.321047526675, 9649.324547281693, 9655.662051828187, 9681.080297371773, 9700.320977531575, 9740.902590974107, 8737.737867863974, 9631.903680963205, 9700.805983880353, 9720.368176068532, 9721.951390243074, 9598.90401095987, 9680.75798105046, 9680.75798105046, 9698.9515052425, 9714.9514252429, 39111.0, 39458.0, 39930.20139597221, 39959.60040399617, 40055.59944400577, 3116.3144108296365, 3298.967010329914, 3377.0037838837357, 3389.440742277521, 3697.556293244828, 28552.0, 28745.56881646777, 29241.707582924322, 29261.853690731623, 29948.0, 2716.483868065081, 2749.7112803155583, 2774.819636723627, 2795.7204279572084, 2829.547272436421, 463604.09187724424, 467156.0, 470561.0, 471184.0, 471222.6438867818, 54185.64535886634, 55972.0, 63000.99999999908, 64267.0, 64753.67623161854, 63139.0, 65947.01079483812, 68478.0, 70139.64930175316, 70146.89559313229, 14004.929975350058, 17576.648467030587, 17693.20380582877, 17697.911510442624, 17744.91127544354, 13222.537211197561, 13365.26491043002, 13438.798418023738, 13441.126326788728, 13494.583068777833, 16073.517794465952, 17655.117244137742, 19315.323963661205, 19576.90211548933, 19718.70421943672, 6578.76974305897, 6899.275576064541, 7034.015237866703, 7382.335589796944, 7997.280244777998, 924.902885197058, 979.9608015679293, 1059.433203236265, 1073.3077165228383, 1081.8755843078147, 30700.385992280477, 30748.923787667325, 31056.844715776275, 31190.376192476022, 31264.4044342014, 1012.8733908261468, 1094.7372630568766, 1128.5711429656678, 1154.9201496600706, 1184.490669012333, 14376.0, 15503.844961550465, 15889.0, 16102.19489025544, 16137.838621613868, 608430.0, 616114.0, 618916.0, 619460.0, 631246.0, 60632.696836516414, 61791.0, 62769.68615156986, 62867.6856615714, 63029.05456418158, 1289.8903593194684, 1485.2202593638426, 1512.5840393891767, 1513.3341329815066, 1525.5042111313867, 29900.551491728078, 30597.847010764803, 30938.381232375676, 31166.844165779024, 31206.531902021034, 55372.89254214974, 55389.44610553843, 55795.72102139463, 55872.161917570455, 56070.0, 551664.0, 553686.0, 557952.0, 558764.0, 559189.0, 27711.584326235527, 28099.157025289274, 28116.156515304574, 28146.85926570354, 28279.575806363333, 47185.58443246708, 47247.29129063136, 48247.5175248243, 48256.793580160775, 48811.0, 37460.81269593634, 39111.60888391193, 39124.804375979074, 39688.80155599204, 40595.39106913458, 37665.81167094257, 38721.80639096787, 38740.41889371605, 38742.031449213995, 38755.61244387633, 37834.81082594569, 38271.85184444471, 38546.6145338543, 38696.613033870424, 39315.410268845415, 29655.40689186161, 34015.48976765301, 34411.82794086014, 34783.4782478258, 34825.65174348224, 20747.0, 25589.616155758053, 25653.97384104615, 25993.870030649727, 26009.739902600733], "value": 20138.212721613807, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.5057048270078, 382.911930256041, 391.74340806771556, 399.0781295208069, 415.0184812917451, 19276.3253286135, 20648.690269645966, 21420.25029123981, 21529.461763455904, 21674.916254187287, 16831.0, 19779.208831646753, 20710.27514037009, 20935.581288374222, 21151.259705910295, 4604.953950460503, 5286.841394758154, 5471.753771080311, 5475.397706252309, 5479.397266300703, 5556.444355564431, 5903.409659034084, 5903.557233207505, 5907.202527658754, 5913.14259432382, 5811.709414529278, 5856.970715146418, 6437.935620643781, 6644.734210631593, 6644.833879153036, 6555.6394398308075, 6571.769988050395, 6600.306967768363, 6617.602943823362, 6626.105475760759, 5223.2165175223645, 5280.785419353532, 5639.8590035249035, 5681.659100453986, 5797.971010144965, 13155.276459794708, 13442.0, 13607.115537490032, 21442.678359824615, 26205.60691589628, 2181.967270490944, 2184.3010236724253, 2214.9889250553806, 2242.473018840571, 2754.1462146734575, 9762.853557196648, 9912.504374781232, 9927.453990030546, 10022.3485473444, 10276.89723102767, 1632.526567295483, 1676.270822192338, 1725.3271224222563, 1769.6903041967605, 1809.80092189859, 106053.93946060518, 107319.0, 110838.0, 111497.44251278773, 111994.44002779934, 9570.90429095714, 11264.38045907483, 11438.485268162953, 11723.358729777838, 11808.0, 15169.938104332812, 15322.15728134941, 15356.308966096552, 15395.692086158215, 15396.230188490754, 9147.496887671108, 9177.403468774508, 9202.30982676302, 9229.815403692022, 9240.445573265626, 8118.0, 8845.115488451103, 9180.586873590704, 9245.029271926485, 9287.303452241109, 4746.620270378361, 4784.354112194878, 4787.329773831666, 4820.060088282821, 4826.203676393356, 1779.0215381540102, 1994.2920263306607, 2043.928462503835, 2061.721667574888, 2072.191845180381, 837.5058715357968, 933.4259430450224, 946.370663508759, 978.858065580501, 1033.204432586904, 20353.185872565227, 21126.154953801983, 21187.258445954318, 21225.575488490143, 21269.574608507744, 974.4348277998744, 981.7987312601042, 983.2527279267808, 1051.9316244444087, 1061.1192710050639, 10900.84102482111, 11327.037201837771, 11328.490217940047, 11396.601118960962, 11409.859014098689, 548884.0, 550456.0, 550774.0, 557010.0, 562512.0, 91926.0, 108216.4589177049, 110365.44817276023, 119795.40102299607, 121010.3949480247, 955.527014128011, 966.9033096690415, 989.366805244644, 990.1435258501344, 993.1310103659298, 14412.630800073975, 17045.48863534096, 17828.10859457048, 17937.730934036263, 18061.819381806014, 35745.64254357527, 48006.039879202915, 50148.247776284115, 51385.48614513807, 52106.47893521168, 68400.0, 69968.60062798613, 75735.62132189304, 76414.61792691001, 76533.46933061419, 7858.332041776284, 7874.055113386201, 8003.83992320162, 8050.235227653364, 8105.148959359182, 9968.50157492108, 11325.15061370384, 12007.879921201025, 12181.695457613276, 12389.876101238871, 10297.845532316871, 10556.683299501028, 10714.303570267908, 10739.677809665722, 10807.297525660806, 10143.0, 10268.58925642966, 10460.68617941463, 10695.411752353384, 10759.623413180501, 3999.540052893838, 5598.692071936082, 9911.702648920544, 10579.62971296001, 11120.665520137636, 7949.92050079515, 7983.20167983197, 8009.078955920144, 8128.0246370435925, 8133.7966550834335, 8060.677572897019, 9775.21798256152, 10902.18233632465, 10998.06516446079, 11028.503717332578], "value": 9271.912290226219, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1365.6858922447836, 1580.652256503569, 1749.5101371615945, 1828.5702859827938, 1938.0, 7685.654145563449, 8291.668333266678, 8435.0, 8627.654893804249, 8636.611352489144, 8295.91704082959, 8348.91651083489, 8389.538575378352, 8391.958040209805, 8418.95790521048, 2215.656573231148, 2389.8528706221023, 2440.3411079008656, 2441.7192022917325, 2443.30365845734, 2122.0, 2397.5924092904247, 2446.388402899275, 2448.2532827487607, 2449.510097980402, 2389.4026493376655, 2411.8794060297005, 2412.7828495435406, 2457.5207834472194, 2464.9260522184272, 2405.338531903723, 2432.282476669378, 2435.9634605480933, 2436.196055301746, 2450.142450142445, 2051.7640471345835, 2399.208261273793, 2408.915687950931, 2409.4337830609966, 2444.2545023767743, 8524.977002759708, 8554.48673079611, 8559.486430814171, 8575.271101956341, 8700.608472618747, 5181.818636347747, 5310.60170487215, 5341.412444631087, 5351.7056561889085, 5359.785608575652, 3401.710854577339, 3408.5057666638445, 3453.740969427303, 3496.283261931298, 3537.168765340143, 1016.8525563793208, 1019.4851599942056, 1038.693585392311, 1039.0285083447002, 1042.5517027678095, 11351.886481135165, 11447.370394628291, 11475.426228688531, 11491.65525034242, 11532.711682207928, 4268.530461649216, 4513.390692256569, 4622.2604383299185, 4765.761711914391, 4770.308305295713, 9887.604495820087, 9910.504474776233, 10000.79998400028, 10005.04952029556, 10005.649802256887, 5206.0, 5305.151175811851, 5365.919511207335, 5366.892662146814, 5373.978944000705, 4158.875233742992, 4339.284018136972, 4555.362249285103, 4857.975710121497, 4870.805167793319, 3383.543221665044, 3478.8434520446135, 4019.376996565537, 4027.899302517402, 4118.814653340607, 1337.7592033434075, 1348.3797453171455, 1348.8179095822134, 1352.2562590575144, 1352.905296629246, 815.8205194857122, 857.4683696108452, 863.935204859638, 955.9761005974767, 965.165132160676, 4744.857654270378, 5098.566621837112, 5857.824265272048, 5898.705064746831, 6029.155918171459, 873.2664561768145, 1050.4747626186906, 1084.8318510630866, 1091.9945400272948, 1094.6059418609295, 3094.6131733533307, 3764.3976963686227, 3805.353089974711, 3853.807309634507, 3891.124496988155, 10065.34575252621, 10288.2798204125, 10363.844542331872, 10417.218708596885, 10434.37393756377, 10233.539490722935, 10316.587336506605, 10319.742006449898, 10391.84412233817, 10646.0, 8104.149064348277, 8212.46618969765, 8232.917670823215, 8251.463654862415, 8273.917260827435, 5550.639208451437, 5637.718114094197, 5712.400197979152, 5800.436302782096, 5864.560157988253, 9251.629934802533, 9395.812083758423, 9402.905970940477, 9472.810543789223, 9498.00270971538, 8719.912800872164, 9264.258859291105, 9623.326367154232, 9675.467849268358, 9723.562439690373, 6345.8730825384155, 6624.337566243337, 6781.6270105144695, 6904.758333458305, 7057.470689698396, 7957.5225486471, 7981.92018079835, 8036.357091432788, 8054.275115239657, 8065.516069035877, 7085.326893945067, 7248.456365772483, 7250.637468126678, 7272.636368181464, 7365.300296471826, 7104.502684812013, 7137.10786151731, 7137.179224389261, 7158.0, 7173.390261827803, 6692.431143352675, 6910.2398736137975, 6911.136107986502, 6943.132108486439, 6948.096747422803, 4681.625469962463, 4694.2254528002495, 4731.148393289241, 4834.685745426536, 4895.118878601886, 4825.372701548777, 4866.951330486791, 4873.269009648581, 4927.211646136601, 4929.605631549539], "value": 4514.727844665423, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.1101499279528, 316.12433559041443, 326.31311090155225, 328.59090432411654, 328.9144822346189, 4666.253399456088, 5285.546474719454, 6479.092926990225, 6585.078089067522, 6715.194176698791, 5328.733563321838, 5342.519173274404, 6640.369164929336, 6662.400383965442, 6706.295838936914, 4225.070484493406, 4368.301071828508, 4450.955490445086, 4986.551210391064, 4998.850034498971, 4977.228529577903, 4985.775640096204, 4996.125678006334, 4998.475160108191, 5012.147934851084, 4922.827701030465, 4934.555889969902, 4937.136001199776, 4945.455999840033, 4951.356323677916, 4630.953690463086, 4923.950760492403, 4953.504649535018, 4956.231784073457, 4975.577075948549, 4005.399190121476, 4067.3085575452246, 4069.694772892045, 4071.145059537471, 4086.2849001424634, 6287.0, 6318.2733985591785, 6444.838879028015, 6453.451456626192, 6482.513811464159, 5677.858053548653, 5681.545476361881, 5695.117256825199, 5699.0, 5705.117311287266, 7026.824329391755, 7084.752033678848, 7130.358267755875, 7131.144262688511, 7132.465065120085, 4580.198465268598, 4586.65600079992, 4587.24310488769, 4591.632669386441, 4597.333386658949, 7366.77899663011, 7430.034095567596, 7473.51422157558, 7474.4767866249385, 7479.0, 4787.40157480315, 4794.2808578712775, 4795.496472870368, 4801.759912004386, 4807.95192048075, 4865.732384718804, 4876.561109500091, 4882.414110306715, 4882.609391248691, 4887.340209071801, 5804.88390232193, 5816.1566572846705, 5817.301923769175, 5818.156367326714, 5834.0, 5766.596338256281, 6888.690008949509, 7180.7845764627145, 7190.820229494305, 7199.784006479814, 1701.3789966662328, 1708.633093525175, 1729.9394521191698, 1730.8528775054262, 1736.0451751536605, 1089.6022951622601, 1104.9723756906142, 1120.3334016260312, 1122.4836575175348, 1124.6794663520857, 6400.487960963112, 6461.19235095613, 6585.506087043491, 6648.268690444047, 6675.332466753286, 7464.029676142067, 7472.14070381913, 7475.70097196117, 7504.887426688604, 7524.209957954446, 6638.4689224861895, 6641.369069938348, 6642.933570664328, 6659.9001014984815, 6665.333466653393, 3920.8039598019986, 3926.332523471017, 3961.425593288957, 3973.0663294125857, 3974.6224108709625, 7676.769696909102, 7981.047012605373, 7989.121196668362, 7999.120096789348, 8025.07711613172, 7781.0, 7791.142974272825, 7812.648430820626, 7820.491668041559, 7831.099423566363, 7783.221677832172, 7935.722249721348, 7940.6029698515995, 7955.7215497458465, 7981.401394895406, 7181.569105853666, 7425.071866016748, 7551.358134558624, 7573.053368328959, 7599.65801538921, 7269.7819065426, 7313.963430182815, 7322.352470694264, 7339.155997060193, 7404.111506619241, 6489.8702025960165, 7740.071191457061, 7918.881216781868, 7926.722564710208, 7931.968844050237, 4644.953550464452, 4680.368150299666, 4689.179393606173, 4705.764761738147, 4714.66997310185, 5832.822279979317, 5911.0, 6358.1734374531015, 6762.087118238975, 7374.557526548424, 7142.892856607259, 7185.748498802517, 7219.45854060937, 7221.494495385273, 7227.7831665050135, 5738.483536481736, 7182.497225194186, 7186.7125314986815, 7209.170945341143, 7210.351068403869, 5816.883662326645, 6129.172561704291, 6482.513811464065, 6984.6158461283085, 7002.0, 6912.377885990084, 7018.859622807618, 7108.218096009538, 7160.749373771893, 7231.746888858865, 5439.836804895859, 6141.662208578571, 6300.558960872695, 7003.54476958996, 7074.327938845801], "value": 5170.958078294901, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [623.9555419315924, 654.744649586661, 680.0343512212658, 706.2654838967474, 710.2187593646992, 13713.794293085612, 14560.927195364022, 14760.557183284493, 14990.475333363334, 15000.399984000627, 13602.523911663095, 13731.107478013922, 13742.587722368346, 13754.449822007107, 13797.51708690196, 743.2418932688654, 900.7838118851494, 912.110692075228, 925.0749250749251, 937.8921424036255, 916.2303664921465, 918.5085979001236, 921.3780698028837, 924.3113880159291, 924.3529529329462, 942.5240253671886, 971.4996776660025, 973.4305431322684, 974.1816873825985, 978.3885071830106, 931.8462453695132, 932.6549176804558, 934.2105920497169, 949.5299826585815, 970.3450171134491, 695.8469136789948, 716.1513606376409, 721.1238345410352, 727.0875051809944, 728.3117454005967, 14630.634234144125, 14725.926370368186, 14748.0, 14882.925585372004, 14962.326695298738, 11425.371604561746, 11949.641510754604, 12552.874471255262, 12580.370981450891, 12858.164219325714, 8382.49705017701, 10059.39643621385, 10088.142507886838, 10174.643887463977, 10201.43892085935, 3458.3083383323183, 3502.807345595991, 3521.2781379817075, 3612.710983121344, 3758.361078616642, 74833.62583187103, 77107.0, 80970.19029809744, 81087.18912810914, 81199.0, 7778.766637000899, 9438.858417123749, 9448.055194480497, 9531.809363812685, 9695.951520242354, 21083.68374474384, 21419.57160856774, 21437.78562214358, 21438.892805535874, 21452.570948580942, 7319.377852882458, 7408.518446301081, 7447.0, 7536.623168841646, 7663.693452261959, 4121.690873184523, 4163.479565054368, 4173.332266837291, 4174.290370636999, 4175.624193822509, 3541.610422853484, 3582.4089025311055, 3584.5877724061547, 3604.333198358312, 3606.441001644749, 535.3415299182028, 536.0993530868179, 543.741722681731, 549.9825323152137, 556.8023351710085, 547.2284079448038, 580.4746704232676, 587.9647221166744, 592.7125344208056, 603.6830663901399, 6163.8150855474405, 7970.442069055226, 8274.875876861852, 8394.874076888851, 8426.199511046441, 528.9497497737709, 593.0866465642862, 610.2493932463007, 614.7725341623628, 626.2266101364811, 3704.8888533344043, 3843.7693738375788, 3844.519435070616, 3911.9437751807423, 4012.538558065801, 53328.0, 76469.0, 78058.0, 88833.0, 89755.0, 68658.0, 69532.0, 71322.64338678378, 71446.0, 72063.0, 64276.07171784854, 64404.67797660982, 65057.67471162614, 65492.0, 65804.0, 7131.557739948754, 8276.958615206886, 8277.255047045795, 8328.292095171735, 8391.244787969112, 44112.55887441213, 44464.55535444734, 45029.774851125534, 45322.77338613286, 46499.0, 44255.0, 62268.0, 62611.37388626238, 63263.94962392827, 64104.679476602316, 10708.03627673482, 10734.355938643706, 10996.175286853359, 11078.667639970814, 11355.602553910574, 11631.243969141979, 11701.356425396685, 11861.881381186422, 11935.641930742091, 11965.880341196476, 11003.394813285346, 11204.83192752093, 11268.15488838324, 11269.323840569592, 11319.0, 11243.0, 11298.587676540432, 11342.0, 11613.3612651305, 11624.5350185995, 11160.832587511031, 11166.776664466828, 11209.55161793519, 11416.543338266376, 11456.828147577626, 9617.663381781604, 9667.419954802734, 9716.3198576099, 9852.113309802153, 10044.547995340374, 7739.690412383667, 9323.906760932305, 9495.66765163216, 9615.807683846144, 9724.173445257233], "value": 6713.493515808301, "unit": "score"}], "name": "dromaeo_css", "value": 7420.426990635276, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 119.0, 110.0, 117.0, 118.0, 116.0, 107.0, 126.0, 173.0, 164.0], "value": 126.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 122.0, 158.0, 151.0, 121.0, 146.0, 140.0, 124.0, 172.0, 123.0], "value": 141.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 195.0, 197.0, 197.0, 201.0, 192.0, 197.0, 199.0, 200.0, 201.0], "value": 197.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 115.0, 89.0, 148.0, 57.0, 56.0, 57.0, 66.0, 152.0, 65.0], "value": 86.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 83.0, 130.0, 76.0, 93.0, 119.0, 123.0, 82.0, 82.0, 130.0], "value": 99.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 89.0, 86.0, 90.0, 87.0, 90.0, 87.0, 89.0, 105.0], "value": 90.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 97.0, 121.0, 116.0, 95.0, 95.0, 108.0, 151.0], "value": 107.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 95.0, 90.0, 98.0, 95.0, 92.0, 92.0, 95.0, 92.0, 90.0], "value": 93.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 65.0, 66.0, 66.0, 66.0, 66.0, 68.0, 82.0, 64.0, 62.0], "value": 66.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 72.0, 71.0, 74.0, 77.0, 76.0, 69.0, 69.0, 71.0], "value": 71.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 81.0, 82.0, 83.0, 80.0, 81.0, 84.0, 82.0, 84.0], "value": 81.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 137.0, 119.0, 128.0, 146.0, 134.0, 153.0, 117.0, 169.0, 175.0], "value": 140.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 165.0, 171.0, 167.0, 168.0, 164.0, 168.0, 167.0, 169.0, 162.0], "value": 167.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 66.0, 69.0, 85.0, 86.0, 91.0, 96.0, 99.0, 103.0, 60.0], "value": 82.5, "unit": "score"}], "name": "kraken", "value": 1551.8, "alertThreshold": 2.0}]} 19:07:43 INFO - Return code: 0 19:07:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 19:07:43 ERROR - # TBPL SUCCESS # 19:07:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:07:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:07:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:07:43 INFO - 2016-05-24 19:07:43.789 screenresolution[2240:65993] starting screenresolution argv=screenresolution get 19:07:43 INFO - 2016-05-24 19:07:43.791 screenresolution[2240:65993] Display 0: 1600x1200x32@60 19:07:43 INFO - 2016-05-24 19:07:43.797 screenresolution[2241:65996] starting screenresolution argv=screenresolution list 19:07:43 INFO - Available Modes on Display 0 19:07:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:07:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:07:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:07:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:07:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:07:44 INFO - Intel Iris: 19:07:44 INFO - Chipset Model: Intel Iris 19:07:44 INFO - Type: GPU 19:07:44 INFO - Bus: Built-In 19:07:44 INFO - VRAM (Dynamic, Max): 1536 MB 19:07:44 INFO - Vendor: Intel (0x8086) 19:07:44 INFO - Device ID: 0x0a2e 19:07:44 INFO - Revision ID: 0x0009 19:07:44 INFO - Displays: 19:07:44 INFO - Display: 19:07:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:07:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:07:44 INFO - Display Serial Number: 1600x1200 60 19:07:44 INFO - Main Display: Yes 19:07:44 INFO - Mirror: Off 19:07:44 INFO - Online: Yes 19:07:44 INFO - Rotation: Supported 19:07:44 INFO - Return code: 0 19:07:44 INFO - Running post-action listener: _resource_record_post_action 19:07:44 INFO - Running post-run listener: _resource_record_post_run 19:07:45 INFO - Total resource usage - Wall time: 1205s; CPU: 53.0%; Read bytes: 110592; Write bytes: 657703424; Read time: 2; Write time: 2098 19:07:45 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 354339328; Write bytes: 355707904; Read time: 27955; Write time: 666 19:07:45 INFO - run-tests - Wall time: 1173s; CPU: 53.0%; Read bytes: 110592; Write bytes: 299151360; Read time: 2; Write time: 1341 19:07:45 INFO - Running post-run listener: _upload_blobber_files 19:07:45 INFO - Blob upload gear active. 19:07:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:07:45 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 19:07:45 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'] 19:07:45 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 19:07:45 INFO - (blobuploader) - INFO - Open directory for files ... 19:07:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:07:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:07:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:07:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:07:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:07:46 INFO - (blobuploader) - INFO - Done attempting. 19:07:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:07:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:07:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:07:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:07:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:07:48 INFO - (blobuploader) - INFO - Done attempting. 19:07:48 INFO - (blobuploader) - INFO - Iteration through files over. 19:07:48 INFO - Return code: 0 19:07:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:07:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:07:48 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1899b1d8cd08340817f20d69167ad30a51f1e3b82ceb2592df1071885e98f4159f5c6a04d9fad68f290e789ed95a51632e851c8ffc33124b79f487e6a871e249", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/807e6516420e6eeeccd528f2be803ff100895880ae1ed9b29d70c37ce7c0322812610898b15466ae4cbbf8c82bc885f952f40ff5f5ee473ee451764eef89f01f"} 19:07:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:07:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:07:48 INFO - Contents: 19:07:48 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1899b1d8cd08340817f20d69167ad30a51f1e3b82ceb2592df1071885e98f4159f5c6a04d9fad68f290e789ed95a51632e851c8ffc33124b79f487e6a871e249", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/807e6516420e6eeeccd528f2be803ff100895880ae1ed9b29d70c37ce7c0322812610898b15466ae4cbbf8c82bc885f952f40ff5f5ee473ee451764eef89f01f"} 19:07:48 INFO - Running post-run listener: copy_logs_to_upload_dir 19:07:48 INFO - Copying logs to upload dir... 19:07:48 INFO - mkdir: /builds/slave/test/build/upload/logs 19:07:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1293.796015 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 33 secs) (at 2016-05-24 19:07:49.030020) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 19:07:49.033836) ========= 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.r1zm1UOQFL/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.FPXQB8jzpw/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1899b1d8cd08340817f20d69167ad30a51f1e3b82ceb2592df1071885e98f4159f5c6a04d9fad68f290e789ed95a51632e851c8ffc33124b79f487e6a871e249", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/807e6516420e6eeeccd528f2be803ff100895880ae1ed9b29d70c37ce7c0322812610898b15466ae4cbbf8c82bc885f952f40ff5f5ee473ee451764eef89f01f"} build_url:https://queue.taskcluster.net/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011211 build_url: 'https://queue.taskcluster.net/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1899b1d8cd08340817f20d69167ad30a51f1e3b82ceb2592df1071885e98f4159f5c6a04d9fad68f290e789ed95a51632e851c8ffc33124b79f487e6a871e249", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/807e6516420e6eeeccd528f2be803ff100895880ae1ed9b29d70c37ce7c0322812610898b15466ae4cbbf8c82bc885f952f40ff5f5ee473ee451764eef89f01f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 19:07:49.064553) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 19:07:49.064885) ========= 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.r1zm1UOQFL/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.FPXQB8jzpw/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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.005374 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 19:07:49.115165) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 19:07:49.115709) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 19:07:49.116270) ========= ========= Total master_lag: 0.17 =========