builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0252 starttime: 1463174471.56 results: success (0) buildid: 20160513122849 builduid: 186f3523e5a745a8ab44d820f92fc8f2 revision: b1ff014a973c1a1d5218cf0be7034f49f2dd537c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 14:21:11.557913) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 14:21:11.559593) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 14:21:11.560076) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 14:21:11.579354) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 14:21:11.579814) ========= 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.HzJzVljEF6/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.cgS8ZVV0OD/Listeners TMPDIR=/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-13 14:21:11-- 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% 7.75M=0.001s 2016-05-13 14:21:12 (7.75 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.478530 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 14:21:12.075279) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 14:21:12.075780) ========= 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.HzJzVljEF6/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.cgS8ZVV0OD/Listeners TMPDIR=/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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.019478 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 14:21:12.125223) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-13 14:21:12.125594) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev b1ff014a973c1a1d5218cf0be7034f49f2dd537c --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 b1ff014a973c1a1d5218cf0be7034f49f2dd537c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HzJzVljEF6/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.cgS8ZVV0OD/Listeners TMPDIR=/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-13 14:21:12,191 truncating revision to first 12 chars 2016-05-13 14:21:12,191 Setting DEBUG logging. 2016-05-13 14:21:12,191 attempt 1/10 2016-05-13 14:21:12,191 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/b1ff014a973c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-13 14:21:14,287 unpacking tar archive at: fx-team-b1ff014a973c/testing/mozharness/ program finished with exit code 0 elapsedTime=2.344669 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-13 14:21:14.487945) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 14:21:14.488277) ========= script_repo_revision: b1ff014a973c1a1d5218cf0be7034f49f2dd537c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 14:21:14.488714) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 14:21:14.489015) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 14:21:14.502238) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-05-13 14:21:14.502830) ========= /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.HzJzVljEF6/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.cgS8ZVV0OD/Listeners TMPDIR=/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 14:21:14 INFO - MultiFileLogger online at 20160513 14:21:14 in /builds/slave/test 14:21:14 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 14:21:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:21:14 INFO - {'append_to_log': False, 14:21:14 INFO - 'base_work_dir': '/builds/slave/test', 14:21:14 INFO - 'blob_upload_branch': 'Fx-Team', 14:21:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:21:14 INFO - 'branch': 'Fx-Team', 14:21:14 INFO - 'buildbot_json_path': 'buildprops.json', 14:21:14 INFO - 'config_files': ('talos/mac_config.py',), 14:21:14 INFO - 'default_actions': ('clobber', 14:21:14 INFO - 'read-buildbot-config', 14:21:14 INFO - 'download-and-extract', 14:21:14 INFO - 'populate-webroot', 14:21:14 INFO - 'create-virtualenv', 14:21:14 INFO - 'install', 14:21:14 INFO - 'run-tests'), 14:21:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:21:14 INFO - 'download_minidump_stackwalk': True, 14:21:14 INFO - 'download_symbols': 'ondemand', 14:21:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:21:14 INFO - 'tooltool.py': '/tools/tooltool.py', 14:21:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:21:14 INFO - '/tools/misc-python/virtualenv.py')}, 14:21:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:21:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:21:14 INFO - 'installer_path': 'installer.exe', 14:21:14 INFO - 'log_level': 'info', 14:21:14 INFO - 'log_name': 'talos', 14:21:14 INFO - 'log_to_console': True, 14:21:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:21:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:21:14 INFO - 'opt_config_files': (), 14:21:14 INFO - 'pip_index': False, 14:21:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:21:14 INFO - 'cmd': ('bash', 14:21:14 INFO - '-c', 14:21:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:21:14 INFO - 'enabled': True, 14:21:14 INFO - 'halt_on_failure': False, 14:21:14 INFO - 'name': 'check_screen_resolution'},), 14:21:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:21:14 INFO - 'cmd': ('bash', 14:21:14 INFO - '-c', 14:21:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:21:14 INFO - 'enabled': True, 14:21:14 INFO - 'halt_on_failure': False, 14:21:14 INFO - 'name': 'check_screen_resolution'},), 14:21:14 INFO - 'run_cmd_checks_enabled': True, 14:21:14 INFO - 'sps_profile': False, 14:21:14 INFO - 'sps_profile_interval': 0, 14:21:14 INFO - 'suite': 'dromaeojs-e10s', 14:21:14 INFO - 'system_bits': '32', 14:21:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:21:14 INFO - 'title': 't-yosemite-r7-0252', 14:21:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:21:14 INFO - 'use_talos_json': True, 14:21:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:21:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:21:14 INFO - 'work_dir': 'build'} 14:21:14 INFO - ##### 14:21:14 INFO - ##### Running clobber step. 14:21:14 INFO - ##### 14:21:14 INFO - Running pre-action listener: _resource_record_pre_action 14:21:14 INFO - Running main action method: clobber 14:21:14 INFO - rmtree: /builds/slave/test/build 14:21:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:21:16 INFO - Running post-action listener: _resource_record_post_action 14:21:16 INFO - ##### 14:21:16 INFO - ##### Finished clobber step (success) 14:21:16 INFO - ##### 14:21:16 INFO - ##### 14:21:16 INFO - ##### Running read-buildbot-config step. 14:21:16 INFO - ##### 14:21:16 INFO - Running pre-action listener: _resource_record_pre_action 14:21:16 INFO - Running main action method: read_buildbot_config 14:21:16 INFO - Using buildbot properties: 14:21:16 INFO - { 14:21:16 INFO - "project": "", 14:21:16 INFO - "product": "firefox", 14:21:16 INFO - "script_repo_revision": "production", 14:21:16 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 14:21:16 INFO - "repository": "", 14:21:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 14:21:16 INFO - "buildid": "20160513122849", 14:21:16 INFO - "pgo_build": "False", 14:21:16 INFO - "basedir": "/builds/slave/test", 14:21:16 INFO - "buildnumber": 666, 14:21:16 INFO - "slavename": "t-yosemite-r7-0252", 14:21:16 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 14:21:16 INFO - "platform": "yosemite_r7", 14:21:16 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 14:21:16 INFO - "branch": "fx-team", 14:21:16 INFO - "repo_path": "integration/fx-team", 14:21:16 INFO - "revision": "b1ff014a973c1a1d5218cf0be7034f49f2dd537c", 14:21:16 INFO - "stage_platform": "macosx64", 14:21:16 INFO - "builduid": "186f3523e5a745a8ab44d820f92fc8f2", 14:21:16 INFO - "slavebuilddir": "test" 14:21:16 INFO - } 14:21:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 14:21:16 INFO - Running post-action listener: _resource_record_post_action 14:21:16 INFO - ##### 14:21:16 INFO - ##### Finished read-buildbot-config step (success) 14:21:16 INFO - ##### 14:21:16 INFO - ##### 14:21:16 INFO - ##### Running download-and-extract step. 14:21:16 INFO - ##### 14:21:16 INFO - Running pre-action listener: _resource_record_pre_action 14:21:16 INFO - Running main action method: download_and_extract 14:21:16 INFO - mkdir: /builds/slave/test/build/tests 14:21:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:21:16 INFO - https://queue.taskcluster.net/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 14:21:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 14:21:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 14:21:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 14:21:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 14:21:19 INFO - Downloaded 1199 bytes. 14:21:19 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 14:21:19 INFO - Using the following test package requirements: 14:21:19 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 14:21:19 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:21:19 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 14:21:19 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:21:19 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 14:21:19 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:21:19 INFO - u'jsshell-mac.zip'], 14:21:19 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:21:19 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 14:21:19 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 14:21:19 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:21:19 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 14:21:19 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:21:19 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 14:21:19 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:21:19 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 14:21:19 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:21:19 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 14:21:19 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:21:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:21:19 INFO - https://queue.taskcluster.net/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:21:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:21:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:21:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:21:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:21:23 INFO - Downloaded 18153778 bytes. 14:21:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:21:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 14:21:26 INFO - Return code: 0 14:21:26 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 14:21:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:21:26 INFO - https://queue.taskcluster.net/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:21:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:21:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:21:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:21:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:21:35 INFO - Downloaded 18153778 bytes. 14:21:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:21:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 14:21:39 INFO - Return code: 0 14:21:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:21:39 INFO - https://queue.taskcluster.net/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 14:21:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 14:21:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 14:21:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 14:21:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:21:40 INFO - Downloaded 10840239 bytes. 14:21:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:21:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 14:21:41 INFO - Return code: 0 14:21:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:21:41 INFO - https://queue.taskcluster.net/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 14:21:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:21:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:21:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 14:21:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 14:21:44 INFO - Downloaded 110072128 bytes. 14:21:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:21:44 INFO - mkdir: /builds/slave/test/properties 14:21:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:21:44 INFO - Writing to file /builds/slave/test/properties/build_url 14:21:44 INFO - Contents: 14:21:44 INFO - build_url:https://queue.taskcluster.net/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:21:45 INFO - Running post-action listener: _resource_record_post_action 14:21:45 INFO - Running post-action listener: set_extra_try_arguments 14:21:45 INFO - ##### 14:21:45 INFO - ##### Finished download-and-extract step (success) 14:21:45 INFO - ##### 14:21:45 INFO - ##### 14:21:45 INFO - ##### Running populate-webroot step. 14:21:45 INFO - ##### 14:21:45 INFO - Running pre-action listener: _resource_record_pre_action 14:21:45 INFO - Running main action method: populate_webroot 14:21:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:21:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:21:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:21:45 INFO - '--tppagecycles', 14:21:45 INFO - '7'], 14:21:45 INFO - 'tests': ['tsvgm']}}, 14:21:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:21:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:21:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:21:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:21:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:21:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:21:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:21:45 INFO - 'talos_options': ['--disable-e10s'], 14:21:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:21:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:21:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:21:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:21:45 INFO - 'talos_options': ['--disable-e10s'], 14:21:45 INFO - 'tests': ['damp', 'tps']}, 14:21:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:21:45 INFO - 'tests': ['damp', 'tps']}, 14:21:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:21:45 INFO - 'tests': ['dromaeo_dom']}, 14:21:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:21:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:21:45 INFO - 'tests': ['a11yr', 14:21:45 INFO - 'ts_paint', 14:21:45 INFO - 'tpaint', 14:21:45 INFO - 'sessionrestore', 14:21:45 INFO - 'sessionrestore_no_auto_restore', 14:21:45 INFO - 'tabpaint']}, 14:21:45 INFO - 'other-e10s': {'tests': ['a11yr', 14:21:45 INFO - 'ts_paint', 14:21:45 INFO - 'tpaint', 14:21:45 INFO - 'sessionrestore', 14:21:45 INFO - 'sessionrestore_no_auto_restore', 14:21:45 INFO - 'tabpaint']}, 14:21:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:21:45 INFO - 'ts_paint', 14:21:45 INFO - 'tpaint', 14:21:45 INFO - 'sessionrestore', 14:21:45 INFO - 'sessionrestore_no_auto_restore', 14:21:45 INFO - 'tabpaint']}, 14:21:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:21:45 INFO - 'ts_paint', 14:21:45 INFO - 'tpaint', 14:21:45 INFO - 'sessionrestore', 14:21:45 INFO - 'sessionrestore_no_auto_restore', 14:21:45 INFO - 'tabpaint']}, 14:21:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:21:45 INFO - 'tests': ['a11yr', 14:21:45 INFO - 'ts_paint', 14:21:45 INFO - 'tpaint', 14:21:45 INFO - 'sessionrestore', 14:21:45 INFO - 'sessionrestore_no_auto_restore', 14:21:45 INFO - 'tabpaint']}, 14:21:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:21:45 INFO - 'tests': ['a11yr', 14:21:45 INFO - 'ts_paint', 14:21:45 INFO - 'tpaint', 14:21:45 INFO - 'sessionrestore', 14:21:45 INFO - 'sessionrestore_no_auto_restore', 14:21:45 INFO - 'tabpaint']}, 14:21:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:21:45 INFO - 'tests': ['tsvgx', 14:21:45 INFO - 'tsvgr_opacity', 14:21:45 INFO - 'tart', 14:21:45 INFO - 'tscrollx', 14:21:45 INFO - 'cart']}, 14:21:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:21:45 INFO - 'tsvgr_opacity', 14:21:45 INFO - 'tart', 14:21:45 INFO - 'tscrollx', 14:21:45 INFO - 'cart']}, 14:21:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:21:45 INFO - 'talos_options': ['--disable-e10s'], 14:21:45 INFO - 'tests': ['tp5o']}, 14:21:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:21:45 INFO - 'tests': ['tp5o']}, 14:21:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:21:45 INFO - 'talos_options': ['--disable-e10s', 14:21:45 INFO - '--xperf_path', 14:21:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:21:45 INFO - 'tests': ['tp5n']}, 14:21:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:21:45 INFO - 'talos_options': ['--xperf_path', 14:21:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:21:45 INFO - 'tests': ['tp5n']}}, 14:21:45 INFO - 'talos.zip': {'path': '', 14:21:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:21:45 INFO - Running post-action listener: _resource_record_post_action 14:21:45 INFO - ##### 14:21:45 INFO - ##### Finished populate-webroot step (success) 14:21:45 INFO - ##### 14:21:45 INFO - ##### 14:21:45 INFO - ##### Running create-virtualenv step. 14:21:45 INFO - ##### 14:21:45 INFO - Running pre-action listener: _resource_record_pre_action 14:21:45 INFO - Running main action method: create_virtualenv 14:21:45 INFO - Creating virtualenv /builds/slave/test/build/venv 14:21:45 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 14:21:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:21:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:21:45 INFO - Using real prefix '/tools/python27' 14:21:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:21:46 INFO - Installing distribute.............................................................................................................................................................................................done. 14:21:49 INFO - Installing pip.................done. 14:21:49 INFO - Return code: 0 14:21:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:21: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')]} 14:21:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:21:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:21: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')]} 14:21:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:21:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:21:49 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 0x10161fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101625570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff60a585a00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014c3d30>, '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 0x1012e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1014bab20>, '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.HzJzVljEF6/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.cgS8ZVV0OD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:21:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:21:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:21:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HzJzVljEF6/Render', 14:21:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:21:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:21:49 INFO - 'HOME': '/Users/cltbld', 14:21:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:21:49 INFO - 'LOGNAME': 'cltbld', 14:21:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:21:49 INFO - 'MOZ_NO_REMOTE': '1', 14:21:49 INFO - 'NO_EM_RESTART': '1', 14:21:49 INFO - 'PAGER': '/bin/cat', 14:21:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:21:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:21:49 INFO - 'PWD': '/builds/slave/test', 14:21:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:21:49 INFO - 'SHELL': '/bin/bash', 14:21:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cgS8ZVV0OD/Listeners', 14:21:49 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 14:21:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:21:49 INFO - 'USER': 'cltbld', 14:21:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:21:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:21:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:21:49 INFO - 'XPC_FLAGS': '0x0', 14:21:49 INFO - 'XPC_SERVICE_NAME': '0', 14:21:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:21:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:21:49 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 14:21:49 INFO - Cleaning up... 14:21:49 INFO - Return code: 0 14:21:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:21: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')]} 14:21:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:21:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:21: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')]} 14:21:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:21:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:21:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10161fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101625570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff60a585a00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014c3d30>, '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 0x1012e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1014bab20>, '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.HzJzVljEF6/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.cgS8ZVV0OD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:21:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:21:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:21:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HzJzVljEF6/Render', 14:21:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:21:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:21:49 INFO - 'HOME': '/Users/cltbld', 14:21:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:21:49 INFO - 'LOGNAME': 'cltbld', 14:21:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:21:49 INFO - 'MOZ_NO_REMOTE': '1', 14:21:49 INFO - 'NO_EM_RESTART': '1', 14:21:49 INFO - 'PAGER': '/bin/cat', 14:21:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:21:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:21:49 INFO - 'PWD': '/builds/slave/test', 14:21:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:21:49 INFO - 'SHELL': '/bin/bash', 14:21:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cgS8ZVV0OD/Listeners', 14:21:49 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 14:21:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:21:49 INFO - 'USER': 'cltbld', 14:21:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:21:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:21:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:21:49 INFO - 'XPC_FLAGS': '0x0', 14:21:49 INFO - 'XPC_SERVICE_NAME': '0', 14:21:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:21:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:21:49 INFO - Downloading/unpacking psutil>=3.1.1 14:21:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:21:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:21:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:21:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:21:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:21:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:21:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:21:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:21:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:21:52 INFO - Installing collected packages: psutil 14:21:52 INFO - Running setup.py install for psutil 14:21:52 INFO - building 'psutil._psutil_osx' extension 14:21:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 14:21:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 14:21:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 14:21:53 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 14:21:53 INFO - building 'psutil._psutil_posix' extension 14:21:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 14:21:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:21:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:21:53 INFO - ^ 14:21:53 INFO - 1 warning generated. 14:21:53 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 14:21:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:21:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:21:53 INFO - Successfully installed psutil 14:21:53 INFO - Cleaning up... 14:21:53 INFO - Return code: 0 14:21:53 INFO - Installing mozsystemmonitor==0.1 into virtualenv /builds/slave/test/build/venv 14:21:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:21:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:21:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:21:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:21:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:21:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:21:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10161fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101625570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff60a585a00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014c3d30>, '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 0x1012e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1014bab20>, '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.HzJzVljEF6/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.cgS8ZVV0OD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:21:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.1'] in /builds/slave/test/build 14:21:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.1 14:21:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HzJzVljEF6/Render', 14:21:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:21:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:21:53 INFO - 'HOME': '/Users/cltbld', 14:21:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:21:53 INFO - 'LOGNAME': 'cltbld', 14:21:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:21:53 INFO - 'MOZ_NO_REMOTE': '1', 14:21:53 INFO - 'NO_EM_RESTART': '1', 14:21:53 INFO - 'PAGER': '/bin/cat', 14:21:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:21:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:21:53 INFO - 'PWD': '/builds/slave/test', 14:21:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:21:53 INFO - 'SHELL': '/bin/bash', 14:21:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cgS8ZVV0OD/Listeners', 14:21:53 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 14:21:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:21:53 INFO - 'USER': 'cltbld', 14:21:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:21:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:21:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:21:53 INFO - 'XPC_FLAGS': '0x0', 14:21:53 INFO - 'XPC_SERVICE_NAME': '0', 14:21:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:21:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:21:53 INFO - Downloading/unpacking mozsystemmonitor==0.1 14:21:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:21:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:21:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:21:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:21:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:21:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:21:56 INFO - Downloading mozsystemmonitor-0.1.tar.gz 14:21:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:21:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.1) 14:21:56 INFO - Installing collected packages: mozsystemmonitor 14:21:56 INFO - Running setup.py install for mozsystemmonitor 14:21:56 INFO - Successfully installed mozsystemmonitor 14:21:56 INFO - Cleaning up... 14:21:56 INFO - Return code: 0 14:21:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:21:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:21:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:21:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:21:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:21:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:21:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:21:56 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 0x10161fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101625570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff60a585a00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014c3d30>, '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 0x1012e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1014bab20>, '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.HzJzVljEF6/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.cgS8ZVV0OD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:21:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:21:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:21:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HzJzVljEF6/Render', 14:21:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:21:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:21:56 INFO - 'HOME': '/Users/cltbld', 14:21:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:21:56 INFO - 'LOGNAME': 'cltbld', 14:21:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:21:56 INFO - 'MOZ_NO_REMOTE': '1', 14:21:56 INFO - 'NO_EM_RESTART': '1', 14:21:56 INFO - 'PAGER': '/bin/cat', 14:21:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:21:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:21:56 INFO - 'PWD': '/builds/slave/test', 14:21:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:21:56 INFO - 'SHELL': '/bin/bash', 14:21:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cgS8ZVV0OD/Listeners', 14:21:56 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 14:21:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:21:56 INFO - 'USER': 'cltbld', 14:21:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:21:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:21:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:21:56 INFO - 'XPC_FLAGS': '0x0', 14:21:56 INFO - 'XPC_SERVICE_NAME': '0', 14:21:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:21:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:21:56 INFO - Downloading/unpacking blobuploader==1.2.4 14:21:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:21:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:21:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:21:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:21:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:21:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:21:59 INFO - Downloading blobuploader-1.2.4.tar.gz 14:21:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:21:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:21:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:21:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:21:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:21:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:21:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:21:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:21:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:21:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:21:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:21:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:21:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:21:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:21:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:21:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:00 INFO - Downloading docopt-0.6.1.tar.gz 14:22:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:22:00 INFO - Installing collected packages: blobuploader, requests, docopt 14:22:00 INFO - Running setup.py install for blobuploader 14:22:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:22:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:22:00 INFO - Running setup.py install for requests 14:22:01 INFO - Running setup.py install for docopt 14:22:01 INFO - Successfully installed blobuploader requests docopt 14:22:01 INFO - Cleaning up... 14:22:01 INFO - Return code: 0 14:22:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:22:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:01 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 0x10161fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101625570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff60a585a00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014c3d30>, '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 0x1012e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1014bab20>, '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.HzJzVljEF6/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.cgS8ZVV0OD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:22:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:22:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:22:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HzJzVljEF6/Render', 14:22:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:22:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:22:01 INFO - 'HOME': '/Users/cltbld', 14:22:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:22:01 INFO - 'LOGNAME': 'cltbld', 14:22:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:01 INFO - 'MOZ_NO_REMOTE': '1', 14:22:01 INFO - 'NO_EM_RESTART': '1', 14:22:01 INFO - 'PAGER': '/bin/cat', 14:22:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:22:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:01 INFO - 'PWD': '/builds/slave/test', 14:22:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:22:01 INFO - 'SHELL': '/bin/bash', 14:22:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cgS8ZVV0OD/Listeners', 14:22:01 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 14:22:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:22:01 INFO - 'USER': 'cltbld', 14:22:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:22:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:22:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:01 INFO - 'XPC_FLAGS': '0x0', 14:22:01 INFO - 'XPC_SERVICE_NAME': '0', 14:22:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:22:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:22:01 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-_rPRgD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:22:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:22:01 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-odO8yX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:22:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:22:01 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-qD2FmN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:22:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:22:01 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-Ec9O5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:22:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:22:01 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-pYvyfU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:22:02 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-PisWyp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:22:02 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-ZEkEPd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:22:02 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-XQykJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:22:02 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-zjAWIh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:22:02 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-ydp5tI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:22:02 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-cObWqR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:22:02 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-b3ZXve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:22:02 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-11vP0F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:22:02 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-IRcHGD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:22:03 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-6zDnKT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:22:03 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-URsEC4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:22:03 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-7dl0fd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:22:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:22:03 INFO - Running setup.py install for manifestparser 14:22:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:22:03 INFO - Running setup.py install for mozcrash 14:22:03 INFO - Running setup.py install for mozdebug 14:22:03 INFO - Running setup.py install for mozdevice 14:22:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:22:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:22:03 INFO - Running setup.py install for mozfile 14:22:04 INFO - Running setup.py install for mozhttpd 14:22:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:22:04 INFO - Running setup.py install for mozinfo 14:22:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:22:04 INFO - Running setup.py install for mozInstall 14:22:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:22:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:22:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:22:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:22:04 INFO - Running setup.py install for mozleak 14:22:04 INFO - Running setup.py install for mozlog 14:22:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:22:04 INFO - Running setup.py install for moznetwork 14:22:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:22:04 INFO - Running setup.py install for mozprocess 14:22:05 INFO - Running setup.py install for mozprofile 14:22:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:22:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:22:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:22:05 INFO - Running setup.py install for mozrunner 14:22:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:22:05 INFO - Running setup.py install for mozscreenshot 14:22:05 INFO - Running setup.py install for moztest 14:22:05 INFO - Running setup.py install for mozversion 14:22:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:22:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:22:05 INFO - Cleaning up... 14:22:05 INFO - Return code: 0 14:22:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:22:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:05 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 0x10161fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101625570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff60a585a00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014c3d30>, '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 0x1012e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1014bab20>, '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.HzJzVljEF6/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.cgS8ZVV0OD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:22:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:22:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:22:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HzJzVljEF6/Render', 14:22:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:22:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:22:05 INFO - 'HOME': '/Users/cltbld', 14:22:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:22:05 INFO - 'LOGNAME': 'cltbld', 14:22:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:05 INFO - 'MOZ_NO_REMOTE': '1', 14:22:05 INFO - 'NO_EM_RESTART': '1', 14:22:05 INFO - 'PAGER': '/bin/cat', 14:22:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:22:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:05 INFO - 'PWD': '/builds/slave/test', 14:22:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:22:05 INFO - 'SHELL': '/bin/bash', 14:22:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cgS8ZVV0OD/Listeners', 14:22:05 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 14:22:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:22:05 INFO - 'USER': 'cltbld', 14:22:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:22:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:22:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:05 INFO - 'XPC_FLAGS': '0x0', 14:22:05 INFO - 'XPC_SERVICE_NAME': '0', 14:22:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:22:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:22:06 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-6VHkd3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:22:06 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 14:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:22:06 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-lgkdqN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:22:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:22:06 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-URdouV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:22:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:22:06 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-rpT8fC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:22:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:22:06 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-lwliFz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:22:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 14:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:22:06 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-HRfsD3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:22:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 14:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:22:06 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-6oIyD4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:22:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 14:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:22:06 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-9S_Vgv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:22:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 14:22:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:22:07 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-XbSK38-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:22:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 14:22:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:22:07 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-Yc1q3k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:22:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:22:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:22:07 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-kKUO3W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:22:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 14:22:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:22:07 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-m_ohkl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:22:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 14:22:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:22:07 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-ol4FE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:22:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 14:22:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:22:07 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-sAeKy0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:22:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:22:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:22:07 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-2bfE2Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:22:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 14:22:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:22:07 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-vVNLJX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:22:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 14:22:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:22:07 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-sDYX0Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:22:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 14:22:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:22:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:22:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:22:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:22:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:22:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:22:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:10 INFO - Downloading blessings-1.6.tar.gz 14:22:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:22:10 INFO - Installing collected packages: blessings 14:22:10 INFO - Running setup.py install for blessings 14:22:10 INFO - Successfully installed blessings 14:22:10 INFO - Cleaning up... 14:22:10 INFO - Return code: 0 14:22:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:22:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:22:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:22:11 INFO - Reading from file tmpfile_stdout 14:22:11 INFO - Current package versions: 14:22:11 INFO - blessings == 1.6 14:22:11 INFO - blobuploader == 1.2.4 14:22:11 INFO - docopt == 0.6.1 14:22:11 INFO - manifestparser == 1.1 14:22:11 INFO - mozInstall == 1.12 14:22:11 INFO - mozcrash == 0.17 14:22:11 INFO - mozdebug == 0.1 14:22:11 INFO - mozdevice == 0.48 14:22:11 INFO - mozfile == 1.2 14:22:11 INFO - mozhttpd == 0.7 14:22:11 INFO - mozinfo == 0.9 14:22:11 INFO - mozleak == 0.1 14:22:11 INFO - mozlog == 3.2 14:22:11 INFO - moznetwork == 0.27 14:22:11 INFO - mozprocess == 0.22 14:22:11 INFO - mozprofile == 0.28 14:22:11 INFO - mozrunner == 6.11 14:22:11 INFO - mozscreenshot == 0.1 14:22:11 INFO - mozsystemmonitor == 0.1 14:22:11 INFO - moztest == 0.7 14:22:11 INFO - mozversion == 1.4 14:22:11 INFO - psutil == 3.1.1 14:22:11 INFO - requests == 1.2.3 14:22:11 INFO - wsgiref == 0.1.2 14:22:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:22:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:11 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 0x10161fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101625570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff60a585a00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014c3d30>, '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 0x1012e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1014bab20>, '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.HzJzVljEF6/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.cgS8ZVV0OD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:22:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 14:22:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:22:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HzJzVljEF6/Render', 14:22:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:22:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:22:11 INFO - 'HOME': '/Users/cltbld', 14:22:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:22:11 INFO - 'LOGNAME': 'cltbld', 14:22:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:11 INFO - 'MOZ_NO_REMOTE': '1', 14:22:11 INFO - 'NO_EM_RESTART': '1', 14:22:11 INFO - 'PAGER': '/bin/cat', 14:22:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:22:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:11 INFO - 'PWD': '/builds/slave/test', 14:22:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:22:11 INFO - 'SHELL': '/bin/bash', 14:22:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cgS8ZVV0OD/Listeners', 14:22:11 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 14:22:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:22:11 INFO - 'USER': 'cltbld', 14:22:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:22:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:22:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:11 INFO - 'XPC_FLAGS': '0x0', 14:22:11 INFO - 'XPC_SERVICE_NAME': '0', 14:22:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:22:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:22:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 14:22:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 14:22:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:22:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 14:22:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 14:22:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 14:22:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 14:22:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 14:22:11 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:22:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:22:11 INFO - Cleaning up... 14:22:11 INFO - Return code: 0 14:22:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:22:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10161fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101625570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff60a585a00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014c3d30>, '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 0x1012e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1014bab20>, '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.HzJzVljEF6/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.cgS8ZVV0OD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:22:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:22:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:22:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HzJzVljEF6/Render', 14:22:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:22:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:22:11 INFO - 'HOME': '/Users/cltbld', 14:22:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:22:11 INFO - 'LOGNAME': 'cltbld', 14:22:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:11 INFO - 'MOZ_NO_REMOTE': '1', 14:22:11 INFO - 'NO_EM_RESTART': '1', 14:22:11 INFO - 'PAGER': '/bin/cat', 14:22:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:22:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:11 INFO - 'PWD': '/builds/slave/test', 14:22:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:22:11 INFO - 'SHELL': '/bin/bash', 14:22:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cgS8ZVV0OD/Listeners', 14:22:11 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 14:22:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:22:11 INFO - 'USER': 'cltbld', 14:22:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:22:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:22:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:11 INFO - 'XPC_FLAGS': '0x0', 14:22:11 INFO - 'XPC_SERVICE_NAME': '0', 14:22:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:22:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:11 INFO - Downloading/unpacking jsonschema 14:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:22:14 INFO - Downloading/unpacking functools32 (from jsonschema) 14:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:14 INFO - Downloading functools32-3.2.3-2.tar.gz 14:22:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:22:14 INFO - warning: no files found matching '*.txt' 14:22:14 INFO - no previously-included directories found matching 'build' 14:22:14 INFO - no previously-included directories found matching 'dist' 14:22:14 INFO - no previously-included directories found matching '.git*' 14:22:14 INFO - Installing collected packages: jsonschema, functools32 14:22:14 INFO - Running setup.py install for functools32 14:22:14 INFO - warning: no files found matching '*.txt' 14:22:14 INFO - no previously-included directories found matching 'build' 14:22:14 INFO - no previously-included directories found matching 'dist' 14:22:14 INFO - no previously-included directories found matching '.git*' 14:22:14 INFO - Successfully installed jsonschema functools32 14:22:14 INFO - Cleaning up... 14:22:14 INFO - Return code: 0 14:22:14 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 14:22:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10161fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101625570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff60a585a00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014c3d30>, '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 0x1012e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1014bab20>, '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.HzJzVljEF6/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.cgS8ZVV0OD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:22:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 14:22:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 14:22:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HzJzVljEF6/Render', 14:22:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:22:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:22:14 INFO - 'HOME': '/Users/cltbld', 14:22:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:22:14 INFO - 'LOGNAME': 'cltbld', 14:22:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:14 INFO - 'MOZ_NO_REMOTE': '1', 14:22:14 INFO - 'NO_EM_RESTART': '1', 14:22:14 INFO - 'PAGER': '/bin/cat', 14:22:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:22:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:14 INFO - 'PWD': '/builds/slave/test', 14:22:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:22:14 INFO - 'SHELL': '/bin/bash', 14:22:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cgS8ZVV0OD/Listeners', 14:22:14 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 14:22:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:22:14 INFO - 'USER': 'cltbld', 14:22:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:22:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:22:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:14 INFO - 'XPC_FLAGS': '0x0', 14:22:14 INFO - 'XPC_SERVICE_NAME': '0', 14:22:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:22:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:15 INFO - Downloading/unpacking flake8 14:22:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:17 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:22:17 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:22:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:18 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:22:18 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:22:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:18 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:22:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 14:22:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:22:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:22:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:22:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:22:19 INFO - no previously-included directories found matching 'docs/_build' 14:22:19 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:22:19 INFO - Running setup.py install for pep8 14:22:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:22:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:22:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:22:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:22:19 INFO - no previously-included directories found matching 'docs/_build' 14:22:19 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 14:22:19 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:22:19 INFO - Cleaning up... 14:22:19 INFO - Return code: 0 14:22:19 INFO - Running post-action listener: _resource_record_post_action 14:22:19 INFO - Running post-action listener: _start_resource_monitoring 14:22:19 INFO - Starting resource monitoring. 14:22:19 INFO - ##### 14:22:19 INFO - ##### Finished create-virtualenv step (success) 14:22:19 INFO - ##### 14:22:19 INFO - ##### 14:22:19 INFO - ##### Running install step. 14:22:19 INFO - ##### 14:22:19 INFO - Running pre-action listener: _resource_record_pre_action 14:22:19 INFO - Running main action method: install 14:22:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:22:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:22:19 INFO - Reading from file tmpfile_stdout 14:22:19 INFO - Detecting whether we're running mozinstall >=1.0... 14:22:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:22:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:22:19 INFO - Reading from file tmpfile_stdout 14:22:19 INFO - Output received: 14:22:19 INFO - Usage: mozinstall [options] installer 14:22:19 INFO - Options: 14:22:19 INFO - -h, --help show this help message and exit 14:22:19 INFO - -d DEST, --destination=DEST 14:22:19 INFO - Directory to install application into. [default: 14:22:19 INFO - "/builds/slave/test"] 14:22:19 INFO - --app=APP Application being installed. [default: firefox] 14:22:19 INFO - mkdir: /builds/slave/test/build/application 14:22:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:22:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 14:22:53 INFO - Reading from file tmpfile_stdout 14:22:53 INFO - Output received: 14:22:53 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:22:53 INFO - Running post-action listener: _resource_record_post_action 14:22:53 INFO - ##### 14:22:53 INFO - ##### Finished install step (success) 14:22:53 INFO - ##### 14:22:53 INFO - ##### 14:22:53 INFO - ##### Running run-tests step. 14:22:53 INFO - ##### 14:22:53 INFO - Running pre-action listener: _resource_record_pre_action 14:22:53 INFO - Running main action method: run_tests 14:22:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:22:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:22:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:22:53 INFO - 2016-05-13 14:22:53.482 screenresolution[1945:24957] starting screenresolution argv=screenresolution get 14:22:53 INFO - 2016-05-13 14:22:53.485 screenresolution[1945:24957] Display 0: 1600x1200x32@60 14:22:53 INFO - 2016-05-13 14:22:53.491 screenresolution[1946:24959] starting screenresolution argv=screenresolution list 14:22:53 INFO - Available Modes on Display 0 14:22:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:22:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:22:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:22:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:22:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:22:53 INFO - Intel Iris: 14:22:53 INFO - Chipset Model: Intel Iris 14:22:53 INFO - Type: GPU 14:22:53 INFO - Bus: Built-In 14:22:53 INFO - VRAM (Dynamic, Max): 1536 MB 14:22:53 INFO - Vendor: Intel (0x8086) 14:22:53 INFO - Device ID: 0x0a2e 14:22:53 INFO - Revision ID: 0x0009 14:22:53 INFO - Displays: 14:22:53 INFO - Display: 14:22:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:22:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:22:53 INFO - Display Serial Number: 1600x1200 60 14:22:53 INFO - Main Display: Yes 14:22:53 INFO - Mirror: Off 14:22:53 INFO - Online: Yes 14:22:53 INFO - Rotation: Supported 14:22:53 INFO - Return code: 0 14:22:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:22:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:22:53 INFO - Python 2.7.3 14:22:53 INFO - Return code: 0 14:22:53 INFO - grabbing minidump binary from tooltool 14:22:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:53 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014c3d30>, '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 0x1012e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1014bab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:22:53 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 14:22:53 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 14:22:53 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 14:22:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:22:53 INFO - Return code: 0 14:22:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:22:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:22:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:22:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:22:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:22:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 14:22:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 14:22:54 INFO - Return code: 0 14:22:54 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/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0252', '--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 14:22:54 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/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0252 --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 14:22:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HzJzVljEF6/Render', 14:22:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:22:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:22:54 INFO - 'HOME': '/Users/cltbld', 14:22:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:22:54 INFO - 'LOGNAME': 'cltbld', 14:22:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:22:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:22:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:54 INFO - 'MOZ_NO_REMOTE': '1', 14:22:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:22:54 INFO - 'NO_EM_RESTART': '1', 14:22:54 INFO - 'PAGER': '/bin/cat', 14:22:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:22:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:54 INFO - 'PWD': '/builds/slave/test', 14:22:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:22:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:22:54 INFO - 'SHELL': '/bin/bash', 14:22:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cgS8ZVV0OD/Listeners', 14:22:54 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 14:22:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:22:54 INFO - 'USER': 'cltbld', 14:22:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:22:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:22:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:54 INFO - 'XPC_FLAGS': '0x0', 14:22:54 INFO - 'XPC_SERVICE_NAME': '0', 14:22:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:22:54 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/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0252', '--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 14:22:55 INFO - mozversion application_buildid: 20160513122849 14:22:55 INFO - mozversion application_changeset: b1ff014a973c1a1d5218cf0be7034f49f2dd537c 14:22:55 INFO - mozversion application_display_name: Nightly 14:22:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:22:55 INFO - mozversion application_name: Firefox 14:22:55 INFO - mozversion application_remotingname: firefox 14:22:55 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 14:22:55 INFO - mozversion application_vendor: Mozilla 14:22:55 INFO - mozversion application_version: 49.0a1 14:22:55 INFO - mozversion platform_buildid: 20160513122849 14:22:55 INFO - mozversion platform_changeset: b1ff014a973c1a1d5218cf0be7034f49f2dd537c 14:22:55 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 14:22:55 INFO - mozversion platform_version: 49.0a1 14:22:55 INFO - using testdate: 1463174575 14:22:55 INFO - actual date: 1463174575 14:22:55 INFO - starting webserver on 'localhost:52847' 14:22:55 INFO - SUITE-START | Running 2 tests 14:22:55 INFO - TEST-START | dromaeo_css 14:22:55 INFO - operating with platform_type : mac_ 14:22:55 INFO - Initialising browser for dromaeo_css test... 14:22:55 INFO - TEST-INFO | started process 1964 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/tmpXvSd7K/profile http://localhost:52847/getInfo.html) 14:22:58 INFO - PROCESS | 1964 | __metrics Screen width/height:1600/1200 14:22:58 INFO - PROCESS | 1964 | colorDepth:24 14:22:58 INFO - PROCESS | 1964 | Browser inner width/height: 1024/689 14:22:58 INFO - PROCESS | 1964 | __metrics 14:22:58 ERROR - PROCESS | 1964 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 14:22:58 INFO - TEST-INFO | 1964: exit 0 14:22:58 INFO - Browser initialized. 14:22:58 INFO - Running cycle 1/1 for dromaeo_css test... 14:22:58 INFO - TEST-INFO | started process 1967 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/tmpXvSd7K/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:23:10 INFO - PROCESS | 1967 | RSS: Main: 250236928 14:23:10 INFO - PROCESS | 1967 | 14:24:00 INFO - PROCESS | 1967 | 1463174640506 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 14:26:11 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:52847/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52847/tests/dromaeo/cssquery-ext.html) 14:26:12 INFO - PROCESS | 1967 | RSS: Main: 263671808 14:26:12 INFO - PROCESS | 1967 | 14:29:14 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:52847/tests/dromaeo/cssquery-ext.html (next: http://localhost:52847/tests/dromaeo/cssquery-jquery.html) 14:29:15 INFO - PROCESS | 1967 | RSS: Main: 275275776 14:29:15 INFO - PROCESS | 1967 | 14:32:17 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:52847/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52847/tests/dromaeo/cssquery-mootools.html) 14:32:17 INFO - PROCESS | 1967 | RSS: Main: 277286912 14:32:17 INFO - PROCESS | 1967 | 14:35:19 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:52847/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52847/tests/dromaeo/cssquery-prototype.html) 14:35:20 INFO - PROCESS | 1967 | RSS: Main: 279040000 14:35:20 INFO - PROCESS | 1967 | 14:38:22 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:52847/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52847/tests/dromaeo/cssquery-yui.html) 14:38:23 INFO - PROCESS | 1967 | RSS: Main: 281923584 14:38:23 INFO - PROCESS | 1967 | 14:41:26 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:52847/tests/dromaeo/cssquery-yui.html (next: http://localhost:52847/tests/dromaeo/cssquery-dojo.html) 14:41:26 INFO - PROCESS | 1967 | RSS: Main: 283095040 14:41:26 INFO - PROCESS | 1967 | 14:41:26 INFO - PROCESS | 1967 | __start_tp_report 14:41:26 INFO - PROCESS | 1967 | _x_x_mozilla_page_load 14:41:26 INFO - PROCESS | 1967 | _x_x_mozilla_page_load_details 14:41:26 INFO - PROCESS | 1967 | |i|pagename|runs| 14:41:26 INFO - PROCESS | 1967 | |0;dojo.html;2842.573613957906;3437.9484307735374;3473.0101920952516;3479.9304013919723;3483;5686.2039314496005;5754.827355179346;5812.389699081599;5834.970825145873;5843.415658434165;5635.887282254353;5769.769209231626;5808.767649294034;5835.153902684108;5912.763489460427;4431.512533621299;4944.604431645478;5246.74078221228;5273.789048438058;5303.549198318128;388.2870320712215;4490.7530085845265;5125.436202017775;5243.2135179723155;5254.4220135785035;5189.948100519003;5191.117510023268;5194.272801807769;5244.527992480657;5284.91659209863;5200.323957885488;5214.921776173361;5252.264682944391;5293.206019097166;5296.841094767163;5086.906315142225;5136.5890728741615;5192.92210616841;5222.0861349264105;5257.579393648499;5506.559475241971;5636.49271565557;5638.577106717013;5641.069223578104;5720.8855822883725;6316.652584107872;6323.6521991290465;6324.525660575429;6330.366963303679;6354.205724284465;5630;6210.1616281801835;6222.253329600477;6260.248770147611;6289.433950944437;5580.748866300985;5922.170896074553;5927.911081333784;5932.466078052996;5950.791722289742;6126.426068710427;6297.96851015742;6304;6314.431701146918;6316.873662526724;6109.769069418392;6114.145302971568;6135.386461353829;6185.41238582334;6213.409726076015;6218.22272215973;6219.440250377488;6232.158658581124;6294.905576416358;6308.968455157695;6180.598261113013;6194.938050619527;6280.371962803774;6292.182016337847;6296.8425789354715;6246.250449945945;6286.277078136073;6292.685365731695;6319.58922670025;6331.810045698637;4933.481984391659;4952.603791696655;4958.677685950402;4959.90080198394;4965.131102057126;4367.868963931087;4380.846670366522;4435.645148388121;4492.685512014127;4519.028408892038;5828.417158284223;5861.501772349398;5864.530837532987;5882.617629854046;5904.645721256738;6239.218084286613;6273.7176827042895;6285.402886725845;6297.4647154992335;6370.490360771128;5767.826965191051;5916.940830591725;5974.4324289192455;5992.700364981733;6007.609505382136;5458.099413596791;5480.205370221295;5486.369067557282;5487.807926722546;5496.6701997880255;6451.967740161269;6457.773977910844;6467.547271691031;6474.9316362800555;6484.221893372825;6204.937950620617;6210.254769427697;6229.719662615103;6235.532335074797;6244.1570387997035;6406.263279722892;6436.195475565554;6441.71012304438;6446.033095035782;6452.128962590178;6288.27684816252;6295.276043254901;6298.307186209605;6302.117703521602;6306.054091886253;4932.63005274613;5631.8592035199445;6169.259688837368;6178;6278.121063051085;4583.702059366131;6150.753969841336;6216.627002379872;6217.689115544295;6225.346338634378;4580.519045500308;6126.886224252626;6189.690515474118;6225.3774622537385;6228.128062071404;5938.703064846654;6023.1266466363;6067.362926892612;6124.8775024500155;6143.6928153593035;6185.381461853779;6236.503239222645;6238.126662267196;6273;6276.874462510816;5927.4665280125;6064.181335519648;6135.6932153393045;6156.445919867234;6160.44555989963 14:41:26 INFO - PROCESS | 1967 | |1;ext.html;519.1952473665817;522.107196693654;524.8294304351086;530.9752178295887;553.7784886045583;37600.87197384076;41422.58577414225;41560;41597.168056638846;41649.37525937113;41732.58267417325;42204.57795422045;42252.788736056275;42275.78862105693;42343.78828105863;9637.373570717898;9826.361286516389;9831.213502919785;9845.55694993727;9845.704628861145;9711.417314961089;9780.608775648965;9817.607295708163;9829.21366290695;9831.213502919749;9841.655542056029;9866.358686685378;9871.60513579456;9874.358166719176;9877.60489580416;7554.357879580242;9776.217902567778;9816.460094694788;9850.359726617748;10200.540975656077;9650.276229282832;9678.032196780336;9712.320137590372;9723.222142228604;9764.755881102958;39153.412698809254;39316;39441;39555.208895821925;39681;2838.4606924684244;3002.4745669507747;3045.4213699397264;3405.4551271796395;3594.19130695594;27093.458130837273;27675.72324276772;27768.58347124795;28106;29106.41787164245;2304.2396009317054;2617.267165193749;2641.8943242270284;2659.7074321824584;2691.2329985954107;448586;454335;455042;456893;458198;29327.266818329714;34320;34627.4805877912;34673.47989780105;34903.12742181466;35211.82394088065;35471;35604.109897252776;35688.64311356905;35706.82146589302;15165.469208577868;15204.695906081817;15332.923335383251;15353.925225234127;15400.691986160215;12857.935710321575;12911.677208069874;12932.547360842325;13014.804777928339;13026.153300035467;7576.128745194263;8151.30713889326;8174.386920980831;8215.589220539068;8248.628811703487;3183.1882869868036;3415.624281329052;3438.3123375325035;3450.741194410379;3474.357243909885;972.9805403891883;983.852422136671;992.7617371830709;1058.9470526473644;1109.2734259060167;23599.646005309933;23799.167029153898;24423.87788061084;28603;29617.703822961925;1000.9849852252222;1087.7117563845586;1100.7248187953012;1107.4020029184312;1131.8981291683788;15137.167455790037;15244.466443674419;15285.770713439308;15669;15733.213339333217;674159;678318;683235;684537;686714;57932.13101803392;58631;59265.111023334684;59301;59415.40584594185;1378.3590630356932;1427.6716355238145;1465.142891408538;1466.171613038651;1469.4563011685746;24410.389740255927;29376.853115734284;29504;29756.85121574465;30022.048566843085;50972.490275096774;51217;51317.48682513127;51542;51556;562089;564807;569811;570309;571529;26350;26595;26992.865035674695;27850.16449506518;28294.575581365883;45174.09651807011;45508.54491455043;45666.315005274286;46121.53878461307;46318.53681463142;30301;37374.6262537382;37835.05412364713;37933.24133517259;38929;37146.8142659285;37243.81378093092;37786;38002.619973799905;38303.233935321696;36600.45099323459;37183.81408092942;37241;37714.622853772205;38295.6170438292;31437.842810785798;31937.840310798296;32824.83587582047;32827.835860820545;32847.178820529676;24428.75571244265;24742.38144046413;24801.651777009873;24963.875180624706;25118.874405628587 14:41:26 INFO - PROCESS | 1967 | |2;jquery.html;327.5545258448508;397.37016828327097;397.4197671399757;409.114267610623;418.53751604477054;11821.17251792375;18931.52671183221;21102.472438189052;21127.683084753742;21179.788202117976;22276.777232227672;22474.66288005681;22963.540729185406;23339.883300583515;23419.41451463714;6349.523785716066;6871.725130994754;7698.153203147649;7721.266479684421;7797.98626178596;7356.154042285126;7945.205479452066;8012.158723334053;8027.759167224993;8047.557384343859;7572.242775722439;7908.565028923407;7951.403644726669;7958.840823183533;8012.479188852706;7100.792865212926;7590.3548198403205;7778.8833167502535;7801.542514108094;7914.920850791476;7101.964490177516;7664.961675191644;7678.577678227695;7680.692772289158;7709.7301594443925;23950.401239968967;26102.086426974965;28905.56641650377;29792;30146.397072058655;2106.557622899193;2207.746109197447;2246.6854640350366;2277.237125562938;2429.5748244057395;16667.91666041674;16910.323587056504;17109.914450427794;17179.055151966637;17298.87557308771;1744.700198352223;1771.4422044614116;1888.3107665702057;1963.0577322885094;1991.4822146241936;101294.49352753263;109385;123277;134681;146424;17523.386681466087;17739.93560386381;21322.040508177168;22232.555348892933;22512;36533.817330913706;36731.4490282646;36754.8162259187;36796.63203367932;36850.26299474049;9143.497107659143;10895.83656245157;11138.888611113785;11314.434278286053;11327.433628318551;9161.175494205554;9173.724788256362;9204.631814727336;9227.031161727922;9260.629574817067;9961.601535938626;10086.344387614781;10128.214740946152;10267.691969240936;10276.537555810006;4015.297322968527;4023.436718859362;4062.857799976987;4070.2673518766314;4109.630133287958;886.7738726624669;951.6859436385976;957.593022965237;959.1032384720309;1045.2526443592715;19656;19803.900980495004;20921.686174707393;20925.790742092384;21038.42211834088;966.7631430299481;972.3436680240809;999.4353190447374;1003.1373019203479;1021.7292417509366;10733.892661073445;10885.455727213608;10948.726281843017;11151.776964460665;11241.437928103562;493202;498383;498738;603026;603861;141859.29070354582;147181;150019;150544;156797.43202567828;842.7219017724136;1031.5564307347913;1077.4343469678481;1079.7624522605015;1087.0977089016098;18984.71522927185;19351;19360;19369.51576210606;19615.411537653894;56510.434895652164;57488;57875.710621446626;58097.838043239746;60836;75831.62084189543;80521.59739201267;82802;84269;85236.31200283796;7042.507024508235;7422.035135432575;7523.661435235318;7619.7714068575815;8481.78795530092;11050;11300.604478843201;11927.522899084286;12940.870591293966;13137.737245255232;9530.952345238507;9676.75808104803;10315.658964334589;11200.10399168081;11669.88330116688;10341.9482902585;10720.303180293256;10840.186985976241;10847.728806779895;11407.600733974272;11201.38392388394;11607.535698571963;11669.88330116688;11818.704532386759;11826.231294965799;6536.182977127859;7485.1766305707415;7590.20302868213;7595.392368610608;7678.924950507046;8443.113473085483;10534.36793792375;10719.517621706886;11289.887101128883;11642.88357116418 14:41:26 INFO - PROCESS | 1967 | |3;mootools.html;1612.7742116103748;1656.0560480526096;1874.8031456697056;1932.903354832258;2002.1390801955154;8290.253877151055;8397.740338949161;8837.690680826172;9384.906150938488;9440.244780417568;7661.348785353238;8624.741257762262;8891.244244239231;9134;9151.267898568116;2136.6154092263346;2424.59994100974;2461.089396923141;2461.9753802462014;2466.6300054991716;2360.7993320567684;2422.358075110097;2467.1365022242253;2474.64117702933;2474.7896428803574;2438.51229754049;2443.59680652693;2454.631805229212;2459.3974476253343;2485.5774518331837;2423.260905423845;2435.476372579895;2444.706635203778;2447.5839107351794;2454.4968281502247;2400.603900356439;2409.325388891113;2419.0807493152665;2433.1119141513395;2451.6322551617222;9048.185663290336;9121.81756364869;9155.313351498598;9193;9222.031686672937;5441.727913604304;5563.777448902039;5617.21359009739;5620.5503559715125;5627.6904770237625;3587.6232995535356;3621.9275614487824;3635.0730563706084;3686.1706116123923;3693.261347730465;1008.4503945349728;1055.5250137438184;1057.4501259345175;1059.6026490066224;1064.4837253931837;12054;12179.756404871941;12182.086343524272;12204.572839950559;12214.450349734283;4875.975620121877;4878.292647566083;4973.875653108701;4978.726170060681;5001.549860512571;10243.385396876061;10255.58977640901;10302.845457318146;10381.169506439466;10391.792164156675;5279.1817268323475;5299.5230429261555;5350.197470379397;5357.142857142839;5575.637583557056;4536.750478723702;4915.631327650441;5005.449400565934;5090.49095090488;5111.131107711698;4126.504819421629;4151.27352713274;4182.853600123981;4195.160967806451;4217.768022758716;1341.3293353323338;1363.754524185656;1366.296357375952;1370.3011464153353;1370.6641872741043;758.6665739848871;858.1675774498726;862.2627653356416;959.6545243712258;979.2031621973758;6059.818205453844;6187.84530386744;6209.9379006210265;6229.127922090911;6275.497960163176;463.9025804581076;468.8195044907667;486.7809485731435;487.1572180128398;487.52222821634996;3544.3088597723195;4035.959640403617;4090.959090409058;4116.958830411718;4176.352665336878;9036.728898132935;10826.729331766768;11145.331280123057;11202.719932001764;11204.383758893338;9426.245900328095;11049.28179668319;11094.500747466222;11137.610183643534;11176.66470005885;7023.578585284899;8513.74458766238;8519.06290308078;8590.785230369165;8613.310935125175;5937.643741375531;5985.072313791369;5986.2517185351835;6118.326984031664;6208.720607572761;9953.552090155816;10285.588576456857;10397.948010260203;10399.32404393712;10444.68665940023;10591.523381448007;10670.946645267035;10675.946620266848;10680.359178449318;10693.518791654238;6803.89794153098;7023.824404389931;7742.806429839299;7772.37820974332;7967.442279040411;8626.39615226928;8636.827263454821;8682.262007729161;8716.60775265102;8732.301415886586;7577.052868391451;7656.234376562299;7735.22647735222;7826.2173782621285;7846.686132554634;7459.925400745923;7500.624968751432;7524.510906791041;7632.274933881273;7673.271039251262;7173.24680908518;7232.987381766452;7286.380657644159;7291.489595728248;7307.525010874276;4913.877153071202;5061.4938506150565;5150.356205474316;5155.845324640267;5169.844904652867;4585.679002469795;5124.666896651705;5135.435102138837;5197.7661005254095;5307.6284660073425 14:41:26 INFO - PROCESS | 1967 | |4;prototype.html;306.65654466996966;315.136525918982;319.7378149917067;324.31083946613444;328.95559099521574;5242.973785131078;5380.300560927084;5561.91657125143;5661.405552416999;5726.3414707308675;4486.573775491323;5568.136938774496;5605.495505404523;5628.043232650449;5629.465200805927;3733.5893051764283;3949.499190307679;4195.706370499159;4221;4254.46819147605;3407.177160219283;4126.700089471807;4241.957580424187;4262.65898728101;4265.53079161292;3645.3802853514967;3988.4814974053334;4150.979245103786;4157.38085006447;4188.72773269738;3191.3298207376247;3386.2899235885843;3640.344737947195;4182.309918863369;4214.4099826024385;3526.6297038811067;3656.4698118772894;3662.6520480554304;3669.3028324618504;3671.6695497405362;4489.595936365743;4532.8866778330475;4812.302216178635;5200.609954253408;5354.732263386854;4083.3874918762126;4596.701214421086;4721.905561888743;4760.143174228638;4834.468208497063;5806.88386232273;5849.596096936747;5892.58577941291;5926.199963004982;5937.495312898409;3752.8311225994894;4001.5194378080005;4006.7395619284653;4014.7992600369867;4037.2329257440992;5599.916001259984;5757.280339957505;5774.076147816371;6037.4566289033755;6134.539909506805;3331.750118741104;4024.8591299304385;4063.817128229237;4065.308897487375;4103.692223083281;3594.388953877847;4174.497181014833;4193.30810416284;4201.831926722897;4228.260054490452;4150.667946564267;4458.509563947963;4802.559232230317;4830.550834749561;4863.878403039952;4821.855344339676;5877.617954832922;5884.882302353929;5891.705414729332;5937.287525496881;1180.1797750563276;1416.7733162694126;1417.8794802441907;1423.2243427332128;1431.7566013777682;748.7346384609991;778.1479280188184;818.3902992270796;823.4194892600822;858.3862538285031;3958.5447673517338;4104.56902025289;4547.63618910493;4570.006645647107;4597.42532183477;4371.497277813029;4702.741349225757;5167.948320516747;5172.534471897472;5189.532942035159;4142.668586513072;4562.448767419093;4584.793684284148;4655.301704744248;4676.87754938819;2394.473215892501;2970.5247160454223;3020.9728692244744;3209.630892447352;3214.646388897266;5497.340319161646;5743.569232307594;5883.794067207627;6341.524385671093;6342.904856427157;5646.805477321565;6106.877862442726;6249.625202390658;6266.553395632475;6284.12022316876;5593.664380137205;5734.913976290359;5835.299764028344;6171.796499682699;6372.139761132281;5151.613628977917;5683.346415162309;6031.336552979256;6059.333893087028;6123.234595675541;5327.256462921521;5386.5152136307925;6033.607815491979;6097.237845269341;6097.634141951497;5281.815136470205;5636;5840.240768700041;6002.358962056681;6412.487001039999;3107.238726512019;3367.161435963053;3964.821583028713;3973.7019723520275;4044.3124668806963;5096.796128154832;5543.8891222176135;5677.744501497526;6146.661933593696;6250.0312401593355;4959.958408734062;5238;5500.569851838631;5531.668099914018;5956.315023772322;4949.034938187019;5468.671879687232;5792.536597072309;5947.791827286197;5951.67884525027;5241.371035475768;5424;5703.828885133453;5786.714247872151;5800.7969721061245;5632.859178520406;5908.813738916629;5909.645421274738;5936;5947.583669143119;4601.907961840811;5078;5200.947990520046;5213.687178769286;5234.953009398212 14:41:26 INFO - PROCESS | 1967 | |5;yui.html;658.1148355461902;676.28989560961;699.167990091791;705.3581241070624;729.2816575672963;15124.016938898978;15334.846651533482;15433.536993890188;15604.53186404407;15651.373945042185;14068.929655351734;14381.784273235884;14446.494372696958;14460.276986150677;14480.855191448083;919.8850143732034;927.2952556057378;934.700895713372;941.7975135345899;941.9764505887339;740.1303468424607;912.4479689787682;939.6899023322321;939.9342046056779;944.0370821761799;978.1392374710246;989.9109080182782;990.4107056301489;992.9751756206081;994.1102713071797;961.8316794560959;967.666155176463;984.1044649369111;985.6254623243158;987.8616993620899;735.7572001239578;736.0321177651372;736.7237286017744;736.7716008037526;737.7712908998174;14897.180655063967;14997.925010374987;15004.849951500455;15016.549503514912;15023.849761502355;10702.892971070267;11692.181547291693;12544.24734515932;12692.238465691995;12842.357882105951;8928.196462318358;10026.448545330004;10031.832921786146;10121.746956326151;10155.847662285072;2878.6977367376335;3477.443609022544;3497.2131270464197;3515.1212196950764;3543.8050907200095;71611.99999999948;85269.57365213196;86851.56574217152;86918.5654071732;87678.5616071922;7905.723299684599;8396.916030839613;8836.955815221012;9446.008169142278;9685.41887486753;22154.778452215593;22206.66689999651;22470.550588988455;22838.657420138712;22871.428214294443;7312.634368281565;7379.557226566423;7395.307395307353;7469;7627.69489220436;3941.763494190358;4008.518977722692;4013.5986401360215;4024.8188831502653;4029.133736246721;3505.4040813061843;3611.94582081269;3620.0225938996323;3621.8913432597064;3624.2751449710163;611.6635850282327;630.4168644004369;634.1059106659587;641.9743210271537;643.5302229372584;477.91397548441614;559.9300087489064;574.2506029631288;595.5890435599423;616.3251239892311;7799.766007019798;7964.681412743425;8104.5137291762685;8331.875021874677;8519.787005324917;541.3747122074011;574.9248904548438;581.0992960910656;594.0643238544642;616.2297128589263;3798.639129282714;3882.747621404656;3970.086880017612;3989.7207195496617;4026.315526360526;78768.84924602952;99911;100071;102439.48780256051;102553.97446025444;67300.6634966822;79330.6033469829;81962;84878.15121848826;85925.57037214898;75062;75067.24932750603;75569;77361.2263877354;77786.61106694544;7732.8453430932195;7737.613119344122;7908.090569584571;7934.920650793649;7967.641456134372;46117.76941115273;47285.2907206399;47549.294084710054;48190.75904620454;48274.793130172024;53127.468725312254;64162.358376417505;67513.66243168752;67663.66168169127;70155.29844701488;8780.604872780612;10107.241956853413;10236.436995965294;10288.691339259834;10295.691129266133;11274.267172633752;11287.830682539932;11667.708307292385;11975.28148311104;12050.939745301217;8014.559199244098;10484.580616775243;10633.361998280128;10886.455677216265;11012.559497620005;10585.788284234426;10659.040686338267;10775.030247277782;10889.074428673652;10898.727531811768;10365.118964888068;10416.062554370143;10428.217883658604;10437.634682786065;10780.29928054674;7569.962150189213;8879.644814207617;8936.783173859874;8957.422141282743;9234.307426942873;8934.821303574023;8963.507007114671;9349.766255843659;9397.403218097752;9590.232781377612 14:41:26 INFO - PROCESS | 1967 | __end_tp_report 14:41:26 INFO - PROCESS | 1967 | __start_cc_report 14:41:26 INFO - PROCESS | 1967 | _x_x_mozilla_cycle_collect,414 14:41:26 INFO - PROCESS | 1967 | __end_cc_report 14:41:26 INFO - PROCESS | 1967 | __startTimestamp1463175686687__endTimestamp 14:41:26 INFO - PROCESS | 1967 | 14:41:26 INFO - PROCESS | 1967 | ------- Summary: start ------- 14:41:26 INFO - PROCESS | 1967 | Number of tests: 6 14:41:26 INFO - PROCESS | 1967 | 14:41:26 INFO - PROCESS | 1967 | [#0] dojo.html Cycles:165 Average:5734.95 Median:6023.13 stddev:806.02 (13.4%) stddev-sans-first:775.89 14:41:26 INFO - PROCESS | 1967 | Values: 2842.6 3437.9 3473.0 3479.9 3483.0 5686.2 5754.8 5812.4 5835.0 5843.4 5635.9 5769.8 5808.8 5835.2 5912.8 4431.5 4944.6 5246.7 5273.8 5303.5 388.3 4490.8 5125.4 5243.2 5254.4 5189.9 5191.1 5194.3 5244.5 5284.9 5200.3 5214.9 5252.3 5293.2 5296.8 5086.9 5136.6 5192.9 5222.1 5257.6 5506.6 5636.5 5638.6 5641.1 5720.9 6316.7 6323.7 6324.5 6330.4 6354.2 5630.0 6210.2 6222.3 6260.2 6289.4 5580.7 5922.2 5927.9 5932.5 5950.8 6126.4 6298.0 6304.0 6314.4 6316.9 6109.8 6114.1 6135.4 6185.4 6213.4 6218.2 6219.4 6232.2 6294.9 6309.0 6180.6 6194.9 6280.4 6292.2 6296.8 6246.3 6286.3 6292.7 6319.6 6331.8 4933.5 4952.6 4958.7 4959.9 4965.1 4367.9 4380.8 4435.6 4492.7 4519.0 5828.4 5861.5 5864.5 5882.6 5904.6 6239.2 6273.7 6285.4 6297.5 6370.5 5767.8 5916.9 5974.4 5992.7 6007.6 5458.1 5480.2 5486.4 5487.8 5496.7 6452.0 6457.8 6467.5 6474.9 6484.2 6204.9 6210.3 6229.7 6235.5 6244.2 6406.3 6436.2 6441.7 6446.0 6452.1 6288.3 6295.3 6298.3 6302.1 6306.1 4932.6 5631.9 6169.3 6178.0 6278.1 4583.7 6150.8 6216.6 6217.7 6225.3 4580.5 6126.9 6189.7 6225.4 6228.1 5938.7 6023.1 6067.4 6124.9 6143.7 6185.4 6236.5 6238.1 6273.0 6276.9 5927.5 6064.2 6135.7 6156.4 6160.4 14:41:26 INFO - PROCESS | 1967 | 14:41:26 INFO - PROCESS | 1967 | [#1] ext.html Cycles:180 Average:67868.93 Median:37657.75 stddev:154371.77 (409.9%) stddev-sans-first:154722.00 14:41:26 INFO - PROCESS | 1967 | Values: 519.2 522.1 524.8 531.0 553.8 37600.9 41422.6 41560.0 41597.2 41649.4 41732.6 42204.6 42252.8 42275.8 42343.8 9637.4 9826.4 9831.2 9845.6 9845.7 9711.4 9780.6 9817.6 9829.2 9831.2 9841.7 9866.4 9871.6 9874.4 9877.6 7554.4 9776.2 9816.5 9850.4 10200.5 9650.3 9678.0 9712.3 9723.2 9764.8 39153.4 39316.0 39441.0 39555.2 39681.0 2838.5 3002.5 3045.4 3405.5 3594.2 27093.5 27675.7 27768.6 28106.0 29106.4 2304.2 2617.3 2641.9 2659.7 2691.2 448586.0 454335.0 455042.0 456893.0 458198.0 29327.3 34320.0 34627.5 34673.5 34903.1 35211.8 35471.0 35604.1 35688.6 35706.8 15165.5 15204.7 15332.9 15353.9 15400.7 12857.9 12911.7 12932.5 13014.8 13026.2 7576.1 8151.3 8174.4 8215.6 8248.6 3183.2 3415.6 3438.3 3450.7 3474.4 973.0 983.9 992.8 1058.9 1109.3 23599.6 23799.2 24423.9 28603.0 29617.7 1001.0 1087.7 1100.7 1107.4 1131.9 15137.2 15244.5 15285.8 15669.0 15733.2 674159.0 678318.0 683235.0 684537.0 686714.0 57932.1 58631.0 59265.1 59301.0 59415.4 1378.4 1427.7 1465.1 1466.2 1469.5 24410.4 29376.9 29504.0 29756.9 30022.0 50972.5 51217.0 51317.5 51542.0 51556.0 562089.0 564807.0 569811.0 570309.0 571529.0 26350.0 26595.0 26992.9 27850.2 28294.6 45174.1 45508.5 45666.3 46121.5 46318.5 30301.0 37374.6 37835.1 37933.2 38929.0 37146.8 37243.8 37786.0 38002.6 38303.2 36600.5 37183.8 37241.0 37714.6 38295.6 31437.8 31937.8 32824.8 32827.8 32847.2 24428.8 24742.4 24801.7 24963.9 25118.9 14:41:26 INFO - PROCESS | 1967 | 14:41:26 INFO - PROCESS | 1967 | [#2] jquery.html Cycles:180 Average:36011.24 Median:23684.91 stddev:91668.41 (387.0%) stddev-sans-first:91886.41 14:41:26 INFO - PROCESS | 1967 | Values: 327.6 397.4 397.4 409.1 418.5 11821.2 18931.5 21102.5 21127.7 21179.8 22276.8 22474.7 22963.5 23339.9 23419.4 6349.5 6871.7 7698.2 7721.3 7798.0 7356.2 7945.2 8012.2 8027.8 8047.6 7572.2 7908.6 7951.4 7958.8 8012.5 7100.8 7590.4 7778.9 7801.5 7914.9 7102.0 7665.0 7678.6 7680.7 7709.7 23950.4 26102.1 28905.6 29792.0 30146.4 2106.6 2207.7 2246.7 2277.2 2429.6 16667.9 16910.3 17109.9 17179.1 17298.9 1744.7 1771.4 1888.3 1963.1 1991.5 101294.5 109385.0 123277.0 134681.0 146424.0 17523.4 17739.9 21322.0 22232.6 22512.0 36533.8 36731.4 36754.8 36796.6 36850.3 9143.5 10895.8 11138.9 11314.4 11327.4 9161.2 9173.7 9204.6 9227.0 9260.6 9961.6 10086.3 10128.2 10267.7 10276.5 4015.3 4023.4 4062.9 4070.3 4109.6 886.8 951.7 957.6 959.1 1045.3 19656.0 19803.9 20921.7 20925.8 21038.4 966.8 972.3 999.4 1003.1 1021.7 10733.9 10885.5 10948.7 11151.8 11241.4 493202.0 498383.0 498738.0 603026.0 603861.0 141859.3 147181.0 150019.0 150544.0 156797.4 842.7 1031.6 1077.4 1079.8 1087.1 18984.7 19351.0 19360.0 19369.5 19615.4 56510.4 57488.0 57875.7 58097.8 60836.0 75831.6 80521.6 82802.0 84269.0 85236.3 7042.5 7422.0 7523.7 7619.8 8481.8 11050.0 11300.6 11927.5 12940.9 13137.7 9531.0 9676.8 10315.7 11200.1 11669.9 10341.9 10720.3 10840.2 10847.7 11407.6 11201.4 11607.5 11669.9 11818.7 11826.2 6536.2 7485.2 7590.2 7595.4 7678.9 8443.1 10534.4 10719.5 11289.9 11642.9 14:41:26 INFO - PROCESS | 1967 | 14:41:26 INFO - PROCESS | 1967 | [#3] mootools.html Cycles:180 Average:5870.05 Median:4896.08 stddev:3308.00 (67.6%) stddev-sans-first:3301.81 14:41:26 INFO - PROCESS | 1967 | Values: 1612.8 1656.1 1874.8 1932.9 2002.1 8290.3 8397.7 8837.7 9384.9 9440.2 7661.3 8624.7 8891.2 9134.0 9151.3 2136.6 2424.6 2461.1 2462.0 2466.6 2360.8 2422.4 2467.1 2474.6 2474.8 2438.5 2443.6 2454.6 2459.4 2485.6 2423.3 2435.5 2444.7 2447.6 2454.5 2400.6 2409.3 2419.1 2433.1 2451.6 9048.2 9121.8 9155.3 9193.0 9222.0 5441.7 5563.8 5617.2 5620.6 5627.7 3587.6 3621.9 3635.1 3686.2 3693.3 1008.5 1055.5 1057.5 1059.6 1064.5 12054.0 12179.8 12182.1 12204.6 12214.5 4876.0 4878.3 4973.9 4978.7 5001.5 10243.4 10255.6 10302.8 10381.2 10391.8 5279.2 5299.5 5350.2 5357.1 5575.6 4536.8 4915.6 5005.4 5090.5 5111.1 4126.5 4151.3 4182.9 4195.2 4217.8 1341.3 1363.8 1366.3 1370.3 1370.7 758.7 858.2 862.3 959.7 979.2 6059.8 6187.8 6209.9 6229.1 6275.5 463.9 468.8 486.8 487.2 487.5 3544.3 4036.0 4091.0 4117.0 4176.4 9036.7 10826.7 11145.3 11202.7 11204.4 9426.2 11049.3 11094.5 11137.6 11176.7 7023.6 8513.7 8519.1 8590.8 8613.3 5937.6 5985.1 5986.3 6118.3 6208.7 9953.6 10285.6 10397.9 10399.3 10444.7 10591.5 10670.9 10675.9 10680.4 10693.5 6803.9 7023.8 7742.8 7772.4 7967.4 8626.4 8636.8 8682.3 8716.6 8732.3 7577.1 7656.2 7735.2 7826.2 7846.7 7459.9 7500.6 7524.5 7632.3 7673.3 7173.2 7233.0 7286.4 7291.5 7307.5 4913.9 5061.5 5150.4 5155.8 5169.8 4585.7 5124.7 5135.4 5197.8 5307.6 14:41:26 INFO - PROCESS | 1967 | 14:41:26 INFO - PROCESS | 1967 | [#4] prototype.html Cycles:180 Average:4600.72 Median:5087.40 stddev:1453.15 (28.6%) stddev-sans-first:1421.03 14:41:26 INFO - PROCESS | 1967 | Values: 306.7 315.1 319.7 324.3 329.0 5243.0 5380.3 5561.9 5661.4 5726.3 4486.6 5568.1 5605.5 5628.0 5629.5 3733.6 3949.5 4195.7 4221.0 4254.5 3407.2 4126.7 4242.0 4262.7 4265.5 3645.4 3988.5 4151.0 4157.4 4188.7 3191.3 3386.3 3640.3 4182.3 4214.4 3526.6 3656.5 3662.7 3669.3 3671.7 4489.6 4532.9 4812.3 5200.6 5354.7 4083.4 4596.7 4721.9 4760.1 4834.5 5806.9 5849.6 5892.6 5926.2 5937.5 3752.8 4001.5 4006.7 4014.8 4037.2 5599.9 5757.3 5774.1 6037.5 6134.5 3331.8 4024.9 4063.8 4065.3 4103.7 3594.4 4174.5 4193.3 4201.8 4228.3 4150.7 4458.5 4802.6 4830.6 4863.9 4821.9 5877.6 5884.9 5891.7 5937.3 1180.2 1416.8 1417.9 1423.2 1431.8 748.7 778.1 818.4 823.4 858.4 3958.5 4104.6 4547.6 4570.0 4597.4 4371.5 4702.7 5167.9 5172.5 5189.5 4142.7 4562.4 4584.8 4655.3 4676.9 2394.5 2970.5 3021.0 3209.6 3214.6 5497.3 5743.6 5883.8 6341.5 6342.9 5646.8 6106.9 6249.6 6266.6 6284.1 5593.7 5734.9 5835.3 6171.8 6372.1 5151.6 5683.3 6031.3 6059.3 6123.2 5327.3 5386.5 6033.6 6097.2 6097.6 5281.8 5636.0 5840.2 6002.4 6412.5 3107.2 3367.2 3964.8 3973.7 4044.3 5096.8 5543.9 5677.7 6146.7 6250.0 4960.0 5238.0 5500.6 5531.7 5956.3 4949.0 5468.7 5792.5 5947.8 5951.7 5241.4 5424.0 5703.8 5786.7 5800.8 5632.9 5908.8 5909.6 5936.0 5947.6 4601.9 5078.0 5200.9 5213.7 5235.0 14:41:26 INFO - PROCESS | 1967 | 14:41:26 INFO - PROCESS | 1967 | [#5] yui.html Cycles:180 Average:18414.82 Median:616.28 stddev:26650.69 (4324.5%) stddev-sans-first:26692.10 14:41:26 INFO - PROCESS | 1967 | Values: 658.1 676.3 699.2 705.4 729.3 15124.0 15334.8 15433.5 15604.5 15651.4 14068.9 14381.8 14446.5 14460.3 14480.9 919.9 927.3 934.7 941.8 942.0 740.1 912.4 939.7 939.9 944.0 978.1 989.9 990.4 993.0 994.1 961.8 967.7 984.1 985.6 987.9 735.8 736.0 736.7 736.8 737.8 14897.2 14997.9 15004.8 15016.5 15023.8 10702.9 11692.2 12544.2 12692.2 12842.4 8928.2 10026.4 10031.8 10121.7 10155.8 2878.7 3477.4 3497.2 3515.1 3543.8 71612.0 85269.6 86851.6 86918.6 87678.6 7905.7 8396.9 8837.0 9446.0 9685.4 22154.8 22206.7 22470.6 22838.7 22871.4 7312.6 7379.6 7395.3 7469.0 7627.7 3941.8 4008.5 4013.6 4024.8 4029.1 3505.4 3611.9 3620.0 3621.9 3624.3 611.7 630.4 634.1 642.0 643.5 477.9 559.9 574.3 595.6 616.3 7799.8 7964.7 8104.5 8331.9 8519.8 541.4 574.9 581.1 594.1 616.2 3798.6 3882.7 3970.1 3989.7 4026.3 78768.8 99911.0 100071.0 102439.5 102554.0 67300.7 79330.6 81962.0 84878.2 85925.6 75062.0 75067.2 75569.0 77361.2 77786.6 7732.8 7737.6 7908.1 7934.9 7967.6 46117.8 47285.3 47549.3 48190.8 48274.8 53127.5 64162.4 67513.7 67663.7 70155.3 8780.6 10107.2 10236.4 10288.7 10295.7 11274.3 11287.8 11667.7 11975.3 12050.9 8014.6 10484.6 10633.4 10886.5 11012.6 10585.8 10659.0 10775.0 10889.1 10898.7 10365.1 10416.1 10428.2 10437.6 10780.3 7570.0 8879.6 8936.8 8957.4 9234.3 8934.8 8963.5 9349.8 9397.4 9590.2 14:41:26 INFO - PROCESS | 1967 | -------- Summary: end -------- 14:41:26 INFO - PROCESS | 1967 | 14:41:27 INFO - TEST-INFO | 1967: exit 0 14:41:27 INFO - TEST-OK | dromaeo_css | took 1112104ms 14:41:27 INFO - TEST-START | kraken 14:41:27 INFO - operating with platform_type : mac_ 14:41:27 INFO - Initialising browser for kraken test... 14:41:27 INFO - TEST-INFO | started process 1974 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/tmpQyonl4/profile http://localhost:52847/getInfo.html) 14:41:30 INFO - PROCESS | 1974 | __metrics Screen width/height:1600/1200 14:41:30 INFO - PROCESS | 1974 | colorDepth:24 14:41:30 INFO - PROCESS | 1974 | Browser inner width/height: 1024/689 14:41:30 INFO - PROCESS | 1974 | __metrics 14:41:30 ERROR - PROCESS | 1974 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 14:41:30 INFO - TEST-INFO | 1974: exit 0 14:41:30 INFO - Browser initialized. 14:41:30 INFO - Running cycle 1/1 for kraken test... 14:41:30 INFO - TEST-INFO | started process 1977 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/tmpQyonl4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:41:42 INFO - PROCESS | 1977 | RSS: Main: 250101760 14:41:42 INFO - PROCESS | 1977 | 14:41:45 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:52847/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52847/tests/kraken/driver.html?testName=audio-beat-detection) 14:41:46 INFO - PROCESS | 1977 | RSS: Main: 261767168 14:41:46 INFO - PROCESS | 1977 | 14:41:49 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:52847/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52847/tests/kraken/driver.html?testName=audio-dft) 14:41:50 INFO - PROCESS | 1977 | RSS: Main: 266469376 14:41:50 INFO - PROCESS | 1977 | 14:41:53 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:52847/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52847/tests/kraken/driver.html?testName=audio-fft) 14:41:54 INFO - PROCESS | 1977 | RSS: Main: 267288576 14:41:54 INFO - PROCESS | 1977 | 14:41:56 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:52847/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52847/tests/kraken/driver.html?testName=audio-oscillator) 14:41:56 INFO - PROCESS | 1977 | RSS: Main: 268054528 14:41:56 INFO - PROCESS | 1977 | 14:41:59 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:52847/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52847/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:41:59 INFO - PROCESS | 1977 | RSS: Main: 268038144 14:41:59 INFO - PROCESS | 1977 | 14:42:02 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:52847/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52847/tests/kraken/driver.html?testName=imaging-darkroom) 14:42:03 INFO - PROCESS | 1977 | RSS: Main: 267927552 14:42:03 INFO - PROCESS | 1977 | 14:42:06 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:52847/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52847/tests/kraken/driver.html?testName=imaging-desaturate) 14:42:07 INFO - PROCESS | 1977 | RSS: Main: 268107776 14:42:07 INFO - PROCESS | 1977 | 14:42:10 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:52847/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52847/tests/kraken/driver.html?testName=json-parse-financial) 14:42:11 INFO - PROCESS | 1977 | RSS: Main: 268640256 14:42:11 INFO - PROCESS | 1977 | 14:42:13 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:52847/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52847/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:42:13 INFO - PROCESS | 1977 | RSS: Main: 268230656 14:42:13 INFO - PROCESS | 1977 | 14:42:15 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:52847/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52847/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:42:16 INFO - PROCESS | 1977 | RSS: Main: 268349440 14:42:16 INFO - PROCESS | 1977 | 14:42:18 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:52847/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52847/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:42:19 INFO - PROCESS | 1977 | RSS: Main: 268623872 14:42:19 INFO - PROCESS | 1977 | 14:42:22 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:52847/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52847/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:42:22 INFO - PROCESS | 1977 | RSS: Main: 268529664 14:42:22 INFO - PROCESS | 1977 | 14:42:25 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:52847/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52847/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:42:26 INFO - PROCESS | 1977 | RSS: Main: 268500992 14:42:26 INFO - PROCESS | 1977 | 14:42:28 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:52847/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52847/tests/kraken/driver.html?testName=ai-astar) 14:42:28 INFO - PROCESS | 1977 | RSS: Main: 271142912 14:42:28 INFO - PROCESS | 1977 | 14:42:28 INFO - PROCESS | 1977 | __start_tp_report 14:42:28 INFO - PROCESS | 1977 | _x_x_mozilla_page_load 14:42:28 INFO - PROCESS | 1977 | _x_x_mozilla_page_load_details 14:42:28 INFO - PROCESS | 1977 | |i|pagename|runs| 14:42:28 INFO - PROCESS | 1977 | |0;ai-astar;109;115;108;115;117;114;97;170;169;130 14:42:28 INFO - PROCESS | 1977 | |1;audio-beat-detection;132;135;177;232;147;131;159;145;189;136 14:42:28 INFO - PROCESS | 1977 | |2;audio-dft;224;271;274;284;201;195;198;202;197;198 14:42:28 INFO - PROCESS | 1977 | |3;audio-fft;66;80;73;178;69;62;63;67;132;61 14:42:28 INFO - PROCESS | 1977 | |4;audio-oscillator;79;90;153;79;83;82;124;83;81;164 14:42:28 INFO - PROCESS | 1977 | |5;imaging-gaussian-blur;92;87;90;88;106;130;96;88;89;118 14:42:28 INFO - PROCESS | 1977 | |6;imaging-darkroom;95;96;150;97;93;95;142;95;96;100 14:42:28 INFO - PROCESS | 1977 | |7;imaging-desaturate;130;152;91;93;95;93;129;150;92;91 14:42:28 INFO - PROCESS | 1977 | |8;json-parse-financial;59;60;60;59;71;72;90;163;62;60 14:42:28 INFO - PROCESS | 1977 | |9;json-stringify-tinderbox;66;68;67;74;80;72;73;69;72;71 14:42:28 INFO - PROCESS | 1977 | |10;stanford-crypto-aes;84;88;89;88;77;88;113;111;111;95 14:42:28 INFO - PROCESS | 1977 | |11;stanford-crypto-ccm;137;145;136;135;141;136;137;135;217;143 14:42:28 INFO - PROCESS | 1977 | |12;stanford-crypto-pbkdf2;178;225;220;173;166;166;168;170;166;167 14:42:28 INFO - PROCESS | 1977 | |13;stanford-crypto-sha256-iterative;67;68;68;65;67;66;68;65;71;63 14:42:28 INFO - PROCESS | 1977 | __end_tp_report 14:42:28 INFO - PROCESS | 1977 | __start_cc_report 14:42:28 INFO - PROCESS | 1977 | _x_x_mozilla_cycle_collect,1098 14:42:28 INFO - PROCESS | 1977 | __end_cc_report 14:42:28 INFO - PROCESS | 1977 | __startTimestamp1463175748569__endTimestamp 14:42:28 INFO - PROCESS | 1977 | 14:42:28 INFO - PROCESS | 1977 | ------- Summary: start ------- 14:42:28 INFO - PROCESS | 1977 | Number of tests: 14 14:42:28 INFO - PROCESS | 1977 | 14:42:28 INFO - PROCESS | 1977 | [#0] ai-astar Cycles:10 Average:124.40 Median:123.50 stddev:25.16 (20.4%) stddev-sans-first:26.06 14:42:28 INFO - PROCESS | 1977 | Values: 109.0 115.0 108.0 115.0 117.0 114.0 97.0 170.0 169.0 130.0 14:42:28 INFO - PROCESS | 1977 | 14:42:28 INFO - PROCESS | 1977 | [#1] audio-beat-detection Cycles:10 Average:158.30 Median:153.00 stddev:32.50 (21.2%) stddev-sans-first:33.05 14:42:28 INFO - PROCESS | 1977 | Values: 132.0 135.0 177.0 232.0 147.0 131.0 159.0 145.0 189.0 136.0 14:42:28 INFO - PROCESS | 1977 | 14:42:28 INFO - PROCESS | 1977 | [#2] audio-dft Cycles:10 Average:224.40 Median:213.00 stddev:36.88 (17.3%) stddev-sans-first:39.12 14:42:28 INFO - PROCESS | 1977 | Values: 224.0 271.0 274.0 284.0 201.0 195.0 198.0 202.0 197.0 198.0 14:42:28 INFO - PROCESS | 1977 | 14:42:28 INFO - PROCESS | 1977 | [#3] audio-fft Cycles:10 Average:85.10 Median:66.50 stddev:38.81 (58.4%) stddev-sans-first:40.55 14:42:28 INFO - PROCESS | 1977 | Values: 66.0 80.0 73.0 178.0 69.0 62.0 63.0 67.0 132.0 61.0 14:42:28 INFO - PROCESS | 1977 | 14:42:28 INFO - PROCESS | 1977 | [#4] audio-oscillator Cycles:10 Average:101.80 Median:81.50 stddev:32.82 (40.3%) stddev-sans-first:33.76 14:42:28 INFO - PROCESS | 1977 | Values: 79.0 90.0 153.0 79.0 83.0 82.0 124.0 83.0 81.0 164.0 14:42:28 INFO - PROCESS | 1977 | 14:42:28 INFO - PROCESS | 1977 | [#5] imaging-gaussian-blur Cycles:10 Average:98.40 Median:88.50 stddev:14.88 (16.8%) stddev-sans-first:15.60 14:42:28 INFO - PROCESS | 1977 | Values: 92.0 87.0 90.0 88.0 106.0 130.0 96.0 88.0 89.0 118.0 14:42:28 INFO - PROCESS | 1977 | 14:42:28 INFO - PROCESS | 1977 | [#6] imaging-darkroom Cycles:10 Average:105.90 Median:95.00 stddev:21.29 (22.4%) stddev-sans-first:22.22 14:42:28 INFO - PROCESS | 1977 | Values: 95.0 96.0 150.0 97.0 93.0 95.0 142.0 95.0 96.0 100.0 14:42:28 INFO - PROCESS | 1977 | 14:42:28 INFO - PROCESS | 1977 | [#7] imaging-desaturate Cycles:10 Average:111.60 Median:91.50 stddev:25.71 (28.1%) stddev-sans-first:26.39 14:42:28 INFO - PROCESS | 1977 | Values: 130.0 152.0 91.0 93.0 95.0 93.0 129.0 150.0 92.0 91.0 14:42:28 INFO - PROCESS | 1977 | 14:42:28 INFO - PROCESS | 1977 | [#8] json-parse-financial Cycles:10 Average:75.60 Median:61.00 stddev:32.23 (52.8%) stddev-sans-first:33.62 14:42:28 INFO - PROCESS | 1977 | Values: 59.0 60.0 60.0 59.0 71.0 72.0 90.0 163.0 62.0 60.0 14:42:28 INFO - PROCESS | 1977 | 14:42:28 INFO - PROCESS | 1977 | [#9] json-stringify-tinderbox Cycles:10 Average:71.20 Median:72.00 stddev:4.08 (5.7%) stddev-sans-first:3.87 14:42:28 INFO - PROCESS | 1977 | Values: 66.0 68.0 67.0 74.0 80.0 72.0 73.0 69.0 72.0 71.0 14:42:28 INFO - PROCESS | 1977 | 14:42:28 INFO - PROCESS | 1977 | [#10] stanford-crypto-aes Cycles:10 Average:94.40 Median:88.00 stddev:12.74 (14.5%) stddev-sans-first:12.94 14:42:28 INFO - PROCESS | 1977 | Values: 84.0 88.0 89.0 88.0 77.0 88.0 113.0 111.0 111.0 95.0 14:42:28 INFO - PROCESS | 1977 | 14:42:28 INFO - PROCESS | 1977 | [#11] stanford-crypto-ccm Cycles:10 Average:146.20 Median:139.00 stddev:25.12 (18.1%) stddev-sans-first:26.42 14:42:28 INFO - PROCESS | 1977 | Values: 137.0 145.0 136.0 135.0 141.0 136.0 137.0 135.0 217.0 143.0 14:42:28 INFO - PROCESS | 1977 | 14:42:28 INFO - PROCESS | 1977 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.90 Median:171.50 stddev:22.80 (13.3%) stddev-sans-first:24.17 14:42:28 INFO - PROCESS | 1977 | Values: 178.0 225.0 220.0 173.0 166.0 166.0 168.0 170.0 166.0 167.0 14:42:28 INFO - PROCESS | 1977 | 14:42:28 INFO - PROCESS | 1977 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.50 stddev:2.20 (3.3%) stddev-sans-first:2.33 14:42:28 INFO - PROCESS | 1977 | Values: 67.0 68.0 68.0 65.0 67.0 66.0 68.0 65.0 71.0 63.0 14:42:28 INFO - PROCESS | 1977 | -------- Summary: end -------- 14:42:28 INFO - PROCESS | 1977 | 14:42:29 INFO - TEST-INFO | 1977: exit 0 14:42:29 INFO - TEST-OK | kraken | took 62071ms 14:42:29 INFO - SUITE-END | took 1174s 14:42:29 INFO - Completed test suite (00:19:34) 14:42:29 INFO - javascript benchmark 14:42:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7298.151575300203, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2842.573613957906, 3437.9484307735374, 3473.0101920952516, 3479.9304013919723, 3483.0, 5686.2039314496005, 5754.827355179346, 5812.389699081599, 5834.970825145873, 5843.415658434165, 5635.887282254353, 5769.769209231626, 5808.767649294034, 5835.153902684108, 5912.763489460427, 4431.512533621299, 4944.604431645478, 5246.74078221228, 5273.789048438058, 5303.549198318128, 388.2870320712215, 4490.7530085845265, 5125.436202017775, 5243.2135179723155, 5254.4220135785035, 5189.948100519003, 5191.117510023268, 5194.272801807769, 5244.527992480657, 5284.91659209863, 5200.323957885488, 5214.921776173361, 5252.264682944391, 5293.206019097166, 5296.841094767163, 5086.906315142225, 5136.5890728741615, 5192.92210616841, 5222.0861349264105, 5257.579393648499, 5506.559475241971, 5636.49271565557, 5638.577106717013, 5641.069223578104, 5720.8855822883725, 6316.652584107872, 6323.6521991290465, 6324.525660575429, 6330.366963303679, 6354.205724284465, 5630.0, 6210.1616281801835, 6222.253329600477, 6260.248770147611, 6289.433950944437, 5580.748866300985, 5922.170896074553, 5927.911081333784, 5932.466078052996, 5950.791722289742, 6126.426068710427, 6297.96851015742, 6304.0, 6314.431701146918, 6316.873662526724, 6109.769069418392, 6114.145302971568, 6135.386461353829, 6185.41238582334, 6213.409726076015, 6218.22272215973, 6219.440250377488, 6232.158658581124, 6294.905576416358, 6308.968455157695, 6180.598261113013, 6194.938050619527, 6280.371962803774, 6292.182016337847, 6296.8425789354715, 6246.250449945945, 6286.277078136073, 6292.685365731695, 6319.58922670025, 6331.810045698637, 4933.481984391659, 4952.603791696655, 4958.677685950402, 4959.90080198394, 4965.131102057126, 4367.868963931087, 4380.846670366522, 4435.645148388121, 4492.685512014127, 4519.028408892038, 5828.417158284223, 5861.501772349398, 5864.530837532987, 5882.617629854046, 5904.645721256738, 6239.218084286613, 6273.7176827042895, 6285.402886725845, 6297.4647154992335, 6370.490360771128, 5767.826965191051, 5916.940830591725, 5974.4324289192455, 5992.700364981733, 6007.609505382136, 5458.099413596791, 5480.205370221295, 5486.369067557282, 5487.807926722546, 5496.6701997880255, 6451.967740161269, 6457.773977910844, 6467.547271691031, 6474.9316362800555, 6484.221893372825, 6204.937950620617, 6210.254769427697, 6229.719662615103, 6235.532335074797, 6244.1570387997035, 6406.263279722892, 6436.195475565554, 6441.71012304438, 6446.033095035782, 6452.128962590178, 6288.27684816252, 6295.276043254901, 6298.307186209605, 6302.117703521602, 6306.054091886253, 4932.63005274613, 5631.8592035199445, 6169.259688837368, 6178.0, 6278.121063051085, 4583.702059366131, 6150.753969841336, 6216.627002379872, 6217.689115544295, 6225.346338634378, 4580.519045500308, 6126.886224252626, 6189.690515474118, 6225.3774622537385, 6228.128062071404, 5938.703064846654, 6023.1266466363, 6067.362926892612, 6124.8775024500155, 6143.6928153593035, 6185.381461853779, 6236.503239222645, 6238.126662267196, 6273.0, 6276.874462510816, 5927.4665280125, 6064.181335519648, 6135.6932153393045, 6156.445919867234, 6160.44555989963], "value": 5683.478145881677, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [519.1952473665817, 522.107196693654, 524.8294304351086, 530.9752178295887, 553.7784886045583, 37600.87197384076, 41422.58577414225, 41560.0, 41597.168056638846, 41649.37525937113, 41732.58267417325, 42204.57795422045, 42252.788736056275, 42275.78862105693, 42343.78828105863, 9637.373570717898, 9826.361286516389, 9831.213502919785, 9845.55694993727, 9845.704628861145, 9711.417314961089, 9780.608775648965, 9817.607295708163, 9829.21366290695, 9831.213502919749, 9841.655542056029, 9866.358686685378, 9871.60513579456, 9874.358166719176, 9877.60489580416, 7554.357879580242, 9776.217902567778, 9816.460094694788, 9850.359726617748, 10200.540975656077, 9650.276229282832, 9678.032196780336, 9712.320137590372, 9723.222142228604, 9764.755881102958, 39153.412698809254, 39316.0, 39441.0, 39555.208895821925, 39681.0, 2838.4606924684244, 3002.4745669507747, 3045.4213699397264, 3405.4551271796395, 3594.19130695594, 27093.458130837273, 27675.72324276772, 27768.58347124795, 28106.0, 29106.41787164245, 2304.2396009317054, 2617.267165193749, 2641.8943242270284, 2659.7074321824584, 2691.2329985954107, 448586.0, 454335.0, 455042.0, 456893.0, 458198.0, 29327.266818329714, 34320.0, 34627.4805877912, 34673.47989780105, 34903.12742181466, 35211.82394088065, 35471.0, 35604.109897252776, 35688.64311356905, 35706.82146589302, 15165.469208577868, 15204.695906081817, 15332.923335383251, 15353.925225234127, 15400.691986160215, 12857.935710321575, 12911.677208069874, 12932.547360842325, 13014.804777928339, 13026.153300035467, 7576.128745194263, 8151.30713889326, 8174.386920980831, 8215.589220539068, 8248.628811703487, 3183.1882869868036, 3415.624281329052, 3438.3123375325035, 3450.741194410379, 3474.357243909885, 972.9805403891883, 983.852422136671, 992.7617371830709, 1058.9470526473644, 1109.2734259060167, 23599.646005309933, 23799.167029153898, 24423.87788061084, 28603.0, 29617.703822961925, 1000.9849852252222, 1087.7117563845586, 1100.7248187953012, 1107.4020029184312, 1131.8981291683788, 15137.167455790037, 15244.466443674419, 15285.770713439308, 15669.0, 15733.213339333217, 674159.0, 678318.0, 683235.0, 684537.0, 686714.0, 57932.13101803392, 58631.0, 59265.111023334684, 59301.0, 59415.40584594185, 1378.3590630356932, 1427.6716355238145, 1465.142891408538, 1466.171613038651, 1469.4563011685746, 24410.389740255927, 29376.853115734284, 29504.0, 29756.85121574465, 30022.048566843085, 50972.490275096774, 51217.0, 51317.48682513127, 51542.0, 51556.0, 562089.0, 564807.0, 569811.0, 570309.0, 571529.0, 26350.0, 26595.0, 26992.865035674695, 27850.16449506518, 28294.575581365883, 45174.09651807011, 45508.54491455043, 45666.315005274286, 46121.53878461307, 46318.53681463142, 30301.0, 37374.6262537382, 37835.05412364713, 37933.24133517259, 38929.0, 37146.8142659285, 37243.81378093092, 37786.0, 38002.619973799905, 38303.233935321696, 36600.45099323459, 37183.81408092942, 37241.0, 37714.622853772205, 38295.6170438292, 31437.842810785798, 31937.840310798296, 32824.83587582047, 32827.835860820545, 32847.178820529676, 24428.75571244265, 24742.38144046413, 24801.651777009873, 24963.875180624706, 25118.874405628587], "value": 18206.805697753425, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [327.5545258448508, 397.37016828327097, 397.4197671399757, 409.114267610623, 418.53751604477054, 11821.17251792375, 18931.52671183221, 21102.472438189052, 21127.683084753742, 21179.788202117976, 22276.777232227672, 22474.66288005681, 22963.540729185406, 23339.883300583515, 23419.41451463714, 6349.523785716066, 6871.725130994754, 7698.153203147649, 7721.266479684421, 7797.98626178596, 7356.154042285126, 7945.205479452066, 8012.158723334053, 8027.759167224993, 8047.557384343859, 7572.242775722439, 7908.565028923407, 7951.403644726669, 7958.840823183533, 8012.479188852706, 7100.792865212926, 7590.3548198403205, 7778.8833167502535, 7801.542514108094, 7914.920850791476, 7101.964490177516, 7664.961675191644, 7678.577678227695, 7680.692772289158, 7709.7301594443925, 23950.401239968967, 26102.086426974965, 28905.56641650377, 29792.0, 30146.397072058655, 2106.557622899193, 2207.746109197447, 2246.6854640350366, 2277.237125562938, 2429.5748244057395, 16667.91666041674, 16910.323587056504, 17109.914450427794, 17179.055151966637, 17298.87557308771, 1744.700198352223, 1771.4422044614116, 1888.3107665702057, 1963.0577322885094, 1991.4822146241936, 101294.49352753263, 109385.0, 123277.0, 134681.0, 146424.0, 17523.386681466087, 17739.93560386381, 21322.040508177168, 22232.555348892933, 22512.0, 36533.817330913706, 36731.4490282646, 36754.8162259187, 36796.63203367932, 36850.26299474049, 9143.497107659143, 10895.83656245157, 11138.888611113785, 11314.434278286053, 11327.433628318551, 9161.175494205554, 9173.724788256362, 9204.631814727336, 9227.031161727922, 9260.629574817067, 9961.601535938626, 10086.344387614781, 10128.214740946152, 10267.691969240936, 10276.537555810006, 4015.297322968527, 4023.436718859362, 4062.857799976987, 4070.2673518766314, 4109.630133287958, 886.7738726624669, 951.6859436385976, 957.593022965237, 959.1032384720309, 1045.2526443592715, 19656.0, 19803.900980495004, 20921.686174707393, 20925.790742092384, 21038.42211834088, 966.7631430299481, 972.3436680240809, 999.4353190447374, 1003.1373019203479, 1021.7292417509366, 10733.892661073445, 10885.455727213608, 10948.726281843017, 11151.776964460665, 11241.437928103562, 493202.0, 498383.0, 498738.0, 603026.0, 603861.0, 141859.29070354582, 147181.0, 150019.0, 150544.0, 156797.43202567828, 842.7219017724136, 1031.5564307347913, 1077.4343469678481, 1079.7624522605015, 1087.0977089016098, 18984.71522927185, 19351.0, 19360.0, 19369.51576210606, 19615.411537653894, 56510.434895652164, 57488.0, 57875.710621446626, 58097.838043239746, 60836.0, 75831.62084189543, 80521.59739201267, 82802.0, 84269.0, 85236.31200283796, 7042.507024508235, 7422.035135432575, 7523.661435235318, 7619.7714068575815, 8481.78795530092, 11050.0, 11300.604478843201, 11927.522899084286, 12940.870591293966, 13137.737245255232, 9530.952345238507, 9676.75808104803, 10315.658964334589, 11200.10399168081, 11669.88330116688, 10341.9482902585, 10720.303180293256, 10840.186985976241, 10847.728806779895, 11407.600733974272, 11201.38392388394, 11607.535698571963, 11669.88330116688, 11818.704532386759, 11826.231294965799, 6536.182977127859, 7485.1766305707415, 7590.20302868213, 7595.392368610608, 7678.924950507046, 8443.113473085483, 10534.36793792375, 10719.517621706886, 11289.887101128883, 11642.88357116418], "value": 11150.706998129426, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1612.7742116103748, 1656.0560480526096, 1874.8031456697056, 1932.903354832258, 2002.1390801955154, 8290.253877151055, 8397.740338949161, 8837.690680826172, 9384.906150938488, 9440.244780417568, 7661.348785353238, 8624.741257762262, 8891.244244239231, 9134.0, 9151.267898568116, 2136.6154092263346, 2424.59994100974, 2461.089396923141, 2461.9753802462014, 2466.6300054991716, 2360.7993320567684, 2422.358075110097, 2467.1365022242253, 2474.64117702933, 2474.7896428803574, 2438.51229754049, 2443.59680652693, 2454.631805229212, 2459.3974476253343, 2485.5774518331837, 2423.260905423845, 2435.476372579895, 2444.706635203778, 2447.5839107351794, 2454.4968281502247, 2400.603900356439, 2409.325388891113, 2419.0807493152665, 2433.1119141513395, 2451.6322551617222, 9048.185663290336, 9121.81756364869, 9155.313351498598, 9193.0, 9222.031686672937, 5441.727913604304, 5563.777448902039, 5617.21359009739, 5620.5503559715125, 5627.6904770237625, 3587.6232995535356, 3621.9275614487824, 3635.0730563706084, 3686.1706116123923, 3693.261347730465, 1008.4503945349728, 1055.5250137438184, 1057.4501259345175, 1059.6026490066224, 1064.4837253931837, 12054.0, 12179.756404871941, 12182.086343524272, 12204.572839950559, 12214.450349734283, 4875.975620121877, 4878.292647566083, 4973.875653108701, 4978.726170060681, 5001.549860512571, 10243.385396876061, 10255.58977640901, 10302.845457318146, 10381.169506439466, 10391.792164156675, 5279.1817268323475, 5299.5230429261555, 5350.197470379397, 5357.142857142839, 5575.637583557056, 4536.750478723702, 4915.631327650441, 5005.449400565934, 5090.49095090488, 5111.131107711698, 4126.504819421629, 4151.27352713274, 4182.853600123981, 4195.160967806451, 4217.768022758716, 1341.3293353323338, 1363.754524185656, 1366.296357375952, 1370.3011464153353, 1370.6641872741043, 758.6665739848871, 858.1675774498726, 862.2627653356416, 959.6545243712258, 979.2031621973758, 6059.818205453844, 6187.84530386744, 6209.9379006210265, 6229.127922090911, 6275.497960163176, 463.9025804581076, 468.8195044907667, 486.7809485731435, 487.1572180128398, 487.52222821634996, 3544.3088597723195, 4035.959640403617, 4090.959090409058, 4116.958830411718, 4176.352665336878, 9036.728898132935, 10826.729331766768, 11145.331280123057, 11202.719932001764, 11204.383758893338, 9426.245900328095, 11049.28179668319, 11094.500747466222, 11137.610183643534, 11176.66470005885, 7023.578585284899, 8513.74458766238, 8519.06290308078, 8590.785230369165, 8613.310935125175, 5937.643741375531, 5985.072313791369, 5986.2517185351835, 6118.326984031664, 6208.720607572761, 9953.552090155816, 10285.588576456857, 10397.948010260203, 10399.32404393712, 10444.68665940023, 10591.523381448007, 10670.946645267035, 10675.946620266848, 10680.359178449318, 10693.518791654238, 6803.89794153098, 7023.824404389931, 7742.806429839299, 7772.37820974332, 7967.442279040411, 8626.39615226928, 8636.827263454821, 8682.262007729161, 8716.60775265102, 8732.301415886586, 7577.052868391451, 7656.234376562299, 7735.22647735222, 7826.2173782621285, 7846.686132554634, 7459.925400745923, 7500.624968751432, 7524.510906791041, 7632.274933881273, 7673.271039251262, 7173.24680908518, 7232.987381766452, 7286.380657644159, 7291.489595728248, 7307.525010874276, 4913.877153071202, 5061.4938506150565, 5150.356205474316, 5155.845324640267, 5169.844904652867, 4585.679002469795, 5124.666896651705, 5135.435102138837, 5197.7661005254095, 5307.6284660073425], "value": 4643.443189468867, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.65654466996966, 315.136525918982, 319.7378149917067, 324.31083946613444, 328.95559099521574, 5242.973785131078, 5380.300560927084, 5561.91657125143, 5661.405552416999, 5726.3414707308675, 4486.573775491323, 5568.136938774496, 5605.495505404523, 5628.043232650449, 5629.465200805927, 3733.5893051764283, 3949.499190307679, 4195.706370499159, 4221.0, 4254.46819147605, 3407.177160219283, 4126.700089471807, 4241.957580424187, 4262.65898728101, 4265.53079161292, 3645.3802853514967, 3988.4814974053334, 4150.979245103786, 4157.38085006447, 4188.72773269738, 3191.3298207376247, 3386.2899235885843, 3640.344737947195, 4182.309918863369, 4214.4099826024385, 3526.6297038811067, 3656.4698118772894, 3662.6520480554304, 3669.3028324618504, 3671.6695497405362, 4489.595936365743, 4532.8866778330475, 4812.302216178635, 5200.609954253408, 5354.732263386854, 4083.3874918762126, 4596.701214421086, 4721.905561888743, 4760.143174228638, 4834.468208497063, 5806.88386232273, 5849.596096936747, 5892.58577941291, 5926.199963004982, 5937.495312898409, 3752.8311225994894, 4001.5194378080005, 4006.7395619284653, 4014.7992600369867, 4037.2329257440992, 5599.916001259984, 5757.280339957505, 5774.076147816371, 6037.4566289033755, 6134.539909506805, 3331.750118741104, 4024.8591299304385, 4063.817128229237, 4065.308897487375, 4103.692223083281, 3594.388953877847, 4174.497181014833, 4193.30810416284, 4201.831926722897, 4228.260054490452, 4150.667946564267, 4458.509563947963, 4802.559232230317, 4830.550834749561, 4863.878403039952, 4821.855344339676, 5877.617954832922, 5884.882302353929, 5891.705414729332, 5937.287525496881, 1180.1797750563276, 1416.7733162694126, 1417.8794802441907, 1423.2243427332128, 1431.7566013777682, 748.7346384609991, 778.1479280188184, 818.3902992270796, 823.4194892600822, 858.3862538285031, 3958.5447673517338, 4104.56902025289, 4547.63618910493, 4570.006645647107, 4597.42532183477, 4371.497277813029, 4702.741349225757, 5167.948320516747, 5172.534471897472, 5189.532942035159, 4142.668586513072, 4562.448767419093, 4584.793684284148, 4655.301704744248, 4676.87754938819, 2394.473215892501, 2970.5247160454223, 3020.9728692244744, 3209.630892447352, 3214.646388897266, 5497.340319161646, 5743.569232307594, 5883.794067207627, 6341.524385671093, 6342.904856427157, 5646.805477321565, 6106.877862442726, 6249.625202390658, 6266.553395632475, 6284.12022316876, 5593.664380137205, 5734.913976290359, 5835.299764028344, 6171.796499682699, 6372.139761132281, 5151.613628977917, 5683.346415162309, 6031.336552979256, 6059.333893087028, 6123.234595675541, 5327.256462921521, 5386.5152136307925, 6033.607815491979, 6097.237845269341, 6097.634141951497, 5281.815136470205, 5636.0, 5840.240768700041, 6002.358962056681, 6412.487001039999, 3107.238726512019, 3367.161435963053, 3964.821583028713, 3973.7019723520275, 4044.3124668806963, 5096.796128154832, 5543.8891222176135, 5677.744501497526, 6146.661933593696, 6250.0312401593355, 4959.958408734062, 5238.0, 5500.569851838631, 5531.668099914018, 5956.315023772322, 4949.034938187019, 5468.671879687232, 5792.536597072309, 5947.791827286197, 5951.67884525027, 5241.371035475768, 5424.0, 5703.828885133453, 5786.714247872151, 5800.7969721061245, 5632.859178520406, 5908.813738916629, 5909.645421274738, 5936.0, 5947.583669143119, 4601.907961840811, 5078.0, 5200.947990520046, 5213.687178769286, 5234.953009398212], "value": 4137.999596073955, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [658.1148355461902, 676.28989560961, 699.167990091791, 705.3581241070624, 729.2816575672963, 15124.016938898978, 15334.846651533482, 15433.536993890188, 15604.53186404407, 15651.373945042185, 14068.929655351734, 14381.784273235884, 14446.494372696958, 14460.276986150677, 14480.855191448083, 919.8850143732034, 927.2952556057378, 934.700895713372, 941.7975135345899, 941.9764505887339, 740.1303468424607, 912.4479689787682, 939.6899023322321, 939.9342046056779, 944.0370821761799, 978.1392374710246, 989.9109080182782, 990.4107056301489, 992.9751756206081, 994.1102713071797, 961.8316794560959, 967.666155176463, 984.1044649369111, 985.6254623243158, 987.8616993620899, 735.7572001239578, 736.0321177651372, 736.7237286017744, 736.7716008037526, 737.7712908998174, 14897.180655063967, 14997.925010374987, 15004.849951500455, 15016.549503514912, 15023.849761502355, 10702.892971070267, 11692.181547291693, 12544.24734515932, 12692.238465691995, 12842.357882105951, 8928.196462318358, 10026.448545330004, 10031.832921786146, 10121.746956326151, 10155.847662285072, 2878.6977367376335, 3477.443609022544, 3497.2131270464197, 3515.1212196950764, 3543.8050907200095, 71611.99999999948, 85269.57365213196, 86851.56574217152, 86918.5654071732, 87678.5616071922, 7905.723299684599, 8396.916030839613, 8836.955815221012, 9446.008169142278, 9685.41887486753, 22154.778452215593, 22206.66689999651, 22470.550588988455, 22838.657420138712, 22871.428214294443, 7312.634368281565, 7379.557226566423, 7395.307395307353, 7469.0, 7627.69489220436, 3941.763494190358, 4008.518977722692, 4013.5986401360215, 4024.8188831502653, 4029.133736246721, 3505.4040813061843, 3611.94582081269, 3620.0225938996323, 3621.8913432597064, 3624.2751449710163, 611.6635850282327, 630.4168644004369, 634.1059106659587, 641.9743210271537, 643.5302229372584, 477.91397548441614, 559.9300087489064, 574.2506029631288, 595.5890435599423, 616.3251239892311, 7799.766007019798, 7964.681412743425, 8104.5137291762685, 8331.875021874677, 8519.787005324917, 541.3747122074011, 574.9248904548438, 581.0992960910656, 594.0643238544642, 616.2297128589263, 3798.639129282714, 3882.747621404656, 3970.086880017612, 3989.7207195496617, 4026.315526360526, 78768.84924602952, 99911.0, 100071.0, 102439.48780256051, 102553.97446025444, 67300.6634966822, 79330.6033469829, 81962.0, 84878.15121848826, 85925.57037214898, 75062.0, 75067.24932750603, 75569.0, 77361.2263877354, 77786.61106694544, 7732.8453430932195, 7737.613119344122, 7908.090569584571, 7934.920650793649, 7967.641456134372, 46117.76941115273, 47285.2907206399, 47549.294084710054, 48190.75904620454, 48274.793130172024, 53127.468725312254, 64162.358376417505, 67513.66243168752, 67663.66168169127, 70155.29844701488, 8780.604872780612, 10107.241956853413, 10236.436995965294, 10288.691339259834, 10295.691129266133, 11274.267172633752, 11287.830682539932, 11667.708307292385, 11975.28148311104, 12050.939745301217, 8014.559199244098, 10484.580616775243, 10633.361998280128, 10886.455677216265, 11012.559497620005, 10585.788284234426, 10659.040686338267, 10775.030247277782, 10889.074428673652, 10898.727531811768, 10365.118964888068, 10416.062554370143, 10428.217883658604, 10437.634682786065, 10780.29928054674, 7569.962150189213, 8879.644814207617, 8936.783173859874, 8957.422141282743, 9234.307426942873, 8934.821303574023, 8963.507007114671, 9349.766255843659, 9397.403218097752, 9590.232781377612], "value": 6814.782037701262, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1644.0000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 115.0, 108.0, 115.0, 117.0, 114.0, 97.0, 170.0, 169.0, 130.0], "value": 124.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 135.0, 177.0, 232.0, 147.0, 131.0, 159.0, 145.0, 189.0, 136.0], "value": 158.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [224.0, 271.0, 274.0, 284.0, 201.0, 195.0, 198.0, 202.0, 197.0, 198.0], "value": 224.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 80.0, 73.0, 178.0, 69.0, 62.0, 63.0, 67.0, 132.0, 61.0], "value": 85.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 90.0, 153.0, 79.0, 83.0, 82.0, 124.0, 83.0, 81.0, 164.0], "value": 101.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 87.0, 90.0, 88.0, 106.0, 130.0, 96.0, 88.0, 89.0, 118.0], "value": 98.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 150.0, 97.0, 93.0, 95.0, 142.0, 95.0, 96.0, 100.0], "value": 105.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 152.0, 91.0, 93.0, 95.0, 93.0, 129.0, 150.0, 92.0, 91.0], "value": 111.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 60.0, 59.0, 71.0, 72.0, 90.0, 163.0, 62.0, 60.0], "value": 75.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 67.0, 74.0, 80.0, 72.0, 73.0, 69.0, 72.0, 71.0], "value": 71.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 88.0, 89.0, 88.0, 77.0, 88.0, 113.0, 111.0, 111.0, 95.0], "value": 94.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 145.0, 136.0, 135.0, 141.0, 136.0, 137.0, 135.0, 217.0, 143.0], "value": 146.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 225.0, 220.0, 173.0, 166.0, 166.0, 168.0, 170.0, 166.0, 167.0], "value": 179.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 68.0, 65.0, 67.0, 66.0, 68.0, 65.0, 71.0, 63.0], "value": 66.8, "unit": "score"}]}]} 14:42:29 INFO - Return code: 0 14:42:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 14:42:29 ERROR - # TBPL SUCCESS # 14:42:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:42:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:42:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:42:29 INFO - 2016-05-13 14:42:29.884 screenresolution[1982:27649] starting screenresolution argv=screenresolution get 14:42:29 INFO - 2016-05-13 14:42:29.887 screenresolution[1982:27649] Display 0: 1600x1200x32@60 14:42:29 INFO - 2016-05-13 14:42:29.893 screenresolution[1983:27652] starting screenresolution argv=screenresolution list 14:42:29 INFO - Available Modes on Display 0 14:42:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:42:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:42:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:42:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:42:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:42:30 INFO - Intel Iris: 14:42:30 INFO - Chipset Model: Intel Iris 14:42:30 INFO - Type: GPU 14:42:30 INFO - Bus: Built-In 14:42:30 INFO - VRAM (Dynamic, Max): 1536 MB 14:42:30 INFO - Vendor: Intel (0x8086) 14:42:30 INFO - Device ID: 0x0a2e 14:42:30 INFO - Revision ID: 0x0009 14:42:30 INFO - Displays: 14:42:30 INFO - Display: 14:42:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:42:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:42:30 INFO - Display Serial Number: 1600x1200 60 14:42:30 INFO - Main Display: Yes 14:42:30 INFO - Mirror: Off 14:42:30 INFO - Online: Yes 14:42:30 INFO - Rotation: Supported 14:42:30 INFO - Return code: 0 14:42:30 INFO - Running post-action listener: _resource_record_post_action 14:42:30 INFO - ##### 14:42:30 INFO - ##### Finished run-tests step (success) 14:42:30 INFO - ##### 14:42:30 INFO - Running post-run listener: _resource_record_post_run 14:42:30 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 528384; Write bytes: 698583552; Read time: 7; Write time: 1954 14:42:30 INFO - TinderboxPrint: CPU usage
54.6% 14:42:30 INFO - TinderboxPrint: I/O read bytes / time
528,384 / 7 14:42:30 INFO - TinderboxPrint: I/O write bytes / time
698,583,552 / 1,954 14:42:30 INFO - TinderboxPrint: CPU idle
2,200.3 (45.4%) 14:42:30 INFO - TinderboxPrint: CPU system
974.3 (20.1%) 14:42:30 INFO - TinderboxPrint: CPU user
1,667.1 (34.4%) 14:42:30 INFO - TinderboxPrint: Swap in / out
395,710,464 / 0 14:42:30 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 378190336; Write bytes: 372138496; Read time: 28224; Write time: 735 14:42:30 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 425984; Write bytes: 297010176; Read time: 3; Write time: 1134 14:42:30 INFO - Running post-run listener: _upload_blobber_files 14:42:30 INFO - Blob upload gear active. 14:42:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:42:30 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 14:42:30 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'] 14:42:30 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 14:42:31 INFO - (blobuploader) - INFO - Open directory for files ... 14:42:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:42:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:42:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:42:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:42:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:42:31 INFO - (blobuploader) - INFO - Done attempting. 14:42:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:42:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:42:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:42:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:42:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:42:32 INFO - (blobuploader) - INFO - Done attempting. 14:42:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:42:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:42:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:42:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:42:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:42:35 INFO - (blobuploader) - INFO - Done attempting. 14:42:35 INFO - (blobuploader) - INFO - Iteration through files over. 14:42:35 INFO - Return code: 0 14:42:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:42:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:42:35 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/066bfd9806cebae31f8f21ad800d932aeec8ecd33eff91d9f00e49ad083b54f53afff2967d054c84502353bfe01dc91981a246d93ecc9fefe2a29ef317c65710", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a1d5d782557e359acfa37b61e6f36228d51416e7c7398fbd00df748efa7358502149f2f46058783511a5ba0ba77685a0a73d36cb62c419440d0c5384a9afd414", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cc3a81c3d215ddaa0d79e24b6f9f8afb708950c22db4fb88e37646ed0f64a85b5f88c273da2118d082c5655c8fc1942afdab13937fde6985a07ef6df22884207"} 14:42:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:42:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:42:35 INFO - Contents: 14:42:35 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/066bfd9806cebae31f8f21ad800d932aeec8ecd33eff91d9f00e49ad083b54f53afff2967d054c84502353bfe01dc91981a246d93ecc9fefe2a29ef317c65710", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a1d5d782557e359acfa37b61e6f36228d51416e7c7398fbd00df748efa7358502149f2f46058783511a5ba0ba77685a0a73d36cb62c419440d0c5384a9afd414", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cc3a81c3d215ddaa0d79e24b6f9f8afb708950c22db4fb88e37646ed0f64a85b5f88c273da2118d082c5655c8fc1942afdab13937fde6985a07ef6df22884207"} 14:42:35 INFO - Running post-run listener: copy_logs_to_upload_dir 14:42:35 INFO - Copying logs to upload dir... 14:42:35 INFO - mkdir: /builds/slave/test/build/upload/logs 14:42:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1280.818642 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-05-13 14:42:35.363344) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 14:42:35.366833) ========= 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.HzJzVljEF6/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.cgS8ZVV0OD/Listeners TMPDIR=/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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/066bfd9806cebae31f8f21ad800d932aeec8ecd33eff91d9f00e49ad083b54f53afff2967d054c84502353bfe01dc91981a246d93ecc9fefe2a29ef317c65710", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a1d5d782557e359acfa37b61e6f36228d51416e7c7398fbd00df748efa7358502149f2f46058783511a5ba0ba77685a0a73d36cb62c419440d0c5384a9afd414", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cc3a81c3d215ddaa0d79e24b6f9f8afb708950c22db4fb88e37646ed0f64a85b5f88c273da2118d082c5655c8fc1942afdab13937fde6985a07ef6df22884207"} build_url:https://queue.taskcluster.net/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011112 build_url: 'https://queue.taskcluster.net/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/066bfd9806cebae31f8f21ad800d932aeec8ecd33eff91d9f00e49ad083b54f53afff2967d054c84502353bfe01dc91981a246d93ecc9fefe2a29ef317c65710", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a1d5d782557e359acfa37b61e6f36228d51416e7c7398fbd00df748efa7358502149f2f46058783511a5ba0ba77685a0a73d36cb62c419440d0c5384a9afd414", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cc3a81c3d215ddaa0d79e24b6f9f8afb708950c22db4fb88e37646ed0f64a85b5f88c273da2118d082c5655c8fc1942afdab13937fde6985a07ef6df22884207"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 14:42:35.394855) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 14:42:35.395436) ========= 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.HzJzVljEF6/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.cgS8ZVV0OD/Listeners TMPDIR=/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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.005322 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 14:42:35.444305) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 14:42:35.444633) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 14:42:35.444997) ========= ========= Total master_lag: 0.17 =========