builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0122 starttime: 1460808101.07 results: success (0) buildid: 20160416022721 builduid: 39a090301730434491ce63017de2658c revision: 19d56ca9e3f5078dba44230ac3ec0718cc029b70 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-16 05:01:41.074213) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-16 05:01:41.074676) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 15 secs) (at 2016-04-16 05:01:41.074957) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 15 secs) (at 2016-04-16 05:01:56.477703) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-16 05:01:56.477975) ========= 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.vwZpEEQhoX/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.KEWpU6p5P2/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-16 05:01:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 27.5M=0s 2016-04-16 05:01:57 (27.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.629662 ========= master_lag: 0.57 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-16 05:01:57.675473) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-16 05:01:57.675779) ========= 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.vwZpEEQhoX/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.KEWpU6p5P2/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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.027225 ========= master_lag: 0.10 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-16 05:01:57.807275) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-16 05:01:57.810290) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 19d56ca9e3f5078dba44230ac3ec0718cc029b70 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 19d56ca9e3f5078dba44230ac3ec0718cc029b70 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vwZpEEQhoX/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.KEWpU6p5P2/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-16 05:01:57,917 truncating revision to first 12 chars 2016-04-16 05:01:57,917 Setting DEBUG logging. 2016-04-16 05:01:57,917 attempt 1/10 2016-04-16 05:01:57,917 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/19d56ca9e3f5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-16 05:01:59,886 unpacking tar archive at: fx-team-19d56ca9e3f5/testing/mozharness/ program finished with exit code 0 elapsedTime=2.219018 ========= master_lag: 0.10 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-16 05:02:00.129904) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-16 05:02:00.130194) ========= script_repo_revision: 19d56ca9e3f5078dba44230ac3ec0718cc029b70 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-16 05:02:00.134635) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-16 05:02:00.134915) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-16 05:02:00.383811) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2016-04-16 05:02:00.384077) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vwZpEEQhoX/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.KEWpU6p5P2/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 05:02:00 INFO - MultiFileLogger online at 20160416 05:02:00 in /builds/slave/test 05:02:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:02:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:02:00 INFO - {'append_to_log': False, 05:02:00 INFO - 'base_work_dir': '/builds/slave/test', 05:02:00 INFO - 'blob_upload_branch': 'Fx-Team', 05:02:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:02:00 INFO - 'branch': 'Fx-Team', 05:02:00 INFO - 'buildbot_json_path': 'buildprops.json', 05:02:00 INFO - 'config_files': ('talos/mac_config.py',), 05:02:00 INFO - 'default_actions': ('clobber', 05:02:00 INFO - 'read-buildbot-config', 05:02:00 INFO - 'download-and-extract', 05:02:00 INFO - 'populate-webroot', 05:02:00 INFO - 'create-virtualenv', 05:02:00 INFO - 'install', 05:02:00 INFO - 'run-tests'), 05:02:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:02:00 INFO - 'download_minidump_stackwalk': True, 05:02:00 INFO - 'download_symbols': 'ondemand', 05:02:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:02:00 INFO - 'tooltool.py': '/tools/tooltool.py', 05:02:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:02:00 INFO - '/tools/misc-python/virtualenv.py')}, 05:02:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:02:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:02:00 INFO - 'installer_path': 'installer.exe', 05:02:00 INFO - 'log_level': 'info', 05:02:00 INFO - 'log_name': 'talos', 05:02:00 INFO - 'log_to_console': True, 05:02:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:02:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:02:00 INFO - 'opt_config_files': (), 05:02:00 INFO - 'pip_index': False, 05:02:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:02:00 INFO - 'cmd': ('bash', 05:02:00 INFO - '-c', 05:02:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:02:00 INFO - 'enabled': True, 05:02:00 INFO - 'halt_on_failure': False, 05:02:00 INFO - 'name': 'check_screen_resolution'},), 05:02:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:02:00 INFO - 'cmd': ('bash', 05:02:00 INFO - '-c', 05:02:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:02:00 INFO - 'enabled': True, 05:02:00 INFO - 'halt_on_failure': False, 05:02:00 INFO - 'name': 'check_screen_resolution'},), 05:02:00 INFO - 'run_cmd_checks_enabled': True, 05:02:00 INFO - 'sps_profile': False, 05:02:00 INFO - 'sps_profile_interval': 0, 05:02:00 INFO - 'suite': 'dromaeojs-e10s', 05:02:00 INFO - 'system_bits': '32', 05:02:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:02:00 INFO - 'title': 't-yosemite-r7-0122', 05:02:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:02:00 INFO - 'use_talos_json': True, 05:02:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:02:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:02:00 INFO - 'work_dir': 'build'} 05:02:00 INFO - ##### 05:02:00 INFO - ##### Running clobber step. 05:02:00 INFO - ##### 05:02:00 INFO - Running pre-action listener: _resource_record_pre_action 05:02:00 INFO - Running main action method: clobber 05:02:00 INFO - rmtree: /builds/slave/test/build 05:02:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:02:02 INFO - Running post-action listener: _resource_record_post_action 05:02:02 INFO - ##### 05:02:02 INFO - ##### Running read-buildbot-config step. 05:02:02 INFO - ##### 05:02:02 INFO - Running pre-action listener: _resource_record_pre_action 05:02:02 INFO - Running main action method: read_buildbot_config 05:02:02 INFO - Using buildbot properties: 05:02:02 INFO - { 05:02:02 INFO - "project": "", 05:02:02 INFO - "product": "firefox", 05:02:02 INFO - "script_repo_revision": "production", 05:02:02 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 05:02:02 INFO - "repository": "", 05:02:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 05:02:02 INFO - "buildid": "20160416022721", 05:02:02 INFO - "pgo_build": "False", 05:02:02 INFO - "basedir": "/builds/slave/test", 05:02:02 INFO - "buildnumber": 699, 05:02:02 INFO - "slavename": "t-yosemite-r7-0122", 05:02:02 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 05:02:02 INFO - "platform": "yosemite_r7", 05:02:02 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 05:02:02 INFO - "branch": "fx-team", 05:02:02 INFO - "repo_path": "integration/fx-team", 05:02:02 INFO - "revision": "19d56ca9e3f5078dba44230ac3ec0718cc029b70", 05:02:02 INFO - "stage_platform": "macosx64", 05:02:02 INFO - "builduid": "39a090301730434491ce63017de2658c", 05:02:02 INFO - "slavebuilddir": "test" 05:02:02 INFO - } 05:02:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/OWFExPIzTnCZmN7EVgavXA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 05:02:02 INFO - Running post-action listener: _resource_record_post_action 05:02:02 INFO - ##### 05:02:02 INFO - ##### Running download-and-extract step. 05:02:02 INFO - ##### 05:02:02 INFO - Running pre-action listener: _resource_record_pre_action 05:02:02 INFO - Running main action method: download_and_extract 05:02:02 INFO - mkdir: /builds/slave/test/build/tests 05:02:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:02:02 INFO - https://queue.taskcluster.net/v1/task/OWFExPIzTnCZmN7EVgavXA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:02:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OWFExPIzTnCZmN7EVgavXA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 05:02:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OWFExPIzTnCZmN7EVgavXA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 05:02:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OWFExPIzTnCZmN7EVgavXA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 05:02:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OWFExPIzTnCZmN7EVgavXA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 05:02:08 INFO - Downloaded 1199 bytes. 05:02:08 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 05:02:08 INFO - Using the following test package requirements: 05:02:08 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:02:08 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:02:08 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 05:02:08 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:02:08 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 05:02:08 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:02:08 INFO - u'jsshell-mac.zip'], 05:02:08 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:02:08 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 05:02:08 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:02:08 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:02:08 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 05:02:08 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:02:08 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 05:02:08 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:02:08 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 05:02:08 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:02:08 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 05:02:08 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:02:08 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')]} 05:02:08 INFO - https://queue.taskcluster.net/v1/task/OWFExPIzTnCZmN7EVgavXA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:02:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OWFExPIzTnCZmN7EVgavXA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:02:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OWFExPIzTnCZmN7EVgavXA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:02:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OWFExPIzTnCZmN7EVgavXA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:02:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OWFExPIzTnCZmN7EVgavXA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:02:12 INFO - Downloaded 18031963 bytes. 05:02:12 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:02:12 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:02:15 INFO - Return code: 0 05:02:15 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:02:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:02:15 INFO - https://queue.taskcluster.net/v1/task/OWFExPIzTnCZmN7EVgavXA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:02:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OWFExPIzTnCZmN7EVgavXA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:02:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OWFExPIzTnCZmN7EVgavXA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:02:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OWFExPIzTnCZmN7EVgavXA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:02:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OWFExPIzTnCZmN7EVgavXA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:02:17 INFO - Downloaded 18031963 bytes. 05:02:17 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:02:17 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:02:21 INFO - Return code: 0 05:02:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:02:21 INFO - https://queue.taskcluster.net/v1/task/OWFExPIzTnCZmN7EVgavXA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:02:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OWFExPIzTnCZmN7EVgavXA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:02:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OWFExPIzTnCZmN7EVgavXA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:02:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OWFExPIzTnCZmN7EVgavXA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:02:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OWFExPIzTnCZmN7EVgavXA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:02:22 INFO - Downloaded 10841076 bytes. 05:02:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:02:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:02:22 INFO - Return code: 0 05:02:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:02:22 INFO - https://queue.taskcluster.net/v1/task/OWFExPIzTnCZmN7EVgavXA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:02:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OWFExPIzTnCZmN7EVgavXA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:02:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OWFExPIzTnCZmN7EVgavXA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:02:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OWFExPIzTnCZmN7EVgavXA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 05:02:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OWFExPIzTnCZmN7EVgavXA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 05:02:27 INFO - Downloaded 109846932 bytes. 05:02:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OWFExPIzTnCZmN7EVgavXA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:02:27 INFO - mkdir: /builds/slave/test/properties 05:02:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:02:27 INFO - Writing to file /builds/slave/test/properties/build_url 05:02:27 INFO - Contents: 05:02:27 INFO - build_url:https://queue.taskcluster.net/v1/task/OWFExPIzTnCZmN7EVgavXA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:02:28 INFO - Running post-action listener: _resource_record_post_action 05:02:28 INFO - Running post-action listener: set_extra_try_arguments 05:02:28 INFO - ##### 05:02:28 INFO - ##### Running populate-webroot step. 05:02:28 INFO - ##### 05:02:28 INFO - Running pre-action listener: _resource_record_pre_action 05:02:28 INFO - Running main action method: populate_webroot 05:02:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:02:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:02:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:02:28 INFO - '--tppagecycles', 05:02:28 INFO - '7'], 05:02:28 INFO - 'tests': ['tsvgm']}}, 05:02:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:02:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:02:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:02:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:02:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:02:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:02:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:02:28 INFO - 'talos_options': ['--disable-e10s'], 05:02:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:02:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:02:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:02:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:02:28 INFO - 'talos_options': ['--disable-e10s'], 05:02:28 INFO - 'tests': ['damp', 'tps']}, 05:02:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:02:28 INFO - 'tests': ['damp', 'tps']}, 05:02:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:02:28 INFO - 'tests': ['dromaeo_dom']}, 05:02:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:02:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:02:28 INFO - 'tests': ['a11yr', 05:02:28 INFO - 'ts_paint', 05:02:28 INFO - 'tpaint', 05:02:28 INFO - 'sessionrestore', 05:02:28 INFO - 'sessionrestore_no_auto_restore', 05:02:28 INFO - 'tabpaint']}, 05:02:28 INFO - 'other-e10s': {'tests': ['a11yr', 05:02:28 INFO - 'ts_paint', 05:02:28 INFO - 'tpaint', 05:02:28 INFO - 'sessionrestore', 05:02:28 INFO - 'sessionrestore_no_auto_restore', 05:02:28 INFO - 'tabpaint']}, 05:02:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:02:28 INFO - 'ts_paint', 05:02:28 INFO - 'tpaint', 05:02:28 INFO - 'sessionrestore', 05:02:28 INFO - 'sessionrestore_no_auto_restore', 05:02:28 INFO - 'tabpaint']}, 05:02:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:02:28 INFO - 'ts_paint', 05:02:28 INFO - 'tpaint', 05:02:28 INFO - 'sessionrestore', 05:02:28 INFO - 'sessionrestore_no_auto_restore', 05:02:28 INFO - 'tabpaint']}, 05:02:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:02:28 INFO - 'tests': ['a11yr', 05:02:28 INFO - 'ts_paint', 05:02:28 INFO - 'tpaint', 05:02:28 INFO - 'sessionrestore', 05:02:28 INFO - 'sessionrestore_no_auto_restore', 05:02:28 INFO - 'tabpaint']}, 05:02:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:02:28 INFO - 'tests': ['a11yr', 05:02:28 INFO - 'ts_paint', 05:02:28 INFO - 'tpaint', 05:02:28 INFO - 'sessionrestore', 05:02:28 INFO - 'sessionrestore_no_auto_restore', 05:02:28 INFO - 'tabpaint']}, 05:02:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:02:28 INFO - 'tests': ['tsvgx', 05:02:28 INFO - 'tsvgr_opacity', 05:02:28 INFO - 'tart', 05:02:28 INFO - 'tscrollx', 05:02:28 INFO - 'cart']}, 05:02:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:02:28 INFO - 'tsvgr_opacity', 05:02:28 INFO - 'tart', 05:02:28 INFO - 'tscrollx', 05:02:28 INFO - 'cart']}, 05:02:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:02:28 INFO - 'talos_options': ['--disable-e10s'], 05:02:28 INFO - 'tests': ['tp5o']}, 05:02:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:02:28 INFO - 'tests': ['tp5o']}, 05:02:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:02:28 INFO - 'talos_options': ['--disable-e10s', 05:02:28 INFO - '--xperf_path', 05:02:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:02:28 INFO - 'tests': ['tp5n']}, 05:02:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:02:28 INFO - 'talos_options': ['--xperf_path', 05:02:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:02:28 INFO - 'tests': ['tp5n']}}, 05:02:28 INFO - 'talos.zip': {'path': '', 05:02:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:02:28 INFO - Running post-action listener: _resource_record_post_action 05:02:28 INFO - ##### 05:02:28 INFO - ##### Running create-virtualenv step. 05:02:28 INFO - ##### 05:02:28 INFO - Running pre-action listener: _resource_record_pre_action 05:02:28 INFO - Running main action method: create_virtualenv 05:02:28 INFO - Creating virtualenv /builds/slave/test/build/venv 05:02:28 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 05:02:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:02:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:02:29 INFO - Using real prefix '/tools/python27' 05:02:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:02:29 INFO - Installing distribute.............................................................................................................................................................................................done. 05:02:32 INFO - Installing pip.................done. 05:02:32 INFO - Return code: 0 05:02:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:02:32 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')]} 05:02:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:32 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')]} 05:02:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:32 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 0x109967a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109977420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea4b4a4930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109806e00>, '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 0x10966fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109801b20>, '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.vwZpEEQhoX/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.KEWpU6p5P2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 05:02:32 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 05:02:32 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 05:02:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vwZpEEQhoX/Render', 05:02:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:32 INFO - 'HOME': '/Users/cltbld', 05:02:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:32 INFO - 'LOGNAME': 'cltbld', 05:02:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:32 INFO - 'MOZ_NO_REMOTE': '1', 05:02:32 INFO - 'NO_EM_RESTART': '1', 05:02:32 INFO - 'PAGER': '/bin/cat', 05:02:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:32 INFO - 'PWD': '/builds/slave/test', 05:02:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:32 INFO - 'SHELL': '/bin/bash', 05:02:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KEWpU6p5P2/Listeners', 05:02:32 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 05:02:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:32 INFO - 'USER': 'cltbld', 05:02:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:32 INFO - 'XPC_FLAGS': '0x0', 05:02:32 INFO - 'XPC_SERVICE_NAME': '0', 05:02:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:32 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 05:02:32 INFO - Cleaning up... 05:02:32 INFO - Return code: 0 05:02:32 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:02:32 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')]} 05:02:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:32 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')]} 05:02:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:32 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 0x109967a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109977420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea4b4a4930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109806e00>, '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 0x10966fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109801b20>, '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.vwZpEEQhoX/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.KEWpU6p5P2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 05:02:32 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 05:02:32 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 05:02:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vwZpEEQhoX/Render', 05:02:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:32 INFO - 'HOME': '/Users/cltbld', 05:02:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:32 INFO - 'LOGNAME': 'cltbld', 05:02:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:32 INFO - 'MOZ_NO_REMOTE': '1', 05:02:32 INFO - 'NO_EM_RESTART': '1', 05:02:32 INFO - 'PAGER': '/bin/cat', 05:02:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:32 INFO - 'PWD': '/builds/slave/test', 05:02:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:32 INFO - 'SHELL': '/bin/bash', 05:02:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KEWpU6p5P2/Listeners', 05:02:32 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 05:02:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:32 INFO - 'USER': 'cltbld', 05:02:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:32 INFO - 'XPC_FLAGS': '0x0', 05:02:32 INFO - 'XPC_SERVICE_NAME': '0', 05:02:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:33 INFO - Downloading/unpacking psutil>=0.7.1 05:02: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 05:02: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 05:02: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 05:02: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 05:02: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 05:02: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 05:02:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:02:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:02:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:02:36 INFO - Installing collected packages: psutil 05:02:36 INFO - Running setup.py install for psutil 05:02:36 INFO - building 'psutil._psutil_osx' extension 05:02:36 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 05:02:36 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 05:02:36 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 05:02:36 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 05:02:36 INFO - building 'psutil._psutil_posix' extension 05:02:36 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 05:02:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:02:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:02:37 INFO - ^ 05:02:37 INFO - 1 warning generated. 05:02:37 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 05:02:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:02:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:02:37 INFO - Successfully installed psutil 05:02:37 INFO - Cleaning up... 05:02:37 INFO - Return code: 0 05:02:37 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:02:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:02:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:02:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109967a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109977420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea4b4a4930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109806e00>, '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 0x10966fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109801b20>, '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.vwZpEEQhoX/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.KEWpU6p5P2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 05:02:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 05:02:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:02:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vwZpEEQhoX/Render', 05:02:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:37 INFO - 'HOME': '/Users/cltbld', 05:02:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:37 INFO - 'LOGNAME': 'cltbld', 05:02:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:37 INFO - 'MOZ_NO_REMOTE': '1', 05:02:37 INFO - 'NO_EM_RESTART': '1', 05:02:37 INFO - 'PAGER': '/bin/cat', 05:02:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:37 INFO - 'PWD': '/builds/slave/test', 05:02:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:37 INFO - 'SHELL': '/bin/bash', 05:02:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KEWpU6p5P2/Listeners', 05:02:37 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 05:02:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:37 INFO - 'USER': 'cltbld', 05:02:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:37 INFO - 'XPC_FLAGS': '0x0', 05:02:37 INFO - 'XPC_SERVICE_NAME': '0', 05:02:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:37 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:02: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 05:02: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 05:02: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 05:02: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 05:02: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 05:02: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 05:02:39 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:02:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:02:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:02:40 INFO - Installing collected packages: mozsystemmonitor 05:02:40 INFO - Running setup.py install for mozsystemmonitor 05:02:40 INFO - Successfully installed mozsystemmonitor 05:02:40 INFO - Cleaning up... 05:02:40 INFO - Return code: 0 05:02:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:02:40 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')]} 05:02:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:40 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')]} 05:02:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:40 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 0x109967a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109977420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea4b4a4930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109806e00>, '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 0x10966fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109801b20>, '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.vwZpEEQhoX/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.KEWpU6p5P2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 05:02:40 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 05:02:40 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 05:02:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vwZpEEQhoX/Render', 05:02:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:40 INFO - 'HOME': '/Users/cltbld', 05:02:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:40 INFO - 'LOGNAME': 'cltbld', 05:02:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:40 INFO - 'MOZ_NO_REMOTE': '1', 05:02:40 INFO - 'NO_EM_RESTART': '1', 05:02:40 INFO - 'PAGER': '/bin/cat', 05:02:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:40 INFO - 'PWD': '/builds/slave/test', 05:02:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:40 INFO - 'SHELL': '/bin/bash', 05:02:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KEWpU6p5P2/Listeners', 05:02:40 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 05:02:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:40 INFO - 'USER': 'cltbld', 05:02:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:40 INFO - 'XPC_FLAGS': '0x0', 05:02:40 INFO - 'XPC_SERVICE_NAME': '0', 05:02:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:40 INFO - Downloading/unpacking blobuploader==1.2.4 05:02:40 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 05:02:40 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 05:02:40 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 05:02:40 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 05:02:40 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 05:02:40 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 05:02:42 INFO - Downloading blobuploader-1.2.4.tar.gz 05:02:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:02:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:02:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:02:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:02:43 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 05:02:43 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 05:02:43 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 05:02:43 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 05:02:43 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 05:02:43 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 05:02:43 INFO - Downloading docopt-0.6.1.tar.gz 05:02:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:02:44 INFO - Installing collected packages: blobuploader, requests, docopt 05:02:44 INFO - Running setup.py install for blobuploader 05:02:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:02:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:02:44 INFO - Running setup.py install for requests 05:02:44 INFO - Running setup.py install for docopt 05:02:44 INFO - Successfully installed blobuploader requests docopt 05:02:44 INFO - Cleaning up... 05:02:44 INFO - Return code: 0 05:02:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:02:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:02:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:02:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:44 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 0x109967a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109977420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea4b4a4930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109806e00>, '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 0x10966fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109801b20>, '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.vwZpEEQhoX/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.KEWpU6p5P2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 05:02:44 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 05:02:44 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 05:02:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vwZpEEQhoX/Render', 05:02:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:44 INFO - 'HOME': '/Users/cltbld', 05:02:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:44 INFO - 'LOGNAME': 'cltbld', 05:02:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:44 INFO - 'MOZ_NO_REMOTE': '1', 05:02:44 INFO - 'NO_EM_RESTART': '1', 05:02:44 INFO - 'PAGER': '/bin/cat', 05:02:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:44 INFO - 'PWD': '/builds/slave/test', 05:02:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:44 INFO - 'SHELL': '/bin/bash', 05:02:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KEWpU6p5P2/Listeners', 05:02:44 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 05:02:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:44 INFO - 'USER': 'cltbld', 05:02:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:44 INFO - 'XPC_FLAGS': '0x0', 05:02:44 INFO - 'XPC_SERVICE_NAME': '0', 05:02:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:02:45 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-iy9FMl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:02:45 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-sVHF4J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:02:45 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-GW_myR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:02:45 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-Z86Abx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:02:45 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-SwbwJM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:02:45 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-BMPiRy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:02:45 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-lKEkO7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:02:45 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-uKJSxV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:02:46 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-XXWWkv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:02:46 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-fj59HV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:02:46 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-L6Wds6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:02:46 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-CDaW8y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:02:46 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-LgBfUW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:02:46 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-4meIaP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:02:46 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-ainLcf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:02:46 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-fp9Itq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:02:46 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-LId2Oh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:02:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:02:46 INFO - Running setup.py install for manifestparser 05:02:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:02:47 INFO - Running setup.py install for mozcrash 05:02:47 INFO - Running setup.py install for mozdebug 05:02:47 INFO - Running setup.py install for mozdevice 05:02:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:02:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:02:47 INFO - Running setup.py install for mozfile 05:02:47 INFO - Running setup.py install for mozhttpd 05:02:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:02:47 INFO - Running setup.py install for mozinfo 05:02:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:02:48 INFO - Running setup.py install for mozInstall 05:02:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:02:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:02:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:02:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:02:48 INFO - Running setup.py install for mozleak 05:02:48 INFO - Running setup.py install for mozlog 05:02:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:02:48 INFO - Running setup.py install for moznetwork 05:02:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:02:48 INFO - Running setup.py install for mozprocess 05:02:48 INFO - Running setup.py install for mozprofile 05:02:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:02:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:02:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:02:48 INFO - Running setup.py install for mozrunner 05:02:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:02:49 INFO - Running setup.py install for mozscreenshot 05:02:49 INFO - Running setup.py install for moztest 05:02:49 INFO - Running setup.py install for mozversion 05:02:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:02:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:02:49 INFO - Cleaning up... 05:02:49 INFO - Return code: 0 05:02:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:02:49 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')]} 05:02:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:49 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')]} 05:02:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:49 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 0x109967a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109977420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea4b4a4930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109806e00>, '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 0x10966fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109801b20>, '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.vwZpEEQhoX/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.KEWpU6p5P2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 05:02:49 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 05:02:49 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 05:02:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vwZpEEQhoX/Render', 05:02:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:49 INFO - 'HOME': '/Users/cltbld', 05:02:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:49 INFO - 'LOGNAME': 'cltbld', 05:02:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:49 INFO - 'MOZ_NO_REMOTE': '1', 05:02:49 INFO - 'NO_EM_RESTART': '1', 05:02:49 INFO - 'PAGER': '/bin/cat', 05:02:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:49 INFO - 'PWD': '/builds/slave/test', 05:02:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:49 INFO - 'SHELL': '/bin/bash', 05:02:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KEWpU6p5P2/Listeners', 05:02:49 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 05:02:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:49 INFO - 'USER': 'cltbld', 05:02:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:49 INFO - 'XPC_FLAGS': '0x0', 05:02:49 INFO - 'XPC_SERVICE_NAME': '0', 05:02:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:02:49 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-lxrTPN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:02:49 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)) 05:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:02:49 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-agoG5k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:02:50 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)) 05:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:02:50 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-ifzOEm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:02:50 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)) 05:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:02:50 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-oTRigi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:02:50 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)) 05:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:02:50 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-vYJCbI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:02:50 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)) 05:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:02:50 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-USPUL4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:02:50 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)) 05:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:02:50 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-dCiSym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:02:50 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)) 05:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:02:50 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-uPd85W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:02:50 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)) 05:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:02:50 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-lGo8u6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:02:50 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)) 05:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:02:50 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-WtXPei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:02:50 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)) 05:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:02:50 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-ndnlIO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:02:50 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)) 05:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:02:50 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-DDdMhF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:02:51 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)) 05:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:02:51 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-NLk6LL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:02:51 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)) 05:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:02:51 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-aUFwql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:02:51 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)) 05:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:02:51 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-7q_Dgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:02:51 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)) 05:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:02:51 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-X71D2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:02:51 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)) 05:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:02:51 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-cwSq5n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:02:51 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)) 05:02:51 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)) 05:02:51 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)) 05:02:51 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)) 05:02:51 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)) 05:02:51 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)) 05:02:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:02:51 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 05:02:51 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 05:02:51 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 05:02:51 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 05:02:51 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 05:02:51 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 05:02:53 INFO - Downloading blessings-1.6.tar.gz 05:02:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:02:54 INFO - Installing collected packages: blessings 05:02:54 INFO - Running setup.py install for blessings 05:02:54 INFO - Successfully installed blessings 05:02:54 INFO - Cleaning up... 05:02:54 INFO - Return code: 0 05:02:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:02:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:02:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:02:54 INFO - Reading from file tmpfile_stdout 05:02:54 INFO - Current package versions: 05:02:54 INFO - blessings == 1.6 05:02:54 INFO - blobuploader == 1.2.4 05:02:54 INFO - docopt == 0.6.1 05:02:54 INFO - manifestparser == 1.1 05:02:54 INFO - mozInstall == 1.12 05:02:54 INFO - mozcrash == 0.17 05:02:54 INFO - mozdebug == 0.1 05:02:54 INFO - mozdevice == 0.48 05:02:54 INFO - mozfile == 1.2 05:02:54 INFO - mozhttpd == 0.7 05:02:54 INFO - mozinfo == 0.9 05:02:54 INFO - mozleak == 0.1 05:02:54 INFO - mozlog == 3.1 05:02:54 INFO - moznetwork == 0.27 05:02:54 INFO - mozprocess == 0.22 05:02:54 INFO - mozprofile == 0.28 05:02:54 INFO - mozrunner == 6.11 05:02:54 INFO - mozscreenshot == 0.1 05:02:54 INFO - mozsystemmonitor == 0.0 05:02:54 INFO - moztest == 0.7 05:02:54 INFO - mozversion == 1.4 05:02:54 INFO - psutil == 3.1.1 05:02:54 INFO - requests == 1.2.3 05:02:54 INFO - wsgiref == 0.1.2 05:02:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:02:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:02:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:02:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:54 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 0x109967a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109977420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea4b4a4930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109806e00>, '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 0x10966fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109801b20>, '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.vwZpEEQhoX/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.KEWpU6p5P2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 05:02:54 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 05:02:54 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 05:02:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vwZpEEQhoX/Render', 05:02:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:54 INFO - 'HOME': '/Users/cltbld', 05:02:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:54 INFO - 'LOGNAME': 'cltbld', 05:02:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:54 INFO - 'MOZ_NO_REMOTE': '1', 05:02:54 INFO - 'NO_EM_RESTART': '1', 05:02:54 INFO - 'PAGER': '/bin/cat', 05:02:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:54 INFO - 'PWD': '/builds/slave/test', 05:02:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:54 INFO - 'SHELL': '/bin/bash', 05:02:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KEWpU6p5P2/Listeners', 05:02:54 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 05:02:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:54 INFO - 'USER': 'cltbld', 05:02:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:54 INFO - 'XPC_FLAGS': '0x0', 05:02:54 INFO - 'XPC_SERVICE_NAME': '0', 05:02:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:54 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)) 05:02:54 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)) 05:02:54 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)) 05:02:54 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)) 05:02:54 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)) 05:02:54 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)) 05:02:54 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)) 05:02:54 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)) 05:02:54 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)) 05:02:54 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)) 05:02:54 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)) 05:02:54 INFO - Cleaning up... 05:02:54 INFO - Return code: 0 05:02:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:02:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:02:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:02:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:54 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 0x109967a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109977420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea4b4a4930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109806e00>, '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 0x10966fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109801b20>, '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.vwZpEEQhoX/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.KEWpU6p5P2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 05:02:54 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 05:02:54 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 05:02:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vwZpEEQhoX/Render', 05:02:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:54 INFO - 'HOME': '/Users/cltbld', 05:02:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:54 INFO - 'LOGNAME': 'cltbld', 05:02:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:54 INFO - 'MOZ_NO_REMOTE': '1', 05:02:54 INFO - 'NO_EM_RESTART': '1', 05:02:54 INFO - 'PAGER': '/bin/cat', 05:02:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:54 INFO - 'PWD': '/builds/slave/test', 05:02:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:54 INFO - 'SHELL': '/bin/bash', 05:02:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KEWpU6p5P2/Listeners', 05:02:54 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 05:02:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:54 INFO - 'USER': 'cltbld', 05:02:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:54 INFO - 'XPC_FLAGS': '0x0', 05:02:54 INFO - 'XPC_SERVICE_NAME': '0', 05:02:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:55 INFO - Downloading/unpacking jsonschema 05:02:55 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 05:02:55 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 05:02:55 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 05:02:55 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 05:02:55 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 05:02:55 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 05:02:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:02:57 INFO - Downloading/unpacking functools32 (from jsonschema) 05:02:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:57 INFO - Downloading functools32-3.2.3-2.tar.gz 05:02:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:02:58 INFO - warning: no files found matching '*.txt' 05:02:58 INFO - no previously-included directories found matching 'build' 05:02:58 INFO - no previously-included directories found matching 'dist' 05:02:58 INFO - no previously-included directories found matching '.git*' 05:02:58 INFO - Installing collected packages: jsonschema, functools32 05:02:58 INFO - Running setup.py install for functools32 05:02:58 INFO - warning: no files found matching '*.txt' 05:02:58 INFO - no previously-included directories found matching 'build' 05:02:58 INFO - no previously-included directories found matching 'dist' 05:02:58 INFO - no previously-included directories found matching '.git*' 05:02:58 INFO - Successfully installed jsonschema functools32 05:02:58 INFO - Cleaning up... 05:02:58 INFO - Return code: 0 05:02:58 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:02:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:02:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:02:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109967a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109977420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea4b4a4930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109806e00>, '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 0x10966fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109801b20>, '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.vwZpEEQhoX/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.KEWpU6p5P2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 05:02:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:02:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:02:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vwZpEEQhoX/Render', 05:02:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:58 INFO - 'HOME': '/Users/cltbld', 05:02:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:58 INFO - 'LOGNAME': 'cltbld', 05:02:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:58 INFO - 'MOZ_NO_REMOTE': '1', 05:02:58 INFO - 'NO_EM_RESTART': '1', 05:02:58 INFO - 'PAGER': '/bin/cat', 05:02:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:58 INFO - 'PWD': '/builds/slave/test', 05:02:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:58 INFO - 'SHELL': '/bin/bash', 05:02:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KEWpU6p5P2/Listeners', 05:02:58 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 05:02:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:58 INFO - 'USER': 'cltbld', 05:02:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:58 INFO - 'XPC_FLAGS': '0x0', 05:02:58 INFO - 'XPC_SERVICE_NAME': '0', 05:02:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:58 INFO - Downloading/unpacking flake8 05:02:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:03:00 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:03:00 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:03:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:03:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:03:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:03:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:03:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:03:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:03:01 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:03:01 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:03:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:03:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:03:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:03:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:03:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:03:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:03:01 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:03:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:03:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:03:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:03:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:03:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:03:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:03:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:03:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:03:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:03:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:03:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:03:02 INFO - no previously-included directories found matching 'docs/_build' 05:03:02 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:03:02 INFO - Running setup.py install for pep8 05:03:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:03:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:03:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:03:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:03:02 INFO - no previously-included directories found matching 'docs/_build' 05:03:02 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:03:02 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:03:02 INFO - Cleaning up... 05:03:02 INFO - Return code: 0 05:03:02 INFO - Running post-action listener: _resource_record_post_action 05:03:02 INFO - Running post-action listener: _start_resource_monitoring 05:03:02 INFO - Starting resource monitoring. 05:03:02 INFO - ##### 05:03:02 INFO - ##### Running install step. 05:03:02 INFO - ##### 05:03:02 INFO - Running pre-action listener: _resource_record_pre_action 05:03:02 INFO - Running main action method: install 05:03:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:03:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:03:02 INFO - Reading from file tmpfile_stdout 05:03:02 INFO - Detecting whether we're running mozinstall >=1.0... 05:03:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:03:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:03:02 INFO - Reading from file tmpfile_stdout 05:03:02 INFO - Output received: 05:03:02 INFO - Usage: mozinstall [options] installer 05:03:02 INFO - Options: 05:03:02 INFO - -h, --help show this help message and exit 05:03:02 INFO - -d DEST, --destination=DEST 05:03:02 INFO - Directory to install application into. [default: 05:03:02 INFO - "/builds/slave/test"] 05:03:02 INFO - --app=APP Application being installed. [default: firefox] 05:03:02 INFO - mkdir: /builds/slave/test/build/application 05:03:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:03:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:03:36 INFO - Reading from file tmpfile_stdout 05:03:36 INFO - Output received: 05:03:36 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:03:36 INFO - Running post-action listener: _resource_record_post_action 05:03:36 INFO - ##### 05:03:36 INFO - ##### Running run-tests step. 05:03:36 INFO - ##### 05:03:36 INFO - Running pre-action listener: _resource_record_pre_action 05:03:36 INFO - Running main action method: run_tests 05:03:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:03:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:03:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:03:36 INFO - 2016-04-16 05:03:36.594 screenresolution[1653:9688] starting screenresolution argv=screenresolution get 05:03:36 INFO - 2016-04-16 05:03:36.596 screenresolution[1653:9688] Display 0: 1600x1200x32@60 05:03:36 INFO - 2016-04-16 05:03:36.602 screenresolution[1654:9691] starting screenresolution argv=screenresolution list 05:03:36 INFO - Available Modes on Display 0 05:03:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:03:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:03:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:03:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:03:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:03:36 INFO - Intel Iris: 05:03:36 INFO - Chipset Model: Intel Iris 05:03:36 INFO - Type: GPU 05:03:36 INFO - Bus: Built-In 05:03:36 INFO - VRAM (Dynamic, Max): 1536 MB 05:03:36 INFO - Vendor: Intel (0x8086) 05:03:36 INFO - Device ID: 0x0a2e 05:03:36 INFO - Revision ID: 0x0009 05:03:36 INFO - Displays: 05:03:36 INFO - Display: 05:03:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:03:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:03:36 INFO - Display Serial Number: 1600x1200 60 05:03:36 INFO - Main Display: Yes 05:03:36 INFO - Mirror: Off 05:03:36 INFO - Online: Yes 05:03:36 INFO - Rotation: Supported 05:03:36 INFO - Return code: 0 05:03:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:03:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:03:36 INFO - Python 2.7.3 05:03:36 INFO - Return code: 0 05:03:36 INFO - grabbing minidump binary from tooltool 05:03:36 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')]} 05:03:36 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 0x109806e00>, '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 0x10966fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109801b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:03:36 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 05:03:36 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 05:03:36 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:03:36 INFO - Return code: 0 05:03:36 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:03:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:03:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:03:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:03:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:03:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:03:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:03:38 INFO - Return code: 0 05:03:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OWFExPIzTnCZmN7EVgavXA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0122', '--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 05:03:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OWFExPIzTnCZmN7EVgavXA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0122 --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 05:03:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vwZpEEQhoX/Render', 05:03:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:03:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:03:38 INFO - 'HOME': '/Users/cltbld', 05:03:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:03:38 INFO - 'LOGNAME': 'cltbld', 05:03:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:03:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:03:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:03:38 INFO - 'MOZ_NO_REMOTE': '1', 05:03:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:03:38 INFO - 'NO_EM_RESTART': '1', 05:03:38 INFO - 'PAGER': '/bin/cat', 05:03:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:03:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:03:38 INFO - 'PWD': '/builds/slave/test', 05:03:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:03:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:03:38 INFO - 'SHELL': '/bin/bash', 05:03:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KEWpU6p5P2/Listeners', 05:03:38 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 05:03:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:03:38 INFO - 'USER': 'cltbld', 05:03:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:03:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:03:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:03:38 INFO - 'XPC_FLAGS': '0x0', 05:03:38 INFO - 'XPC_SERVICE_NAME': '0', 05:03:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:03:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OWFExPIzTnCZmN7EVgavXA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0122', '--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 05:03:38 INFO - mozversion application_buildid: 20160416022721 05:03:38 INFO - mozversion application_changeset: 19d56ca9e3f5078dba44230ac3ec0718cc029b70 05:03:38 INFO - mozversion application_display_name: Nightly 05:03:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:03:38 INFO - mozversion application_name: Firefox 05:03:38 INFO - mozversion application_remotingname: firefox 05:03:38 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:03:38 INFO - mozversion application_vendor: Mozilla 05:03:38 INFO - mozversion application_version: 48.0a1 05:03:38 INFO - mozversion platform_buildid: 20160416022721 05:03:38 INFO - mozversion platform_changeset: 19d56ca9e3f5078dba44230ac3ec0718cc029b70 05:03:38 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:03:38 INFO - mozversion platform_version: 48.0a1 05:03:38 INFO - using testdate: 1460808218 05:03:38 INFO - actual date: 1460808218 05:03:38 INFO - starting webserver on 'localhost:49277' 05:03:38 INFO - SUITE-START | Running 2 tests 05:03:38 INFO - TEST-START | dromaeo_css 05:03:38 INFO - operating with platform_type : mac_ 05:03:38 INFO - Initialising browser for dromaeo_css test... 05:03:38 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/tmpLfnPcl/profile http://localhost:49277/getInfo.html) 05:03:40 INFO - PROCESS | 1672 | JavaScript error: jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/omni.ja!/components/Weave.js, line 13: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIXPCComponents_Utils.import] 05:03:41 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 05:03:41 INFO - PROCESS | 1672 | colorDepth:24 05:03:41 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 05:03:41 INFO - PROCESS | 1672 | __metrics 05:03:41 ERROR - PROCESS | 1672 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 05:03:42 INFO - TEST-INFO | 1672: exit 0 05:03:42 INFO - Browser initialized. 05:03:42 INFO - Running cycle 1/1 for dromaeo_css test... 05:03:42 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/tmpLfnPcl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:03:54 INFO - PROCESS | 1676 | RSS: Main: 247570432 05:03:54 INFO - PROCESS | 1676 | 05:04:43 INFO - PROCESS | 1676 | 1460808283771 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] 05:06:54 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 05:06:55 INFO - PROCESS | 1676 | RSS: Main: 259973120 05:06:55 INFO - PROCESS | 1676 | 05:09:57 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 05:09:58 INFO - PROCESS | 1676 | RSS: Main: 271540224 05:09:58 INFO - PROCESS | 1676 | 05:13:00 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 05:13:01 INFO - PROCESS | 1676 | RSS: Main: 273522688 05:13:01 INFO - PROCESS | 1676 | 05:16:02 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 05:16:03 INFO - PROCESS | 1676 | RSS: Main: 275173376 05:16:03 INFO - PROCESS | 1676 | 05:19:06 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 05:19:06 INFO - PROCESS | 1676 | RSS: Main: 278036480 05:19:06 INFO - PROCESS | 1676 | 05:22:09 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 05:22:09 INFO - PROCESS | 1676 | RSS: Main: 278904832 05:22:09 INFO - PROCESS | 1676 | 05:22:09 INFO - PROCESS | 1676 | __start_tp_report 05:22:09 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 05:22:09 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 05:22:09 INFO - PROCESS | 1676 | |i|pagename|runs| 05:22:09 INFO - PROCESS | 1676 | |0;dojo.html;3061.6172978377704;3400.0989737719524;3432.9999999999995;3466.4453687410005;3507.158282012317;4683.7822166236765;5526.806561770329;5629.071203251464;5640.915386269209;5654.660720356782;5598.104303311471;5678.687672178028;5693.77224911003;5724.398938111512;5749.655020698761;5137.357830271216;5180.093483640367;5220.895582088356;5264.447233040533;5278.9208161877605;5103.3875934887865;5127.077126117316;5195.896082078356;5211.009908117446;5324.307839980816;5069.315642388267;5089.567386772092;5121.0270048691;5150.819721309755;5165.070287348276;5114.181730923035;5116.189020001094;5125.692458452505;5127.7948882044675;5161.767720452551;5075.213341932006;5076.898462030776;5081.441041485434;5135.460776618476;5195.168772996302;4445.666300109954;5086.287919691246;5528.585356098309;5528.917066244009;5555.083411237141;6068.878622427526;6071.33215346316;6132.938670613281;6150.077488376734;6157.692115394212;4975.676581022222;5073.518015788531;6140.811014692577;6184.845378865564;6187.443130118266;5611.905678392756;5741.712914354265;5779.075347944308;5800.071988481823;5801.738921748573;5368.731563421813;6245.406686364788;6248.687565621701;6256.499480041586;6260.342664020303;5902;6078.54410919174;6137.4995631051315;6139.30815411195;6139.837983438532;6172.69136543171;6185.165002724574;6221.160143380676;6230.00319948806;6251.9687401563615;6010.188624535632;6165.290991535941;6184.226971628546;6188.81433556994;6197.5351848611535;6045.063015232646;6114.78598249068;6122.112293717475;6151.96924015377;6198.876022479525;4759.785809638574;4819.903601927942;4831.347768051339;4831.806727730851;4846.903061938741;4287.335463003239;4340.544242854518;4345.67407444443;4356.4336636237085;4383.254846676073;5565.916511252334;5638.85902852432;5638.85902852432;5652.067408877571;5667.914981275284;6049.334573196945;6165.136880836686;6200.751969921243;6226.159468471789;6235.064740288903;5499.120140777456;5595.916061259084;5622.690752008597;5624.015797235467;5625.662460252316;5282.6302158849285;5294.708791016454;5358.651687640291;5376.327959005124;5403.13549832025;6295.276043254993;6340.270868850049;6350.809475715736;6352.428281454783;6365.649889256136;6102;6163.3528479509005;6235.906461402992;6250.562460627712;6262.937370626418;6211.09939058843;6299.118123462627;6300.086487459201;6311.558190926591;6323.177986861679;6069.939300607114;6120.816375508742;6136.048912418582;6143.416375444325;6149.631022138686;6101.969490152698;6117.84705382369;6123.343628574205;6138.3861613840045;6209.627422354673;5890.410958904075;6023.427774361428;6041.546883983631;6042.788502402394;6057.636541807506;6015;6022.66875321844;6031;6049.274087109575;6055.2430946131735;4949.455559888482;5683.232763576864;5722.713864306851;5816.680082595498;5849.151872978479;5825.6795876225515;5985.40145985398;6094.856286623056;6109.481429920806;6120.020796672689;4930.630202734881;5897;5953.255843019622;5983.640981541122;5996.790112346047 05:22:09 INFO - PROCESS | 1676 | |1;ext.html;516.8346129238644;557.4731878374935;562.0501352713914;590.9025010873206;608.6348191085349;29906.551401728964;34883;38404;38561;38823;39379.606203937954;39935;40085.398719019235;40103.99999999993;40105.19789604206;7954.96022519888;9540.427574345527;9631.27765417593;9633.08485693858;9641.61433542661;9527.23782097434;9629.229661627052;9665.565049572786;9726.17327527161;9743.464109473976;9552.331336806426;9631.855522167172;9678.032196780301;9759.316847820655;9798.314118011742;9589.712308630753;9601.375910565861;9626.56680449378;9738.367006144614;9759.219262458986;8507.957460212721;9593.280503962162;9601.519924003842;9727.854082188773;9734.172595329406;31269;31481.527777083364;31628.683713162805;31706.682933170603;31725.84137079323;2493.962590561143;2710.227585138226;3039.3465404937824;3583;3927.8625248116464;26381.472370552674;27112.915483380642;27777.86111069452;27820.026299079436;27827;2640.1419538650016;2656.8007399444928;2761.737634924679;2773.417582307718;2778.847163406032;370503.58992819994;371152;373810;375232.99999999726;375447;35472.64527354745;36469.08827279339;58947.70526147342;59469.107963380586;60003;34760;35797.1050723734;35952.28095438077;35982;35996;14917.179555124356;15089.47186848477;15089.547313580391;15126.924365378103;15145;12363.814542781865;12669.683257918627;12787.872121278855;12788.296643684687;12827;8090.676372945016;8164.346852251806;8187.1812818718845;8204.097549269576;8227.588620568948;3430.5368775215525;3441.1397150712323;3449.4308439107767;3481.3385456763135;3487.598926123528;956.2780101023822;1002.7242508310273;1019.6482213636284;1054.4516851237468;1063.9680809575725;27268.72731272662;27533.898644054414;27895.72104278972;28207.71792282051;28303.009394671088;992.7468495533593;1109.8346735927244;1145.8911403416662;1180.451090243041;1274.0635632809876;14632;14663.853361466463;14738;14772;14897;633038;633497;636390;645911;646150;59504.70247648734;60630;60645.69677151586;60729;60901.69549152315;1450.3835869755312;1487.2266421460997;1499.5576304990054;1501.8573235542606;1502.2413681091198;27703.58444623292;27776.305592360353;28397.716022839508;28410.289742756595;28432.715672843835;49195;49722;50289.49710502848;50433;50732.492675072775;552760;553393;554208;559426;561898;22084.33746987593;25961.221163365128;26769;27026.054088106823;27172.32069198286;45957.77021114873;46518.534814652776;46876.29685554651;47018.529814702786;47164;38230.808845955595;38284.46862125484;38377.40785134775;38524.8442546724;39788.602113978486;27692.307692307048;31421.371572568878;37103.62896371002;37906.81046594749;38109.42835857409;30559.388812224075;30726;38002.619973799905;38207;39778.60221397749;30992.69007309898;32677.673223267462;32688.182795430304;32978.17554561155;32986.67013329836;24023.75976240215;24521.141760038314;24634.75365246396;24840.627390588794;24855.87572062128 05:22:09 INFO - PROCESS | 1676 | |2;jquery.html;390.7889739540647;393.1252962159196;399.4647172788463;400.25951988820674;406.05997116674894;14673;19933.810590305555;20496.180152793906;20583.794162058395;20671.483212919684;20381.184752609915;20722;20797.96010199488;21091.894540527315;21164.576708465822;5669.886602267952;7279.963600182018;7783.805404864867;7786.182450842665;7801.843963120734;7309.524880882752;7466.66400011998;7474.32731054205;7621.885671714928;7700.730474433396;7240.637968101574;7510.324070833624;7525.2850979157165;7571.621418929031;7575.621218939059;7473.663685134182;7508.662110205027;7510.136334321568;7601.0878694557005;7617.895405166248;6552.8361790955605;7012.158540975065;7021.262767409399;7062.5056246062795;7422.925770742278;19925.10337034837;26591;26654.46691066187;28504;28785.85607071972;2015.3349394699715;2248.280550223929;2281.555096756133;2458.385403649088;2926.7853840656103;15068;16214.513564592962;16261.430849920314;16376.181190940406;16468.176591170515;1869.4204796513125;1939.398786376228;1963.2637760839684;1975.8320542753884;2103.316422162803;119821;150247;150788;151945;154359.4230432711;20343.4914127148;21569.67645485319;21621;22025.559488810453;22178.001989910492;35641.64358356435;36190.8190459046;36554.903352899455;36562.63437365645;36593.26813463716;10630.73423164427;10972.835407468894;11118.388488633203;11214.327140371604;11343.602973895873;8236.588170591567;8959.776005599912;9105.180533751862;9222.170004699608;9230.123138301851;7737.711472624224;9846.556904939294;9874.210063194927;9882.91287958324;9949;3835.386338185877;3958.44581758554;4007.3751692641367;4052.918941621151;4067.51189857219;907.6505545365083;932.3287233192091;1008.4201584089178;1035.2028937717914;1035.637526865606;19586.335161511146;19939.10274037672;19949.902755348594;20154;20424;743.5204293230836;846.2595229174473;926.1988380051207;957.6073809737974;966.4974213408988;10291.53688084038;11271.38007409584;11279.887201128047;11330.60342887995;11332.716682083013;560656.7868642686;619156;622034;622617;640315;128105;134578;137520;150849.49150508575;152290.23854880655;861.5003298087095;1034.6497820344373;1055.0504545908743;1057.413135709673;1063.8510608514814;17802.91098544551;18122;18172.636547268718;18201.726974095665;18216.81783182151;51844.40777961011;52280.47719522908;52658;53915.730421347645;54907.176392354944;68693.62612747618;70384.64807675929;72518;73702.63148684222;75398.24601753912;7805.687772489036;7870.9606451967375;7880.684772609261;7956.2043795619975;8233.094359620556;10150.898491014996;10953.233273670767;12016.879831201926;12067;12191.390430478617;10383.117435018108;10406.947965260126;10471.371717696962;10561.679790026246;10725.517351719036;10376.429296388771;10495.422751748727;10639.042486176282;10702.464876756287;10724.195685323788;10596.947015264874;10793.946030269799;10962.068224201032;10982.670519884416;10997.61508347204;5929.466348028698;7319.451041171827;7426.443016773872;7809.570473624005;8238.464499807493;10494.632687855888;10743.355398676105;11002.834957475485;11023.06303964172;11029.944850275697 05:22:09 INFO - PROCESS | 1676 | |3;mootools.html;1575.2123938030984;1585.02520949616;1851.4723303858402;1951.4535929939623;1951.912163952622;8013.799655008613;8364.749057528275;8603.698870539532;8642.481451112937;8660.350473714472;8471.618777155027;8502.659893604248;8557.871631925525;8569.27161191298;8581.613827377767;1848.833604975552;2447.21689059501;2469.5678256305073;2469.753024697534;2481.9203646413775;1983.6925276582153;2458.2379462366634;2482.416632091457;2483.416397146669;2491.638712386703;2455.7544245575477;2458.6189140683223;2471.085698291635;2472.7389031593925;2499;2048.8770673759623;2472.7279999200073;2482.726900040994;2488.0669748844184;2671.3722275265295;2342.953140937189;2436.658867758515;2463.8275320727557;2469.95060098797;2474.542209691213;8190.689489681682;8830.381873268874;8847.07105753893;8893.288536917096;8969.461832290084;4885.755712214411;5301.496357845998;5402.51377376038;5426.21319908615;5426.9185962210595;3534.1341371363924;3602.6310002198998;3613.9819300903328;3643.1074386775144;3657.9268414631824;1030.6753372687579;1048.3028785857396;1049.4700176410863;1063.984040239397;1087.0868470484832;11619.128565357632;11739.823902641467;11759.823602645967;11790.705232369173;11801;4076.7961601919787;4335.3713711511655;4528.547145285445;4693.95306046942;4898.24077268024;9404.95297523508;9769.413835169913;9891.901080989242;9953.104220620178;9955.303128780915;5343.118385466432;5357.8392648220615;5364.597655175878;5419.729013549307;5425.457454254543;4441.777911104432;4826.61387089032;4831.903361932742;4836.613070954315;4948.4061912570005;4056.6957478189256;4059.8579049733116;4064.878053658395;4102.084051830355;4113.794310284474;1331.9200847949155;1352.891768658505;1358.5788405594296;1362.3188405797102;1372.2589801507274;808.4946908182386;867.4925168776336;894.9260886935704;945.9668911588061;966.6906589891167;4512.435945506812;5967.880642387215;6057.242844644419;6080.361562036011;6188.566800324024;454.28223407016833;463.30041637127505;472.78488287829646;473.5974421741492;474.5468077985515;3935.1145992151537;4047.473828402348;4077.9796101019306;4088.7751173685733;4119.670426365884;10288.691339259834;10557.841632375521;10736.463176841127;10796.244262901522;10802.351858888493;10690.78618427627;10742.785144297226;10745.731356715989;10772.084372828398;10778.568857245778;7721.227877212234;8041.396895232764;8042.5174489529645;8140.0638926523025;8208.09710931797;5569.554435645057;5886.499647529837;5980.252468441445;6009.849753756016;6081.817545473643;9192.49441280736;9563.952180239054;9565.61737530491;9572.282078844253;9626.133647971716;9225.907740922505;9452.054794520493;9674.9032509674;9744.902550974399;9838.212942964403;7992.840143196987;8193.139720329518;8272.545010024507;8310.459820111673;8311.418200725891;8175.67297308101;8190.836183276182;8202.507849529047;8223.712170074019;8319.750407487785;7535;7540.472166948481;7546.584937828472;7669.233076692286;7691.50005249657;7262.455315851226;7339.522931009467;7373.889391659022;7439.367653749276;7476;7006.334398232159;7016.052832867497;7021.1574611047;7045.823854403681;7091.936209568606;4949.183384741621;4986.501349864984;5013.348264725562;5042.974785126051;5056.418511871182;4252.87241382759;4305.354196870495;4969.627277954096;5088.7201203932655;5118.283440318432 05:22:09 INFO - PROCESS | 1676 | |4;prototype.html;272.8403883728019;274.3032696949748;317.76485400803404;323.13400087764796;332.9550510681057;5272.841814745564;6045.365236650149;6096.26844778626;6116.6756632479655;6143.877122457554;5410.377806552247;5909.793157239497;6030.276366835975;6057.909576276268;6111.205543279368;4312.094460163369;4416.160929423415;4508.706934049293;4537.319402089696;4543.886402839922;4465.226883283636;4475.373447717322;4534.501204867478;4536.9092618147615;4569.634429245652;3780.9810950945207;4090.365993271048;4255.361695745632;4526.79629416677;4567.109413664337;4396.802143903516;4498.617617502517;4535.024969631545;4537.795799189028;4544.954550454519;3876.9612303877166;3889.5527014393424;3892.377219644843;3904.6333783175946;3927.2734544109144;4295.174550815891;5454.400015998237;5700.885982280372;5754.597178197528;5785.421457854223;4814.6870453420415;5102.311187989648;5134.486551344873;5160.7419629018395;5266.499682530153;5625.6905870177125;6180.783672571483;6222.3466536013975;6375.45808606269;6433.967830160819;3440;3939.8227079781477;4105.055837157471;4201.747895126272;4245.64139475368;5734.197212390227;6340.651264180468;6737.898931516032;6779.898231548236;6799.7280108795585;4276.48682158143;4280.743155410685;4289.313709806416;4294.57054294568;4420.8673739787855;3819.3125237457525;3839.500864887603;4193.161367726467;4354.629856462173;4364.192624364503;4116.447802362554;4478.328250762346;5040.39515258164;5116.155834287375;5151.22731590257;5671.234383358276;6436.397975447578;6457.741690332334;6463.289038205794;6468.932626116638;1149.3735913926762;1241.7206128620987;1330.6207730796677;1472.0578829549097;1488.8362280149174;900.5767289373983;919.0212423768666;951.4481600671594;985.4579981010415;986.6152200641756;5345.839624811262;5849.268841394825;5909.3795151508475;5935.495482884004;5950.940490595125;5742.310922689304;6576.210854697467;6580.901286480706;6615.801525954229;6631.635260060743;5496.258005169251;5667.064934285879;5866.178734977107;5955;6005.729742161613;2774.680911695181;3032.9545056824163;3040.939181216363;3051.9847400763297;3456.6197718250974;6522.673866306665;6573.211214654177;6857.5199736018;6887.552309099892;6908;5931.584789064724;6685.532012759158;6763.830904227335;6807.761728339485;6829.795106146824;5944.554158438134;6555.344465553407;6613.6693165340575;6810.387065164159;6889.655517224119;6502.187226596676;6559.0817285581015;6569.441597464269;6580;6590.934090659032;5229.581633469237;6219.564630475823;6481.935180648322;6483.384078512534;6523.510736694672;5960.496591431523;6071.907056729831;6491.026346048131;6715.966420167868;6881;4067.6949228807366;4224.978875105605;4260.744355338689;4281.957180428156;4302.247106756367;5067.543921047123;6129.662868542095;6136.23297087864;6316.463100636497;6480.319566445455;6292.433680968735;6299.212598425197;6300.936990630035;6316.336784637547;6354.6504942226775;5694.544436444991;5878.823635290948;6097.2683278006925;6283.528735344776;6288.434040936338;5994.370591088047;6099.756009759737;6191.288001879841;6191.907121393093;6279.797303375402;5141.588672906084;6453.386928241809;6473.255575608865;6475.676216189266;6512.772052978123;5306.28365170697;6005.2676032406025;6334;6347.777827776005;6351.96824015877 05:22:09 INFO - PROCESS | 1676 | |5;yui.html;656.3108735827382;762.2948772385546;763.6716212028832;767.9769606911794;768.3660979691754;14707.117572945617;14937.804975601926;15254.466093686724;15270.465533706323;15425.768613470807;14248.928755356208;14668.826493880491;14728.410863565472;14740.484083057096;14771.482998095069;742.6954948471127;925.0240995749498;930.7673081729567;936.7517607833895;937.9108984646448;905.5245995852165;924.2421214604027;944.7118628818242;947.2706016367393;950.9524523773819;840.9579521023954;982.6020461712982;989.9950500247488;990.7374545745383;996.2577879479798;948.2698322291866;962.4658314635375;976.0532283684814;977.0229770229806;980.9509524523745;719.8740220461399;734.6767422334156;736.6427282767853;741.3735393592406;743.4795643049887;14615.63460913475;14649.926750366287;14803.777943330859;15145.545633631009;15149.166795826226;12951.740965180737;13004.869951300556;13061;13097.86902130976;13211.537596184184;10147.492625368775;10249.84625230622;10450.111740502067;10561.947190264;10595.046445819837;3463.307338532278;3483.303339332144;3526.959546933661;3535;3624.981875090634;62043.68978155126;82954;86316;89967.10032899653;91226.5438672809;7485.962570187223;9673.951630241945;9747.220222382193;10056.547455364525;10063.899361006443;20658.58682826335;21442.678359824615;21634.91825408723;21680.89159554192;21752.02115904788;6553.377429144223;7538.509996850187;7639.350655194259;7854.6858125674335;7976.521408715495;4109.815058322382;4147.294959856831;4156.91686166281;4162.958370416257;4164.895877603084;3530.593981692087;3562.6615471530163;3574.6782789549065;3631.291898079902;3873.8063096845044;695.45754311636;712.3410844968383;714.1002337055244;714.9428045756327;715.7673756029311;527.5753018819887;567.7643777832236;572.9026065568863;615.2432508015166;621.1893479009846;6906.205786334535;6958.512904096665;7898.44710870245;7961.323287520626;8366.414350995372;571.4200086911787;594.5927039977629;596.3946594206883;603.9009003613469;614.757170917494;3260.7065364117343;3897.8051097445014;3908.413737939275;3940.15286713358;3951.9012024699614;77914.6104269475;96682.51658741802;98310;98886.50556747314;99788.50105749424;83540;83540.58229708813;83737;85944;88171;74141.6292918532;74488;74769.6261518689;75098.87351689729;75352.62323688346;6506.739730410731;7285.307895749896;7705.691772329044;7721.073471183494;7743.5353878767455;31052.68947310498;44659;44788.77605611951;45376.31935521036;46306;67515;67993;68703.3129668697;68717.65641171762;69289.65355173191;10108.832354601383;10120.443375614117;10261.794764104825;10336.483175841327;10515.05364517197;10994.890051099386;11044.33733975964;11135.220534562503;11400.258983166399;11419.885801142214;10353.84469232947;10427.635032773816;10442;10563.260571759904;11190.776184476103;10526.578936842441;10619.203559733205;10861;10873.836892446778;11265.887341126483;10068.899311006797;10110.949445252727;10377.221708371751;10498.790024199321;10601.0459058682;8727.91272087271;8781.882386926707;8813.471191728517;9117.361784675275;9213.76965575866;8446.915530844613;8957.865632015655;8965.686200982935;9030.819383612423;9162.358634895494 05:22:09 INFO - PROCESS | 1676 | __end_tp_report 05:22:09 INFO - PROCESS | 1676 | __start_cc_report 05:22:09 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,405 05:22:09 INFO - PROCESS | 1676 | __end_cc_report 05:22:09 INFO - PROCESS | 1676 | __startTimestamp1460809329561__endTimestamp 05:22:09 INFO - PROCESS | 1676 | 05:22:09 INFO - PROCESS | 1676 | ------- Summary: start ------- 05:22:09 INFO - PROCESS | 1676 | Number of tests: 6 05:22:09 INFO - PROCESS | 1676 | 05:22:09 INFO - PROCESS | 1676 | [#0] dojo.html Cycles:165 Average:5640.51 Median:5816.68 stddev:654.38 (11.3%) stddev-sans-first:624.33 05:22:09 INFO - PROCESS | 1676 | Values: 3061.6 3400.1 3433.0 3466.4 3507.2 4683.8 5526.8 5629.1 5640.9 5654.7 5598.1 5678.7 5693.8 5724.4 5749.7 5137.4 5180.1 5220.9 5264.4 5278.9 5103.4 5127.1 5195.9 5211.0 5324.3 5069.3 5089.6 5121.0 5150.8 5165.1 5114.2 5116.2 5125.7 5127.8 5161.8 5075.2 5076.9 5081.4 5135.5 5195.2 4445.7 5086.3 5528.6 5528.9 5555.1 6068.9 6071.3 6132.9 6150.1 6157.7 4975.7 5073.5 6140.8 6184.8 6187.4 5611.9 5741.7 5779.1 5800.1 5801.7 5368.7 6245.4 6248.7 6256.5 6260.3 5902.0 6078.5 6137.5 6139.3 6139.8 6172.7 6185.2 6221.2 6230.0 6252.0 6010.2 6165.3 6184.2 6188.8 6197.5 6045.1 6114.8 6122.1 6152.0 6198.9 4759.8 4819.9 4831.3 4831.8 4846.9 4287.3 4340.5 4345.7 4356.4 4383.3 5565.9 5638.9 5638.9 5652.1 5667.9 6049.3 6165.1 6200.8 6226.2 6235.1 5499.1 5595.9 5622.7 5624.0 5625.7 5282.6 5294.7 5358.7 5376.3 5403.1 6295.3 6340.3 6350.8 6352.4 6365.6 6102.0 6163.4 6235.9 6250.6 6262.9 6211.1 6299.1 6300.1 6311.6 6323.2 6069.9 6120.8 6136.0 6143.4 6149.6 6102.0 6117.8 6123.3 6138.4 6209.6 5890.4 6023.4 6041.5 6042.8 6057.6 6015.0 6022.7 6031.0 6049.3 6055.2 4949.5 5683.2 5722.7 5816.7 5849.2 5825.7 5985.4 6094.9 6109.5 6120.0 4930.6 5897.0 5953.3 5983.6 5996.8 05:22:09 INFO - PROCESS | 1676 | 05:22:09 INFO - PROCESS | 1676 | [#1] ext.html Cycles:180 Average:64052.58 Median:203803.61 stddev:143327.30 (70.3%) stddev-sans-first:143649.99 05:22:09 INFO - PROCESS | 1676 | Values: 516.8 557.5 562.1 590.9 608.6 29906.6 34883.0 38404.0 38561.0 38823.0 39379.6 39935.0 40085.4 40104.0 40105.2 7955.0 9540.4 9631.3 9633.1 9641.6 9527.2 9629.2 9665.6 9726.2 9743.5 9552.3 9631.9 9678.0 9759.3 9798.3 9589.7 9601.4 9626.6 9738.4 9759.2 8508.0 9593.3 9601.5 9727.9 9734.2 31269.0 31481.5 31628.7 31706.7 31725.8 2494.0 2710.2 3039.3 3583.0 3927.9 26381.5 27112.9 27777.9 27820.0 27827.0 2640.1 2656.8 2761.7 2773.4 2778.8 370503.6 371152.0 373810.0 375233.0 375447.0 35472.6 36469.1 58947.7 59469.1 60003.0 34760.0 35797.1 35952.3 35982.0 35996.0 14917.2 15089.5 15089.5 15126.9 15145.0 12363.8 12669.7 12787.9 12788.3 12827.0 8090.7 8164.3 8187.2 8204.1 8227.6 3430.5 3441.1 3449.4 3481.3 3487.6 956.3 1002.7 1019.6 1054.5 1064.0 27268.7 27533.9 27895.7 28207.7 28303.0 992.7 1109.8 1145.9 1180.5 1274.1 14632.0 14663.9 14738.0 14772.0 14897.0 633038.0 633497.0 636390.0 645911.0 646150.0 59504.7 60630.0 60645.7 60729.0 60901.7 1450.4 1487.2 1499.6 1501.9 1502.2 27703.6 27776.3 28397.7 28410.3 28432.7 49195.0 49722.0 50289.5 50433.0 50732.5 552760.0 553393.0 554208.0 559426.0 561898.0 22084.3 25961.2 26769.0 27026.1 27172.3 45957.8 46518.5 46876.3 47018.5 47164.0 38230.8 38284.5 38377.4 38524.8 39788.6 27692.3 31421.4 37103.6 37906.8 38109.4 30559.4 30726.0 38002.6 38207.0 39778.6 30992.7 32677.7 32688.2 32978.2 32986.7 24023.8 24521.1 24634.8 24840.6 24855.9 05:22:09 INFO - PROCESS | 1676 | 05:22:09 INFO - PROCESS | 1676 | [#2] jquery.html Cycles:180 Average:37810.11 Median:2369.97 stddev:103014.88 (4346.7%) stddev-sans-first:103265.54 05:22:09 INFO - PROCESS | 1676 | Values: 390.8 393.1 399.5 400.3 406.1 14673.0 19933.8 20496.2 20583.8 20671.5 20381.2 20722.0 20798.0 21091.9 21164.6 5669.9 7280.0 7783.8 7786.2 7801.8 7309.5 7466.7 7474.3 7621.9 7700.7 7240.6 7510.3 7525.3 7571.6 7575.6 7473.7 7508.7 7510.1 7601.1 7617.9 6552.8 7012.2 7021.3 7062.5 7422.9 19925.1 26591.0 26654.5 28504.0 28785.9 2015.3 2248.3 2281.6 2458.4 2926.8 15068.0 16214.5 16261.4 16376.2 16468.2 1869.4 1939.4 1963.3 1975.8 2103.3 119821.0 150247.0 150788.0 151945.0 154359.4 20343.5 21569.7 21621.0 22025.6 22178.0 35641.6 36190.8 36554.9 36562.6 36593.3 10630.7 10972.8 11118.4 11214.3 11343.6 8236.6 8959.8 9105.2 9222.2 9230.1 7737.7 9846.6 9874.2 9882.9 9949.0 3835.4 3958.4 4007.4 4052.9 4067.5 907.7 932.3 1008.4 1035.2 1035.6 19586.3 19939.1 19949.9 20154.0 20424.0 743.5 846.3 926.2 957.6 966.5 10291.5 11271.4 11279.9 11330.6 11332.7 560656.8 619156.0 622034.0 622617.0 640315.0 128105.0 134578.0 137520.0 150849.5 152290.2 861.5 1034.6 1055.1 1057.4 1063.9 17802.9 18122.0 18172.6 18201.7 18216.8 51844.4 52280.5 52658.0 53915.7 54907.2 68693.6 70384.6 72518.0 73702.6 75398.2 7805.7 7871.0 7880.7 7956.2 8233.1 10150.9 10953.2 12016.9 12067.0 12191.4 10383.1 10406.9 10471.4 10561.7 10725.5 10376.4 10495.4 10639.0 10702.5 10724.2 10596.9 10793.9 10962.1 10982.7 10997.6 5929.5 7319.5 7426.4 7809.6 8238.5 10494.6 10743.4 11002.8 11023.1 11029.9 05:22:09 INFO - PROCESS | 1676 | 05:22:09 INFO - PROCESS | 1676 | [#3] mootools.html Cycles:180 Average:5693.79 Median:4978.06 stddev:3167.37 (63.6%) stddev-sans-first:3161.13 05:22:09 INFO - PROCESS | 1676 | Values: 1575.2 1585.0 1851.5 1951.5 1951.9 8013.8 8364.7 8603.7 8642.5 8660.4 8471.6 8502.7 8557.9 8569.3 8581.6 1848.8 2447.2 2469.6 2469.8 2481.9 1983.7 2458.2 2482.4 2483.4 2491.6 2455.8 2458.6 2471.1 2472.7 2499.0 2048.9 2472.7 2482.7 2488.1 2671.4 2343.0 2436.7 2463.8 2470.0 2474.5 8190.7 8830.4 8847.1 8893.3 8969.5 4885.8 5301.5 5402.5 5426.2 5426.9 3534.1 3602.6 3614.0 3643.1 3657.9 1030.7 1048.3 1049.5 1064.0 1087.1 11619.1 11739.8 11759.8 11790.7 11801.0 4076.8 4335.4 4528.5 4694.0 4898.2 9405.0 9769.4 9891.9 9953.1 9955.3 5343.1 5357.8 5364.6 5419.7 5425.5 4441.8 4826.6 4831.9 4836.6 4948.4 4056.7 4059.9 4064.9 4102.1 4113.8 1331.9 1352.9 1358.6 1362.3 1372.3 808.5 867.5 894.9 946.0 966.7 4512.4 5967.9 6057.2 6080.4 6188.6 454.3 463.3 472.8 473.6 474.5 3935.1 4047.5 4078.0 4088.8 4119.7 10288.7 10557.8 10736.5 10796.2 10802.4 10690.8 10742.8 10745.7 10772.1 10778.6 7721.2 8041.4 8042.5 8140.1 8208.1 5569.6 5886.5 5980.3 6009.8 6081.8 9192.5 9564.0 9565.6 9572.3 9626.1 9225.9 9452.1 9674.9 9744.9 9838.2 7992.8 8193.1 8272.5 8310.5 8311.4 8175.7 8190.8 8202.5 8223.7 8319.8 7535.0 7540.5 7546.6 7669.2 7691.5 7262.5 7339.5 7373.9 7439.4 7476.0 7006.3 7016.1 7021.2 7045.8 7091.9 4949.2 4986.5 5013.3 5043.0 5056.4 4252.9 4305.4 4969.6 5088.7 5118.3 05:22:09 INFO - PROCESS | 1676 | 05:22:09 INFO - PROCESS | 1676 | [#4] prototype.html Cycles:180 Average:5108.83 Median:5872.50 stddev:1635.65 (27.9%) stddev-sans-first:1599.46 05:22:09 INFO - PROCESS | 1676 | Values: 272.8 274.3 317.8 323.1 333.0 5272.8 6045.4 6096.3 6116.7 6143.9 5410.4 5909.8 6030.3 6057.9 6111.2 4312.1 4416.2 4508.7 4537.3 4543.9 4465.2 4475.4 4534.5 4536.9 4569.6 3781.0 4090.4 4255.4 4526.8 4567.1 4396.8 4498.6 4535.0 4537.8 4545.0 3877.0 3889.6 3892.4 3904.6 3927.3 4295.2 5454.4 5700.9 5754.6 5785.4 4814.7 5102.3 5134.5 5160.7 5266.5 5625.7 6180.8 6222.3 6375.5 6434.0 3440.0 3939.8 4105.1 4201.7 4245.6 5734.2 6340.7 6737.9 6779.9 6799.7 4276.5 4280.7 4289.3 4294.6 4420.9 3819.3 3839.5 4193.2 4354.6 4364.2 4116.4 4478.3 5040.4 5116.2 5151.2 5671.2 6436.4 6457.7 6463.3 6468.9 1149.4 1241.7 1330.6 1472.1 1488.8 900.6 919.0 951.4 985.5 986.6 5345.8 5849.3 5909.4 5935.5 5950.9 5742.3 6576.2 6580.9 6615.8 6631.6 5496.3 5667.1 5866.2 5955.0 6005.7 2774.7 3033.0 3040.9 3052.0 3456.6 6522.7 6573.2 6857.5 6887.6 6908.0 5931.6 6685.5 6763.8 6807.8 6829.8 5944.6 6555.3 6613.7 6810.4 6889.7 6502.2 6559.1 6569.4 6580.0 6590.9 5229.6 6219.6 6481.9 6483.4 6523.5 5960.5 6071.9 6491.0 6716.0 6881.0 4067.7 4225.0 4260.7 4282.0 4302.2 5067.5 6129.7 6136.2 6316.5 6480.3 6292.4 6299.2 6300.9 6316.3 6354.7 5694.5 5878.8 6097.3 6283.5 6288.4 5994.4 6099.8 6191.3 6191.9 6279.8 5141.6 6453.4 6473.3 6475.7 6512.8 5306.3 6005.3 6334.0 6347.8 6352.0 05:22:09 INFO - PROCESS | 1676 | 05:22:09 INFO - PROCESS | 1676 | [#5] yui.html Cycles:180 Average:18394.46 Median:31329.47 stddev:26683.35 (85.2%) stddev-sans-first:26724.96 05:22:09 INFO - PROCESS | 1676 | Values: 656.3 762.3 763.7 768.0 768.4 14707.1 14937.8 15254.5 15270.5 15425.8 14248.9 14668.8 14728.4 14740.5 14771.5 742.7 925.0 930.8 936.8 937.9 905.5 924.2 944.7 947.3 951.0 841.0 982.6 990.0 990.7 996.3 948.3 962.5 976.1 977.0 981.0 719.9 734.7 736.6 741.4 743.5 14615.6 14649.9 14803.8 15145.5 15149.2 12951.7 13004.9 13061.0 13097.9 13211.5 10147.5 10249.8 10450.1 10561.9 10595.0 3463.3 3483.3 3527.0 3535.0 3625.0 62043.7 82954.0 86316.0 89967.1 91226.5 7486.0 9674.0 9747.2 10056.5 10063.9 20658.6 21442.7 21634.9 21680.9 21752.0 6553.4 7538.5 7639.4 7854.7 7976.5 4109.8 4147.3 4156.9 4163.0 4164.9 3530.6 3562.7 3574.7 3631.3 3873.8 695.5 712.3 714.1 714.9 715.8 527.6 567.8 572.9 615.2 621.2 6906.2 6958.5 7898.4 7961.3 8366.4 571.4 594.6 596.4 603.9 614.8 3260.7 3897.8 3908.4 3940.2 3951.9 77914.6 96682.5 98310.0 98886.5 99788.5 83540.0 83540.6 83737.0 85944.0 88171.0 74141.6 74488.0 74769.6 75098.9 75352.6 6506.7 7285.3 7705.7 7721.1 7743.5 31052.7 44659.0 44788.8 45376.3 46306.0 67515.0 67993.0 68703.3 68717.7 69289.7 10108.8 10120.4 10261.8 10336.5 10515.1 10994.9 11044.3 11135.2 11400.3 11419.9 10353.8 10427.6 10442.0 10563.3 11190.8 10526.6 10619.2 10861.0 10873.8 11265.9 10068.9 10110.9 10377.2 10498.8 10601.0 8727.9 8781.9 8813.5 9117.4 9213.8 8446.9 8957.9 8965.7 9030.8 9162.4 05:22:09 INFO - PROCESS | 1676 | -------- Summary: end -------- 05:22:09 INFO - PROCESS | 1676 | 05:22:10 INFO - TEST-INFO | 1676: exit 0 05:22:10 INFO - TEST-OK | dromaeo_css | took 1111745ms 05:22:10 INFO - TEST-START | kraken 05:22:10 INFO - operating with platform_type : mac_ 05:22:10 INFO - Initialising browser for kraken test... 05:22:10 INFO - TEST-INFO | started process 1684 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/tmpZdzmrI/profile http://localhost:49277/getInfo.html) 05:22:12 INFO - PROCESS | 1684 | JavaScript error: jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/omni.ja!/components/Weave.js, line 13: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIXPCComponents_Utils.import] 05:22:12 INFO - PROCESS | 1684 | __metrics Screen width/height:1600/1200 05:22:12 INFO - PROCESS | 1684 | colorDepth:24 05:22:12 INFO - PROCESS | 1684 | Browser inner width/height: 1024/689 05:22:12 INFO - PROCESS | 1684 | __metrics 05:22:12 ERROR - PROCESS | 1684 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 05:22:13 INFO - TEST-INFO | 1684: exit 0 05:22:13 INFO - Browser initialized. 05:22:13 INFO - Running cycle 1/1 for kraken test... 05:22:13 INFO - TEST-INFO | started process 1687 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/tmpZdzmrI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:22:25 INFO - PROCESS | 1687 | RSS: Main: 247717888 05:22:25 INFO - PROCESS | 1687 | 05:22:28 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 05:22:28 INFO - PROCESS | 1687 | RSS: Main: 260112384 05:22:28 INFO - PROCESS | 1687 | 05:22:31 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 05:22:32 INFO - PROCESS | 1687 | RSS: Main: 269393920 05:22:32 INFO - PROCESS | 1687 | 05:22:35 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 05:22:36 INFO - PROCESS | 1687 | RSS: Main: 272232448 05:22:36 INFO - PROCESS | 1687 | 05:22:38 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 05:22:39 INFO - PROCESS | 1687 | RSS: Main: 274669568 05:22:39 INFO - PROCESS | 1687 | 05:22:41 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:22:41 INFO - PROCESS | 1687 | RSS: Main: 275312640 05:22:41 INFO - PROCESS | 1687 | 05:22:44 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 05:22:45 INFO - PROCESS | 1687 | RSS: Main: 275787776 05:22:45 INFO - PROCESS | 1687 | 05:22:48 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 05:22:48 INFO - PROCESS | 1687 | RSS: Main: 275947520 05:22:48 INFO - PROCESS | 1687 | 05:22:51 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 05:22:52 INFO - PROCESS | 1687 | RSS: Main: 276037632 05:22:52 INFO - PROCESS | 1687 | 05:22:53 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:22:54 INFO - PROCESS | 1687 | RSS: Main: 274845696 05:22:54 INFO - PROCESS | 1687 | 05:22:56 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:22:57 INFO - PROCESS | 1687 | RSS: Main: 274984960 05:22:57 INFO - PROCESS | 1687 | 05:22:59 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:23:00 INFO - PROCESS | 1687 | RSS: Main: 275324928 05:23:00 INFO - PROCESS | 1687 | 05:23:02 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:23:03 INFO - PROCESS | 1687 | RSS: Main: 275386368 05:23:03 INFO - PROCESS | 1687 | 05:23:06 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:23:07 INFO - PROCESS | 1687 | RSS: Main: 275374080 05:23:07 INFO - PROCESS | 1687 | 05:23:09 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 05:23:09 INFO - PROCESS | 1687 | RSS: Main: 277078016 05:23:09 INFO - PROCESS | 1687 | 05:23:09 INFO - PROCESS | 1687 | __start_tp_report 05:23:09 INFO - PROCESS | 1687 | _x_x_mozilla_page_load 05:23:09 INFO - PROCESS | 1687 | _x_x_mozilla_page_load_details 05:23:09 INFO - PROCESS | 1687 | |i|pagename|runs| 05:23:09 INFO - PROCESS | 1687 | |0;ai-astar;116;109;117;116;115;127;115;116;117;117 05:23:09 INFO - PROCESS | 1687 | |1;audio-beat-detection;184;168;156;154;142;169;177;122;169;118 05:23:09 INFO - PROCESS | 1687 | |2;audio-dft;205;187;225;258;201;273;191;274;211;198 05:23:09 INFO - PROCESS | 1687 | |3;audio-fft;67;83;73;126;51;63;61;63;77;56 05:23:09 INFO - PROCESS | 1687 | |4;audio-oscillator;79;81;126;97;120;69;142;114;120;152 05:23:09 INFO - PROCESS | 1687 | |5;imaging-gaussian-blur;89;88;89;86;89;86;90;75;136;131 05:23:09 INFO - PROCESS | 1687 | |6;imaging-darkroom;95;95;95;95;95;95;96;94;95;94 05:23:09 INFO - PROCESS | 1687 | |7;imaging-desaturate;91;92;116;150;92;89;90;92;117;89 05:23:09 INFO - PROCESS | 1687 | |8;json-parse-financial;61;62;62;62;61;63;61;80;65;63 05:23:09 INFO - PROCESS | 1687 | |9;json-stringify-tinderbox;64;66;69;65;66;71;71;65;79;83 05:23:09 INFO - PROCESS | 1687 | |10;stanford-crypto-aes;92;108;110;107;86;82;86;81;83;84 05:23:09 INFO - PROCESS | 1687 | |11;stanford-crypto-ccm;141;150;190;167;158;173;154;160;178;147 05:23:09 INFO - PROCESS | 1687 | |12;stanford-crypto-pbkdf2;169;174;172;177;195;230;183;169;176;193 05:23:09 INFO - PROCESS | 1687 | |13;stanford-crypto-sha256-iterative;61;84;68;70;69;67;68;67;85;97 05:23:09 INFO - PROCESS | 1687 | __end_tp_report 05:23:09 INFO - PROCESS | 1687 | __start_cc_report 05:23:09 INFO - PROCESS | 1687 | _x_x_mozilla_cycle_collect,1040 05:23:09 INFO - PROCESS | 1687 | __end_cc_report 05:23:09 INFO - PROCESS | 1687 | __startTimestamp1460809389263__endTimestamp 05:23:09 INFO - PROCESS | 1687 | 05:23:09 INFO - PROCESS | 1687 | ------- Summary: start ------- 05:23:09 INFO - PROCESS | 1687 | Number of tests: 14 05:23:09 INFO - PROCESS | 1687 | 05:23:09 INFO - PROCESS | 1687 | [#0] ai-astar Cycles:10 Average:116.50 Median:116.50 stddev:4.38 (3.8%) stddev-sans-first:4.64 05:23:09 INFO - PROCESS | 1687 | Values: 116.0 109.0 117.0 116.0 115.0 127.0 115.0 116.0 117.0 117.0 05:23:09 INFO - PROCESS | 1687 | 05:23:09 INFO - PROCESS | 1687 | [#1] audio-beat-detection Cycles:10 Average:155.90 Median:168.50 stddev:22.38 (13.3%) stddev-sans-first:21.30 05:23:09 INFO - PROCESS | 1687 | Values: 184.0 168.0 156.0 154.0 142.0 169.0 177.0 122.0 169.0 118.0 05:23:09 INFO - PROCESS | 1687 | 05:23:09 INFO - PROCESS | 1687 | [#2] audio-dft Cycles:10 Average:222.30 Median:218.00 stddev:33.70 (15.5%) stddev-sans-first:35.16 05:23:09 INFO - PROCESS | 1687 | Values: 205.0 187.0 225.0 258.0 201.0 273.0 191.0 274.0 211.0 198.0 05:23:09 INFO - PROCESS | 1687 | 05:23:09 INFO - PROCESS | 1687 | [#3] audio-fft Cycles:10 Average:72.00 Median:65.00 stddev:21.26 (32.7%) stddev-sans-first:22.47 05:23:09 INFO - PROCESS | 1687 | Values: 67.0 83.0 73.0 126.0 51.0 63.0 61.0 63.0 77.0 56.0 05:23:09 INFO - PROCESS | 1687 | 05:23:09 INFO - PROCESS | 1687 | [#4] audio-oscillator Cycles:10 Average:110.00 Median:110.50 stddev:27.71 (25.1%) stddev-sans-first:27.03 05:23:09 INFO - PROCESS | 1687 | Values: 79.0 81.0 126.0 97.0 120.0 69.0 142.0 114.0 120.0 152.0 05:23:09 INFO - PROCESS | 1687 | 05:23:09 INFO - PROCESS | 1687 | [#5] imaging-gaussian-blur Cycles:10 Average:95.90 Median:88.50 stddev:20.31 (23.0%) stddev-sans-first:21.39 05:23:09 INFO - PROCESS | 1687 | Values: 89.0 88.0 89.0 86.0 89.0 86.0 90.0 75.0 136.0 131.0 05:23:09 INFO - PROCESS | 1687 | 05:23:09 INFO - PROCESS | 1687 | [#6] imaging-darkroom Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 05:23:09 INFO - PROCESS | 1687 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 96.0 94.0 95.0 94.0 05:23:09 INFO - PROCESS | 1687 | 05:23:09 INFO - PROCESS | 1687 | [#7] imaging-desaturate Cycles:10 Average:101.80 Median:90.50 stddev:20.08 (22.2%) stddev-sans-first:20.91 05:23:09 INFO - PROCESS | 1687 | Values: 91.0 92.0 116.0 150.0 92.0 89.0 90.0 92.0 117.0 89.0 05:23:09 INFO - PROCESS | 1687 | 05:23:09 INFO - PROCESS | 1687 | [#8] json-parse-financial Cycles:10 Average:64.00 Median:62.50 stddev:5.75 (9.2%) stddev-sans-first:6.00 05:23:09 INFO - PROCESS | 1687 | Values: 61.0 62.0 62.0 62.0 61.0 63.0 61.0 80.0 65.0 63.0 05:23:09 INFO - PROCESS | 1687 | 05:23:09 INFO - PROCESS | 1687 | [#9] json-stringify-tinderbox Cycles:10 Average:69.90 Median:70.00 stddev:6.42 (9.2%) stddev-sans-first:6.44 05:23:09 INFO - PROCESS | 1687 | Values: 64.0 66.0 69.0 65.0 66.0 71.0 71.0 65.0 79.0 83.0 05:23:09 INFO - PROCESS | 1687 | 05:23:09 INFO - PROCESS | 1687 | [#10] stanford-crypto-aes Cycles:10 Average:91.90 Median:83.50 stddev:11.75 (14.1%) stddev-sans-first:12.46 05:23:09 INFO - PROCESS | 1687 | Values: 92.0 108.0 110.0 107.0 86.0 82.0 86.0 81.0 83.0 84.0 05:23:09 INFO - PROCESS | 1687 | 05:23:09 INFO - PROCESS | 1687 | [#11] stanford-crypto-ccm Cycles:10 Average:161.80 Median:163.50 stddev:15.20 (9.3%) stddev-sans-first:14.14 05:23:09 INFO - PROCESS | 1687 | Values: 141.0 150.0 190.0 167.0 158.0 173.0 154.0 160.0 178.0 147.0 05:23:09 INFO - PROCESS | 1687 | 05:23:09 INFO - PROCESS | 1687 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.80 Median:180.00 stddev:18.64 (10.4%) stddev-sans-first:18.98 05:23:09 INFO - PROCESS | 1687 | Values: 169.0 174.0 172.0 177.0 195.0 230.0 183.0 169.0 176.0 193.0 05:23:09 INFO - PROCESS | 1687 | 05:23:09 INFO - PROCESS | 1687 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.60 Median:69.50 stddev:11.20 (16.1%) stddev-sans-first:10.91 05:23:09 INFO - PROCESS | 1687 | Values: 61.0 84.0 68.0 70.0 69.0 67.0 68.0 67.0 85.0 97.0 05:23:09 INFO - PROCESS | 1687 | -------- Summary: end -------- 05:23:09 INFO - PROCESS | 1687 | 05:23:09 INFO - TEST-INFO | 1687: exit 0 05:23:10 INFO - TEST-OK | kraken | took 59946ms 05:23:10 INFO - SUITE-END | took 1171s 05:23:10 INFO - Completed test suite (00:19:32) 05:23:10 INFO - javascript benchmark 05:23:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3061.6172978377704, 3400.0989737719524, 3432.9999999999995, 3466.4453687410005, 3507.158282012317, 4683.7822166236765, 5526.806561770329, 5629.071203251464, 5640.915386269209, 5654.660720356782, 5598.104303311471, 5678.687672178028, 5693.77224911003, 5724.398938111512, 5749.655020698761, 5137.357830271216, 5180.093483640367, 5220.895582088356, 5264.447233040533, 5278.9208161877605, 5103.3875934887865, 5127.077126117316, 5195.896082078356, 5211.009908117446, 5324.307839980816, 5069.315642388267, 5089.567386772092, 5121.0270048691, 5150.819721309755, 5165.070287348276, 5114.181730923035, 5116.189020001094, 5125.692458452505, 5127.7948882044675, 5161.767720452551, 5075.213341932006, 5076.898462030776, 5081.441041485434, 5135.460776618476, 5195.168772996302, 4445.666300109954, 5086.287919691246, 5528.585356098309, 5528.917066244009, 5555.083411237141, 6068.878622427526, 6071.33215346316, 6132.938670613281, 6150.077488376734, 6157.692115394212, 4975.676581022222, 5073.518015788531, 6140.811014692577, 6184.845378865564, 6187.443130118266, 5611.905678392756, 5741.712914354265, 5779.075347944308, 5800.071988481823, 5801.738921748573, 5368.731563421813, 6245.406686364788, 6248.687565621701, 6256.499480041586, 6260.342664020303, 5902.0, 6078.54410919174, 6137.4995631051315, 6139.30815411195, 6139.837983438532, 6172.69136543171, 6185.165002724574, 6221.160143380676, 6230.00319948806, 6251.9687401563615, 6010.188624535632, 6165.290991535941, 6184.226971628546, 6188.81433556994, 6197.5351848611535, 6045.063015232646, 6114.78598249068, 6122.112293717475, 6151.96924015377, 6198.876022479525, 4759.785809638574, 4819.903601927942, 4831.347768051339, 4831.806727730851, 4846.903061938741, 4287.335463003239, 4340.544242854518, 4345.67407444443, 4356.4336636237085, 4383.254846676073, 5565.916511252334, 5638.85902852432, 5638.85902852432, 5652.067408877571, 5667.914981275284, 6049.334573196945, 6165.136880836686, 6200.751969921243, 6226.159468471789, 6235.064740288903, 5499.120140777456, 5595.916061259084, 5622.690752008597, 5624.015797235467, 5625.662460252316, 5282.6302158849285, 5294.708791016454, 5358.651687640291, 5376.327959005124, 5403.13549832025, 6295.276043254993, 6340.270868850049, 6350.809475715736, 6352.428281454783, 6365.649889256136, 6102.0, 6163.3528479509005, 6235.906461402992, 6250.562460627712, 6262.937370626418, 6211.09939058843, 6299.118123462627, 6300.086487459201, 6311.558190926591, 6323.177986861679, 6069.939300607114, 6120.816375508742, 6136.048912418582, 6143.416375444325, 6149.631022138686, 6101.969490152698, 6117.84705382369, 6123.343628574205, 6138.3861613840045, 6209.627422354673, 5890.410958904075, 6023.427774361428, 6041.546883983631, 6042.788502402394, 6057.636541807506, 6015.0, 6022.66875321844, 6031.0, 6049.274087109575, 6055.2430946131735, 4949.455559888482, 5683.232763576864, 5722.713864306851, 5816.680082595498, 5849.151872978479, 5825.6795876225515, 5985.40145985398, 6094.856286623056, 6109.481429920806, 6120.020796672689, 4930.630202734881, 5897.0, 5953.255843019622, 5983.640981541122, 5996.790112346047], "value": 5599.9998290088115, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [516.8346129238644, 557.4731878374935, 562.0501352713914, 590.9025010873206, 608.6348191085349, 29906.551401728964, 34883.0, 38404.0, 38561.0, 38823.0, 39379.606203937954, 39935.0, 40085.398719019235, 40103.99999999993, 40105.19789604206, 7954.96022519888, 9540.427574345527, 9631.27765417593, 9633.08485693858, 9641.61433542661, 9527.23782097434, 9629.229661627052, 9665.565049572786, 9726.17327527161, 9743.464109473976, 9552.331336806426, 9631.855522167172, 9678.032196780301, 9759.316847820655, 9798.314118011742, 9589.712308630753, 9601.375910565861, 9626.56680449378, 9738.367006144614, 9759.219262458986, 8507.957460212721, 9593.280503962162, 9601.519924003842, 9727.854082188773, 9734.172595329406, 31269.0, 31481.527777083364, 31628.683713162805, 31706.682933170603, 31725.84137079323, 2493.962590561143, 2710.227585138226, 3039.3465404937824, 3583.0, 3927.8625248116464, 26381.472370552674, 27112.915483380642, 27777.86111069452, 27820.026299079436, 27827.0, 2640.1419538650016, 2656.8007399444928, 2761.737634924679, 2773.417582307718, 2778.847163406032, 370503.58992819994, 371152.0, 373810.0, 375232.99999999726, 375447.0, 35472.64527354745, 36469.08827279339, 58947.70526147342, 59469.107963380586, 60003.0, 34760.0, 35797.1050723734, 35952.28095438077, 35982.0, 35996.0, 14917.179555124356, 15089.47186848477, 15089.547313580391, 15126.924365378103, 15145.0, 12363.814542781865, 12669.683257918627, 12787.872121278855, 12788.296643684687, 12827.0, 8090.676372945016, 8164.346852251806, 8187.1812818718845, 8204.097549269576, 8227.588620568948, 3430.5368775215525, 3441.1397150712323, 3449.4308439107767, 3481.3385456763135, 3487.598926123528, 956.2780101023822, 1002.7242508310273, 1019.6482213636284, 1054.4516851237468, 1063.9680809575725, 27268.72731272662, 27533.898644054414, 27895.72104278972, 28207.71792282051, 28303.009394671088, 992.7468495533593, 1109.8346735927244, 1145.8911403416662, 1180.451090243041, 1274.0635632809876, 14632.0, 14663.853361466463, 14738.0, 14772.0, 14897.0, 633038.0, 633497.0, 636390.0, 645911.0, 646150.0, 59504.70247648734, 60630.0, 60645.69677151586, 60729.0, 60901.69549152315, 1450.3835869755312, 1487.2266421460997, 1499.5576304990054, 1501.8573235542606, 1502.2413681091198, 27703.58444623292, 27776.305592360353, 28397.716022839508, 28410.289742756595, 28432.715672843835, 49195.0, 49722.0, 50289.49710502848, 50433.0, 50732.492675072775, 552760.0, 553393.0, 554208.0, 559426.0, 561898.0, 22084.33746987593, 25961.221163365128, 26769.0, 27026.054088106823, 27172.32069198286, 45957.77021114873, 46518.534814652776, 46876.29685554651, 47018.529814702786, 47164.0, 38230.808845955595, 38284.46862125484, 38377.40785134775, 38524.8442546724, 39788.602113978486, 27692.307692307048, 31421.371572568878, 37103.62896371002, 37906.81046594749, 38109.42835857409, 30559.388812224075, 30726.0, 38002.619973799905, 38207.0, 39778.60221397749, 30992.69007309898, 32677.673223267462, 32688.182795430304, 32978.17554561155, 32986.67013329836, 24023.75976240215, 24521.141760038314, 24634.75365246396, 24840.627390588794, 24855.87572062128], "value": 18045.975910398003, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.7889739540647, 393.1252962159196, 399.4647172788463, 400.25951988820674, 406.05997116674894, 14673.0, 19933.810590305555, 20496.180152793906, 20583.794162058395, 20671.483212919684, 20381.184752609915, 20722.0, 20797.96010199488, 21091.894540527315, 21164.576708465822, 5669.886602267952, 7279.963600182018, 7783.805404864867, 7786.182450842665, 7801.843963120734, 7309.524880882752, 7466.66400011998, 7474.32731054205, 7621.885671714928, 7700.730474433396, 7240.637968101574, 7510.324070833624, 7525.2850979157165, 7571.621418929031, 7575.621218939059, 7473.663685134182, 7508.662110205027, 7510.136334321568, 7601.0878694557005, 7617.895405166248, 6552.8361790955605, 7012.158540975065, 7021.262767409399, 7062.5056246062795, 7422.925770742278, 19925.10337034837, 26591.0, 26654.46691066187, 28504.0, 28785.85607071972, 2015.3349394699715, 2248.280550223929, 2281.555096756133, 2458.385403649088, 2926.7853840656103, 15068.0, 16214.513564592962, 16261.430849920314, 16376.181190940406, 16468.176591170515, 1869.4204796513125, 1939.398786376228, 1963.2637760839684, 1975.8320542753884, 2103.316422162803, 119821.0, 150247.0, 150788.0, 151945.0, 154359.4230432711, 20343.4914127148, 21569.67645485319, 21621.0, 22025.559488810453, 22178.001989910492, 35641.64358356435, 36190.8190459046, 36554.903352899455, 36562.63437365645, 36593.26813463716, 10630.73423164427, 10972.835407468894, 11118.388488633203, 11214.327140371604, 11343.602973895873, 8236.588170591567, 8959.776005599912, 9105.180533751862, 9222.170004699608, 9230.123138301851, 7737.711472624224, 9846.556904939294, 9874.210063194927, 9882.91287958324, 9949.0, 3835.386338185877, 3958.44581758554, 4007.3751692641367, 4052.918941621151, 4067.51189857219, 907.6505545365083, 932.3287233192091, 1008.4201584089178, 1035.2028937717914, 1035.637526865606, 19586.335161511146, 19939.10274037672, 19949.902755348594, 20154.0, 20424.0, 743.5204293230836, 846.2595229174473, 926.1988380051207, 957.6073809737974, 966.4974213408988, 10291.53688084038, 11271.38007409584, 11279.887201128047, 11330.60342887995, 11332.716682083013, 560656.7868642686, 619156.0, 622034.0, 622617.0, 640315.0, 128105.0, 134578.0, 137520.0, 150849.49150508575, 152290.23854880655, 861.5003298087095, 1034.6497820344373, 1055.0504545908743, 1057.413135709673, 1063.8510608514814, 17802.91098544551, 18122.0, 18172.636547268718, 18201.726974095665, 18216.81783182151, 51844.40777961011, 52280.47719522908, 52658.0, 53915.730421347645, 54907.176392354944, 68693.62612747618, 70384.64807675929, 72518.0, 73702.63148684222, 75398.24601753912, 7805.687772489036, 7870.9606451967375, 7880.684772609261, 7956.2043795619975, 8233.094359620556, 10150.898491014996, 10953.233273670767, 12016.879831201926, 12067.0, 12191.390430478617, 10383.117435018108, 10406.947965260126, 10471.371717696962, 10561.679790026246, 10725.517351719036, 10376.429296388771, 10495.422751748727, 10639.042486176282, 10702.464876756287, 10724.195685323788, 10596.947015264874, 10793.946030269799, 10962.068224201032, 10982.670519884416, 10997.61508347204, 5929.466348028698, 7319.451041171827, 7426.443016773872, 7809.570473624005, 8238.464499807493, 10494.632687855888, 10743.355398676105, 11002.834957475485, 11023.06303964172, 11029.944850275697], "value": 10991.992534168463, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1575.2123938030984, 1585.02520949616, 1851.4723303858402, 1951.4535929939623, 1951.912163952622, 8013.799655008613, 8364.749057528275, 8603.698870539532, 8642.481451112937, 8660.350473714472, 8471.618777155027, 8502.659893604248, 8557.871631925525, 8569.27161191298, 8581.613827377767, 1848.833604975552, 2447.21689059501, 2469.5678256305073, 2469.753024697534, 2481.9203646413775, 1983.6925276582153, 2458.2379462366634, 2482.416632091457, 2483.416397146669, 2491.638712386703, 2455.7544245575477, 2458.6189140683223, 2471.085698291635, 2472.7389031593925, 2499.0, 2048.8770673759623, 2472.7279999200073, 2482.726900040994, 2488.0669748844184, 2671.3722275265295, 2342.953140937189, 2436.658867758515, 2463.8275320727557, 2469.95060098797, 2474.542209691213, 8190.689489681682, 8830.381873268874, 8847.07105753893, 8893.288536917096, 8969.461832290084, 4885.755712214411, 5301.496357845998, 5402.51377376038, 5426.21319908615, 5426.9185962210595, 3534.1341371363924, 3602.6310002198998, 3613.9819300903328, 3643.1074386775144, 3657.9268414631824, 1030.6753372687579, 1048.3028785857396, 1049.4700176410863, 1063.984040239397, 1087.0868470484832, 11619.128565357632, 11739.823902641467, 11759.823602645967, 11790.705232369173, 11801.0, 4076.7961601919787, 4335.3713711511655, 4528.547145285445, 4693.95306046942, 4898.24077268024, 9404.95297523508, 9769.413835169913, 9891.901080989242, 9953.104220620178, 9955.303128780915, 5343.118385466432, 5357.8392648220615, 5364.597655175878, 5419.729013549307, 5425.457454254543, 4441.777911104432, 4826.61387089032, 4831.903361932742, 4836.613070954315, 4948.4061912570005, 4056.6957478189256, 4059.8579049733116, 4064.878053658395, 4102.084051830355, 4113.794310284474, 1331.9200847949155, 1352.891768658505, 1358.5788405594296, 1362.3188405797102, 1372.2589801507274, 808.4946908182386, 867.4925168776336, 894.9260886935704, 945.9668911588061, 966.6906589891167, 4512.435945506812, 5967.880642387215, 6057.242844644419, 6080.361562036011, 6188.566800324024, 454.28223407016833, 463.30041637127505, 472.78488287829646, 473.5974421741492, 474.5468077985515, 3935.1145992151537, 4047.473828402348, 4077.9796101019306, 4088.7751173685733, 4119.670426365884, 10288.691339259834, 10557.841632375521, 10736.463176841127, 10796.244262901522, 10802.351858888493, 10690.78618427627, 10742.785144297226, 10745.731356715989, 10772.084372828398, 10778.568857245778, 7721.227877212234, 8041.396895232764, 8042.5174489529645, 8140.0638926523025, 8208.09710931797, 5569.554435645057, 5886.499647529837, 5980.252468441445, 6009.849753756016, 6081.817545473643, 9192.49441280736, 9563.952180239054, 9565.61737530491, 9572.282078844253, 9626.133647971716, 9225.907740922505, 9452.054794520493, 9674.9032509674, 9744.902550974399, 9838.212942964403, 7992.840143196987, 8193.139720329518, 8272.545010024507, 8310.459820111673, 8311.418200725891, 8175.67297308101, 8190.836183276182, 8202.507849529047, 8223.712170074019, 8319.750407487785, 7535.0, 7540.472166948481, 7546.584937828472, 7669.233076692286, 7691.50005249657, 7262.455315851226, 7339.522931009467, 7373.889391659022, 7439.367653749276, 7476.0, 7006.334398232159, 7016.052832867497, 7021.1574611047, 7045.823854403681, 7091.936209568606, 4949.183384741621, 4986.501349864984, 5013.348264725562, 5042.974785126051, 5056.418511871182, 4252.87241382759, 4305.354196870495, 4969.627277954096, 5088.7201203932655, 5118.283440318432], "value": 4531.268562265557, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [272.8403883728019, 274.3032696949748, 317.76485400803404, 323.13400087764796, 332.9550510681057, 5272.841814745564, 6045.365236650149, 6096.26844778626, 6116.6756632479655, 6143.877122457554, 5410.377806552247, 5909.793157239497, 6030.276366835975, 6057.909576276268, 6111.205543279368, 4312.094460163369, 4416.160929423415, 4508.706934049293, 4537.319402089696, 4543.886402839922, 4465.226883283636, 4475.373447717322, 4534.501204867478, 4536.9092618147615, 4569.634429245652, 3780.9810950945207, 4090.365993271048, 4255.361695745632, 4526.79629416677, 4567.109413664337, 4396.802143903516, 4498.617617502517, 4535.024969631545, 4537.795799189028, 4544.954550454519, 3876.9612303877166, 3889.5527014393424, 3892.377219644843, 3904.6333783175946, 3927.2734544109144, 4295.174550815891, 5454.400015998237, 5700.885982280372, 5754.597178197528, 5785.421457854223, 4814.6870453420415, 5102.311187989648, 5134.486551344873, 5160.7419629018395, 5266.499682530153, 5625.6905870177125, 6180.783672571483, 6222.3466536013975, 6375.45808606269, 6433.967830160819, 3440.0, 3939.8227079781477, 4105.055837157471, 4201.747895126272, 4245.64139475368, 5734.197212390227, 6340.651264180468, 6737.898931516032, 6779.898231548236, 6799.7280108795585, 4276.48682158143, 4280.743155410685, 4289.313709806416, 4294.57054294568, 4420.8673739787855, 3819.3125237457525, 3839.500864887603, 4193.161367726467, 4354.629856462173, 4364.192624364503, 4116.447802362554, 4478.328250762346, 5040.39515258164, 5116.155834287375, 5151.22731590257, 5671.234383358276, 6436.397975447578, 6457.741690332334, 6463.289038205794, 6468.932626116638, 1149.3735913926762, 1241.7206128620987, 1330.6207730796677, 1472.0578829549097, 1488.8362280149174, 900.5767289373983, 919.0212423768666, 951.4481600671594, 985.4579981010415, 986.6152200641756, 5345.839624811262, 5849.268841394825, 5909.3795151508475, 5935.495482884004, 5950.940490595125, 5742.310922689304, 6576.210854697467, 6580.901286480706, 6615.801525954229, 6631.635260060743, 5496.258005169251, 5667.064934285879, 5866.178734977107, 5955.0, 6005.729742161613, 2774.680911695181, 3032.9545056824163, 3040.939181216363, 3051.9847400763297, 3456.6197718250974, 6522.673866306665, 6573.211214654177, 6857.5199736018, 6887.552309099892, 6908.0, 5931.584789064724, 6685.532012759158, 6763.830904227335, 6807.761728339485, 6829.795106146824, 5944.554158438134, 6555.344465553407, 6613.6693165340575, 6810.387065164159, 6889.655517224119, 6502.187226596676, 6559.0817285581015, 6569.441597464269, 6580.0, 6590.934090659032, 5229.581633469237, 6219.564630475823, 6481.935180648322, 6483.384078512534, 6523.510736694672, 5960.496591431523, 6071.907056729831, 6491.026346048131, 6715.966420167868, 6881.0, 4067.6949228807366, 4224.978875105605, 4260.744355338689, 4281.957180428156, 4302.247106756367, 5067.543921047123, 6129.662868542095, 6136.23297087864, 6316.463100636497, 6480.319566445455, 6292.433680968735, 6299.212598425197, 6300.936990630035, 6316.336784637547, 6354.6504942226775, 5694.544436444991, 5878.823635290948, 6097.2683278006925, 6283.528735344776, 6288.434040936338, 5994.370591088047, 6099.756009759737, 6191.288001879841, 6191.907121393093, 6279.797303375402, 5141.588672906084, 6453.386928241809, 6473.255575608865, 6475.676216189266, 6512.772052978123, 5306.28365170697, 6005.2676032406025, 6334.0, 6347.777827776005, 6351.96824015877], "value": 4563.886538934897, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [656.3108735827382, 762.2948772385546, 763.6716212028832, 767.9769606911794, 768.3660979691754, 14707.117572945617, 14937.804975601926, 15254.466093686724, 15270.465533706323, 15425.768613470807, 14248.928755356208, 14668.826493880491, 14728.410863565472, 14740.484083057096, 14771.482998095069, 742.6954948471127, 925.0240995749498, 930.7673081729567, 936.7517607833895, 937.9108984646448, 905.5245995852165, 924.2421214604027, 944.7118628818242, 947.2706016367393, 950.9524523773819, 840.9579521023954, 982.6020461712982, 989.9950500247488, 990.7374545745383, 996.2577879479798, 948.2698322291866, 962.4658314635375, 976.0532283684814, 977.0229770229806, 980.9509524523745, 719.8740220461399, 734.6767422334156, 736.6427282767853, 741.3735393592406, 743.4795643049887, 14615.63460913475, 14649.926750366287, 14803.777943330859, 15145.545633631009, 15149.166795826226, 12951.740965180737, 13004.869951300556, 13061.0, 13097.86902130976, 13211.537596184184, 10147.492625368775, 10249.84625230622, 10450.111740502067, 10561.947190264, 10595.046445819837, 3463.307338532278, 3483.303339332144, 3526.959546933661, 3535.0, 3624.981875090634, 62043.68978155126, 82954.0, 86316.0, 89967.10032899653, 91226.5438672809, 7485.962570187223, 9673.951630241945, 9747.220222382193, 10056.547455364525, 10063.899361006443, 20658.58682826335, 21442.678359824615, 21634.91825408723, 21680.89159554192, 21752.02115904788, 6553.377429144223, 7538.509996850187, 7639.350655194259, 7854.6858125674335, 7976.521408715495, 4109.815058322382, 4147.294959856831, 4156.91686166281, 4162.958370416257, 4164.895877603084, 3530.593981692087, 3562.6615471530163, 3574.6782789549065, 3631.291898079902, 3873.8063096845044, 695.45754311636, 712.3410844968383, 714.1002337055244, 714.9428045756327, 715.7673756029311, 527.5753018819887, 567.7643777832236, 572.9026065568863, 615.2432508015166, 621.1893479009846, 6906.205786334535, 6958.512904096665, 7898.44710870245, 7961.323287520626, 8366.414350995372, 571.4200086911787, 594.5927039977629, 596.3946594206883, 603.9009003613469, 614.757170917494, 3260.7065364117343, 3897.8051097445014, 3908.413737939275, 3940.15286713358, 3951.9012024699614, 77914.6104269475, 96682.51658741802, 98310.0, 98886.50556747314, 99788.50105749424, 83540.0, 83540.58229708813, 83737.0, 85944.0, 88171.0, 74141.6292918532, 74488.0, 74769.6261518689, 75098.87351689729, 75352.62323688346, 6506.739730410731, 7285.307895749896, 7705.691772329044, 7721.073471183494, 7743.5353878767455, 31052.68947310498, 44659.0, 44788.77605611951, 45376.31935521036, 46306.0, 67515.0, 67993.0, 68703.3129668697, 68717.65641171762, 69289.65355173191, 10108.832354601383, 10120.443375614117, 10261.794764104825, 10336.483175841327, 10515.05364517197, 10994.890051099386, 11044.33733975964, 11135.220534562503, 11400.258983166399, 11419.885801142214, 10353.84469232947, 10427.635032773816, 10442.0, 10563.260571759904, 11190.776184476103, 10526.578936842441, 10619.203559733205, 10861.0, 10873.836892446778, 11265.887341126483, 10068.899311006797, 10110.949445252727, 10377.221708371751, 10498.790024199321, 10601.0459058682, 8727.91272087271, 8781.882386926707, 8813.471191728517, 9117.361784675275, 9213.76965575866, 8446.915530844613, 8957.865632015655, 8965.686200982935, 9030.819383612423, 9162.358634895494], "value": 6857.671401882637, "unit": "score"}], "name": "dromaeo_css", "value": 7349.134030229667, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 109.0, 117.0, 116.0, 115.0, 127.0, 115.0, 116.0, 117.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 168.0, 156.0, 154.0, 142.0, 169.0, 177.0, 122.0, 169.0, 118.0], "value": 155.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 187.0, 225.0, 258.0, 201.0, 273.0, 191.0, 274.0, 211.0, 198.0], "value": 222.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 83.0, 73.0, 126.0, 51.0, 63.0, 61.0, 63.0, 77.0, 56.0], "value": 72.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 81.0, 126.0, 97.0, 120.0, 69.0, 142.0, 114.0, 120.0, 152.0], "value": 110.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 89.0, 86.0, 89.0, 86.0, 90.0, 75.0, 136.0, 131.0], "value": 95.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 94.0, 95.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 116.0, 150.0, 92.0, 89.0, 90.0, 92.0, 117.0, 89.0], "value": 101.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 62.0, 62.0, 61.0, 63.0, 61.0, 80.0, 65.0, 63.0], "value": 64.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 69.0, 65.0, 66.0, 71.0, 71.0, 65.0, 79.0, 83.0], "value": 69.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 108.0, 110.0, 107.0, 86.0, 82.0, 86.0, 81.0, 83.0, 84.0], "value": 91.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 150.0, 190.0, 167.0, 158.0, 173.0, 154.0, 160.0, 178.0, 147.0], "value": 161.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 174.0, 172.0, 177.0, 195.0, 230.0, 183.0, 169.0, 176.0, 193.0], "value": 183.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 84.0, 68.0, 70.0, 69.0, 67.0, 68.0, 67.0, 85.0, 97.0], "value": 73.6, "unit": "score"}], "name": "kraken", "value": 1614.3, "alertThreshold": 2.0}]} 05:23:10 INFO - Return code: 0 05:23:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:23:10 ERROR - # TBPL SUCCESS # 05:23:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:23:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:23:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:23:10 INFO - 2016-04-16 05:23:10.557 screenresolution[1692:12486] starting screenresolution argv=screenresolution get 05:23:10 INFO - 2016-04-16 05:23:10.560 screenresolution[1692:12486] Display 0: 1600x1200x32@60 05:23:10 INFO - 2016-04-16 05:23:10.566 screenresolution[1693:12489] starting screenresolution argv=screenresolution list 05:23:10 INFO - Available Modes on Display 0 05:23:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:23:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:23:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:23:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:23:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:23:10 INFO - Intel Iris: 05:23:10 INFO - Chipset Model: Intel Iris 05:23:10 INFO - Type: GPU 05:23:10 INFO - Bus: Built-In 05:23:10 INFO - VRAM (Dynamic, Max): 1536 MB 05:23:10 INFO - Vendor: Intel (0x8086) 05:23:10 INFO - Device ID: 0x0a2e 05:23:10 INFO - Revision ID: 0x0009 05:23:10 INFO - Displays: 05:23:10 INFO - Display: 05:23:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:23:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:23:10 INFO - Display Serial Number: 1600x1200 60 05:23:10 INFO - Main Display: Yes 05:23:10 INFO - Mirror: Off 05:23:10 INFO - Online: Yes 05:23:10 INFO - Rotation: Supported 05:23:10 INFO - Return code: 0 05:23:10 INFO - Running post-action listener: _resource_record_post_action 05:23:10 INFO - Running post-run listener: _resource_record_post_run 05:23:11 INFO - Total resource usage - Wall time: 1207s; CPU: 54.0%; Read bytes: 30796288; Write bytes: 697608192; Read time: 318; Write time: 2261 05:23:11 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 373289472; Write bytes: 367601152; Read time: 28290; Write time: 665 05:23:11 INFO - run-tests - Wall time: 1174s; CPU: 54.0%; Read bytes: 29874688; Write bytes: 300663296; Read time: 305; Write time: 1544 05:23:11 INFO - Running post-run listener: _upload_blobber_files 05:23:11 INFO - Blob upload gear active. 05:23:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:23:11 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 05:23:11 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:23:11 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:23:12 INFO - (blobuploader) - INFO - Open directory for files ... 05:23:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:23:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:23:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:23:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:23:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:23:13 INFO - (blobuploader) - INFO - Done attempting. 05:23:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:23:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:23:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:23:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:23:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:23:14 INFO - (blobuploader) - INFO - Done attempting. 05:23:14 INFO - (blobuploader) - INFO - Iteration through files over. 05:23:14 INFO - Return code: 0 05:23:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:23:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:23:14 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/069b764deec9a708b3c685f894b96ae5528569d15b933e3cae91a41582bba62511a5136b5c691d91e89bcbcfd219853f60a0a199617c86e01962694947f16d63", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/90cb3ea2266905f486f9e14808c92828609af508f7367a5bc1bc505b34fa63fbcb4d5bfd5b8876fce94c6923d79644b9694c4ee36042d33320cadb901f339af7"} 05:23:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:23:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:23:14 INFO - Contents: 05:23:14 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/069b764deec9a708b3c685f894b96ae5528569d15b933e3cae91a41582bba62511a5136b5c691d91e89bcbcfd219853f60a0a199617c86e01962694947f16d63", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/90cb3ea2266905f486f9e14808c92828609af508f7367a5bc1bc505b34fa63fbcb4d5bfd5b8876fce94c6923d79644b9694c4ee36042d33320cadb901f339af7"} 05:23:14 INFO - Running post-run listener: copy_logs_to_upload_dir 05:23:14 INFO - Copying logs to upload dir... 05:23:14 INFO - mkdir: /builds/slave/test/build/upload/logs 05:23:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.725470 ========= master_lag: 7.89 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2016-04-16 05:23:21.995193) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-04-16 05:23:21.999671) ========= 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.vwZpEEQhoX/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.KEWpU6p5P2/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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/Fx-Team/sha512/069b764deec9a708b3c685f894b96ae5528569d15b933e3cae91a41582bba62511a5136b5c691d91e89bcbcfd219853f60a0a199617c86e01962694947f16d63", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/90cb3ea2266905f486f9e14808c92828609af508f7367a5bc1bc505b34fa63fbcb4d5bfd5b8876fce94c6923d79644b9694c4ee36042d33320cadb901f339af7"} build_url:https://queue.taskcluster.net/v1/task/OWFExPIzTnCZmN7EVgavXA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011118 build_url: 'https://queue.taskcluster.net/v1/task/OWFExPIzTnCZmN7EVgavXA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/069b764deec9a708b3c685f894b96ae5528569d15b933e3cae91a41582bba62511a5136b5c691d91e89bcbcfd219853f60a0a199617c86e01962694947f16d63", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/90cb3ea2266905f486f9e14808c92828609af508f7367a5bc1bc505b34fa63fbcb4d5bfd5b8876fce94c6923d79644b9694c4ee36042d33320cadb901f339af7"}' ========= master_lag: 1.46 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-04-16 05:23:23.468339) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 7 secs) (at 2016-04-16 05:23:23.468633) ========= 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.vwZpEEQhoX/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.KEWpU6p5P2/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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.005014 ========= master_lag: 7.65 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 7 secs) (at 2016-04-16 05:23:31.124979) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-16 05:23:31.129271) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-16 05:23:31.150722) ========= ========= Total master_lag: 17.77 =========