builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0336 starttime: 1463794176.77 results: success (0) buildid: 20160520162836 builduid: 12f81a960b0b4f8b928eb1eab627d70f revision: 9804d9410b32b53e7fc6a497a6c3d731a78495a6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 18:29:36.768095) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 18:29:36.768605) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 18:29:36.768958) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 18:29:36.789197) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 18:29:36.789626) ========= 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.XkzYqhHBRZ/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.DqrkuV5Lfy/Listeners TMPDIR=/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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-20 18:29:36-- 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% 60.3M=0s 2016-05-20 18:29:36 (60.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.175405 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 18:29:36.984294) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 18:29:36.984607) ========= 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.XkzYqhHBRZ/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.DqrkuV5Lfy/Listeners TMPDIR=/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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.020094 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 18:29:37.034371) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-20 18:29:37.034749) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 9804d9410b32b53e7fc6a497a6c3d731a78495a6 --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 9804d9410b32b53e7fc6a497a6c3d731a78495a6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XkzYqhHBRZ/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.DqrkuV5Lfy/Listeners TMPDIR=/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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-20 18:29:36,815 truncating revision to first 12 chars 2016-05-20 18:29:36,815 Setting DEBUG logging. 2016-05-20 18:29:36,815 attempt 1/10 2016-05-20 18:29:36,815 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/9804d9410b32?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-20 18:29:38,463 unpacking tar archive at: fx-team-9804d9410b32/testing/mozharness/ program finished with exit code 0 elapsedTime=1.903440 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-20 18:29:38.957180) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 18:29:38.957573) ========= script_repo_revision: 9804d9410b32b53e7fc6a497a6c3d731a78495a6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 18:29:38.958030) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 18:29:38.958345) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 18:29:38.975059) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-05-20 18:29:38.975594) ========= /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.XkzYqhHBRZ/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.DqrkuV5Lfy/Listeners TMPDIR=/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 18:29:38 INFO - MultiFileLogger online at 20160520 18:29:38 in /builds/slave/test 18:29:38 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 18:29:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:29:38 INFO - {'append_to_log': False, 18:29:38 INFO - 'base_work_dir': '/builds/slave/test', 18:29:38 INFO - 'blob_upload_branch': 'Fx-Team', 18:29:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:29:38 INFO - 'branch': 'Fx-Team', 18:29:38 INFO - 'buildbot_json_path': 'buildprops.json', 18:29:38 INFO - 'config_files': ('talos/mac_config.py',), 18:29:38 INFO - 'default_actions': ('clobber', 18:29:38 INFO - 'read-buildbot-config', 18:29:38 INFO - 'download-and-extract', 18:29:38 INFO - 'populate-webroot', 18:29:38 INFO - 'create-virtualenv', 18:29:38 INFO - 'install', 18:29:38 INFO - 'run-tests'), 18:29:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:29:38 INFO - 'download_minidump_stackwalk': True, 18:29:38 INFO - 'download_symbols': 'ondemand', 18:29:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:29:38 INFO - 'tooltool.py': '/tools/tooltool.py', 18:29:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:29:38 INFO - '/tools/misc-python/virtualenv.py')}, 18:29:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:29:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:29:38 INFO - 'installer_path': 'installer.exe', 18:29:38 INFO - 'log_level': 'info', 18:29:38 INFO - 'log_name': 'talos', 18:29:38 INFO - 'log_to_console': True, 18:29:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:29:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:29:38 INFO - 'opt_config_files': (), 18:29:38 INFO - 'pip_index': False, 18:29:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:29:38 INFO - 'cmd': ('bash', 18:29:38 INFO - '-c', 18:29:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:29:38 INFO - 'enabled': True, 18:29:38 INFO - 'halt_on_failure': False, 18:29:38 INFO - 'name': 'check_screen_resolution'},), 18:29:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:29:38 INFO - 'cmd': ('bash', 18:29:38 INFO - '-c', 18:29:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:29:38 INFO - 'enabled': True, 18:29:38 INFO - 'halt_on_failure': False, 18:29:38 INFO - 'name': 'check_screen_resolution'},), 18:29:38 INFO - 'run_cmd_checks_enabled': True, 18:29:38 INFO - 'sps_profile': False, 18:29:38 INFO - 'sps_profile_interval': 0, 18:29:38 INFO - 'suite': 'dromaeojs-e10s', 18:29:38 INFO - 'system_bits': '32', 18:29:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:29:38 INFO - 'title': 't-yosemite-r7-0336', 18:29:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:29:38 INFO - 'use_talos_json': True, 18:29:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:29:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:29:38 INFO - 'work_dir': 'build'} 18:29:38 INFO - ##### 18:29:38 INFO - ##### Running clobber step. 18:29:38 INFO - ##### 18:29:38 INFO - Running pre-action listener: _resource_record_pre_action 18:29:38 INFO - Running main action method: clobber 18:29:38 INFO - rmtree: /builds/slave/test/build 18:29:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:29:40 INFO - Running post-action listener: _resource_record_post_action 18:29:40 INFO - ##### 18:29:40 INFO - ##### Finished clobber step (success) 18:29:40 INFO - ##### 18:29:40 INFO - ##### 18:29:40 INFO - ##### Running read-buildbot-config step. 18:29:40 INFO - ##### 18:29:40 INFO - Running pre-action listener: _resource_record_pre_action 18:29:40 INFO - Running main action method: read_buildbot_config 18:29:40 INFO - Using buildbot properties: 18:29:40 INFO - { 18:29:40 INFO - "project": "", 18:29:40 INFO - "product": "firefox", 18:29:40 INFO - "script_repo_revision": "production", 18:29:40 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 18:29:40 INFO - "repository": "", 18:29:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 18:29:40 INFO - "buildid": "20160520162836", 18:29:40 INFO - "pgo_build": "False", 18:29:40 INFO - "basedir": "/builds/slave/test", 18:29:40 INFO - "buildnumber": 12, 18:29:40 INFO - "slavename": "t-yosemite-r7-0336", 18:29:40 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 18:29:40 INFO - "platform": "yosemite_r7", 18:29:40 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 18:29:40 INFO - "branch": "fx-team", 18:29:40 INFO - "repo_path": "integration/fx-team", 18:29:40 INFO - "revision": "9804d9410b32b53e7fc6a497a6c3d731a78495a6", 18:29:40 INFO - "stage_platform": "macosx64", 18:29:40 INFO - "builduid": "12f81a960b0b4f8b928eb1eab627d70f", 18:29:40 INFO - "slavebuilddir": "test" 18:29:40 INFO - } 18:29:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 18:29:40 INFO - Running post-action listener: _resource_record_post_action 18:29:40 INFO - ##### 18:29:40 INFO - ##### Finished read-buildbot-config step (success) 18:29:40 INFO - ##### 18:29:40 INFO - ##### 18:29:40 INFO - ##### Running download-and-extract step. 18:29:40 INFO - ##### 18:29:40 INFO - Running pre-action listener: _resource_record_pre_action 18:29:40 INFO - Running main action method: download_and_extract 18:29:40 INFO - mkdir: /builds/slave/test/build/tests 18:29:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:40 INFO - https://queue.taskcluster.net/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 18:29:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 18:29:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 18:29:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/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 18:29:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/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 18:29:43 INFO - Downloaded 1199 bytes. 18:29:43 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 18:29:43 INFO - Using the following test package requirements: 18:29:43 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 18:29:43 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:29:43 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 18:29:43 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:29:43 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 18:29:43 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:29:43 INFO - u'jsshell-mac.zip'], 18:29:43 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:29:43 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 18:29:43 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 18:29:43 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:29:43 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 18:29:43 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:29:43 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 18:29:43 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:29:43 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 18:29:43 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:29:43 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 18:29:43 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:29:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:43 INFO - https://queue.taskcluster.net/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:29:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:29:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:29:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/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 18:29:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/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 18:29:44 INFO - Downloaded 18630459 bytes. 18:29:44 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'] 18:29:44 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 18:29:47 INFO - Return code: 0 18:29:47 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 18:29:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:47 INFO - https://queue.taskcluster.net/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:29:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:29:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:29:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/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 18:29:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/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 18:29:48 INFO - Downloaded 18630459 bytes. 18:29:48 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'] 18:29:48 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 18:29:52 INFO - Return code: 0 18:29:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:52 INFO - https://queue.taskcluster.net/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 18:29:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 18:29:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 18:29:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/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 18:29:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/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 18:29:53 INFO - Downloaded 10840359 bytes. 18:29:53 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'] 18:29:53 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 18:29:53 INFO - Return code: 0 18:29: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')]} 18:29:53 INFO - https://queue.taskcluster.net/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 18:29:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:29:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:29:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 18:29:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/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 18:29:57 INFO - Downloaded 110154276 bytes. 18:29:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:29:57 INFO - mkdir: /builds/slave/test/properties 18:29:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:29:57 INFO - Writing to file /builds/slave/test/properties/build_url 18:29:57 INFO - Contents: 18:29:57 INFO - build_url:https://queue.taskcluster.net/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:29:58 INFO - Running post-action listener: _resource_record_post_action 18:29:58 INFO - Running post-action listener: set_extra_try_arguments 18:29:58 INFO - ##### 18:29:58 INFO - ##### Finished download-and-extract step (success) 18:29:58 INFO - ##### 18:29:58 INFO - ##### 18:29:58 INFO - ##### Running populate-webroot step. 18:29:58 INFO - ##### 18:29:58 INFO - Running pre-action listener: _resource_record_pre_action 18:29:58 INFO - Running main action method: populate_webroot 18:29:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:29:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:29:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:29:58 INFO - '--tppagecycles', 18:29:58 INFO - '7'], 18:29:58 INFO - 'tests': ['tsvgm']}}, 18:29:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:29:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:29:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:29:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:29:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:29:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:29:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:58 INFO - 'talos_options': ['--disable-e10s'], 18:29:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:29:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:29:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:58 INFO - 'talos_options': ['--disable-e10s'], 18:29:58 INFO - 'tests': ['damp', 'tps']}, 18:29:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:58 INFO - 'tests': ['damp', 'tps']}, 18:29:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:29:58 INFO - 'tests': ['dromaeo_dom']}, 18:29:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:29:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:29:58 INFO - 'tests': ['a11yr', 18:29:58 INFO - 'ts_paint', 18:29:58 INFO - 'tpaint', 18:29:58 INFO - 'sessionrestore', 18:29:58 INFO - 'sessionrestore_no_auto_restore', 18:29:58 INFO - 'tabpaint']}, 18:29:58 INFO - 'other-e10s': {'tests': ['a11yr', 18:29:58 INFO - 'ts_paint', 18:29:58 INFO - 'tpaint', 18:29:58 INFO - 'sessionrestore', 18:29:58 INFO - 'sessionrestore_no_auto_restore', 18:29:58 INFO - 'tabpaint']}, 18:29:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:29:58 INFO - 'ts_paint', 18:29:58 INFO - 'tpaint', 18:29:58 INFO - 'sessionrestore', 18:29:58 INFO - 'sessionrestore_no_auto_restore', 18:29:58 INFO - 'tabpaint']}, 18:29:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:29:58 INFO - 'ts_paint', 18:29:58 INFO - 'tpaint', 18:29:58 INFO - 'sessionrestore', 18:29:58 INFO - 'sessionrestore_no_auto_restore', 18:29:58 INFO - 'tabpaint']}, 18:29:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:29:58 INFO - 'tests': ['a11yr', 18:29:58 INFO - 'ts_paint', 18:29:58 INFO - 'tpaint', 18:29:58 INFO - 'sessionrestore', 18:29:58 INFO - 'sessionrestore_no_auto_restore', 18:29:58 INFO - 'tabpaint']}, 18:29:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:29:58 INFO - 'tests': ['a11yr', 18:29:58 INFO - 'ts_paint', 18:29:58 INFO - 'tpaint', 18:29:58 INFO - 'sessionrestore', 18:29:58 INFO - 'sessionrestore_no_auto_restore', 18:29:58 INFO - 'tabpaint']}, 18:29:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:29:58 INFO - 'tests': ['tsvgx', 18:29:58 INFO - 'tsvgr_opacity', 18:29:58 INFO - 'tart', 18:29:58 INFO - 'tscrollx', 18:29:58 INFO - 'cart']}, 18:29:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:29:58 INFO - 'tsvgr_opacity', 18:29:58 INFO - 'tart', 18:29:58 INFO - 'tscrollx', 18:29:58 INFO - 'cart']}, 18:29:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:58 INFO - 'talos_options': ['--disable-e10s'], 18:29:58 INFO - 'tests': ['tp5o']}, 18:29:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:58 INFO - 'tests': ['tp5o']}, 18:29:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:58 INFO - 'talos_options': ['--disable-e10s', 18:29:58 INFO - '--xperf_path', 18:29:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:29:58 INFO - 'tests': ['tp5n']}, 18:29:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:58 INFO - 'talos_options': ['--xperf_path', 18:29:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:29:58 INFO - 'tests': ['tp5n']}}, 18:29:58 INFO - 'talos.zip': {'path': '', 18:29:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:29:58 INFO - Running post-action listener: _resource_record_post_action 18:29:58 INFO - ##### 18:29:58 INFO - ##### Finished populate-webroot step (success) 18:29:58 INFO - ##### 18:29:58 INFO - ##### 18:29:58 INFO - ##### Running create-virtualenv step. 18:29:58 INFO - ##### 18:29:58 INFO - Running pre-action listener: _resource_record_pre_action 18:29:58 INFO - Running main action method: create_virtualenv 18:29:58 INFO - Creating virtualenv /builds/slave/test/build/venv 18:29:58 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:29:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:29:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:29:58 INFO - Using real prefix '/tools/python27' 18:29:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:29:59 INFO - Installing distribute.............................................................................................................................................................................................done. 18:30:01 INFO - Installing pip.................done. 18:30:01 INFO - Return code: 0 18:30:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:30: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')]} 18:30:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30: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')]} 18:30:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:01 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 0x10fbdca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbe2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a285b6c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa80d30>, '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 0x10f8a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa77b20>, '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.XkzYqhHBRZ/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.DqrkuV5Lfy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:30:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:30:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:30:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XkzYqhHBRZ/Render', 18:30:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:01 INFO - 'HOME': '/Users/cltbld', 18:30:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:01 INFO - 'LOGNAME': 'cltbld', 18:30:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:01 INFO - 'MOZ_NO_REMOTE': '1', 18:30:01 INFO - 'NO_EM_RESTART': '1', 18:30:01 INFO - 'PAGER': '/bin/cat', 18:30:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:01 INFO - 'PWD': '/builds/slave/test', 18:30:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:01 INFO - 'SHELL': '/bin/bash', 18:30:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DqrkuV5Lfy/Listeners', 18:30:01 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 18:30:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:01 INFO - 'USER': 'cltbld', 18:30:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:01 INFO - 'XPC_FLAGS': '0x0', 18:30:01 INFO - 'XPC_SERVICE_NAME': '0', 18:30:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:02 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:30:02 INFO - Cleaning up... 18:30:02 INFO - Return code: 0 18:30:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:30:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:02 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 0x10fbdca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbe2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a285b6c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa80d30>, '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 0x10f8a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa77b20>, '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.XkzYqhHBRZ/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.DqrkuV5Lfy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:30:02 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 18:30:02 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 18:30:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XkzYqhHBRZ/Render', 18:30:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:02 INFO - 'HOME': '/Users/cltbld', 18:30:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:02 INFO - 'LOGNAME': 'cltbld', 18:30:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:02 INFO - 'MOZ_NO_REMOTE': '1', 18:30:02 INFO - 'NO_EM_RESTART': '1', 18:30:02 INFO - 'PAGER': '/bin/cat', 18:30:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:02 INFO - 'PWD': '/builds/slave/test', 18:30:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:02 INFO - 'SHELL': '/bin/bash', 18:30:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DqrkuV5Lfy/Listeners', 18:30:02 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 18:30:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:02 INFO - 'USER': 'cltbld', 18:30:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:02 INFO - 'XPC_FLAGS': '0x0', 18:30:02 INFO - 'XPC_SERVICE_NAME': '0', 18:30:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:02 INFO - Downloading/unpacking psutil>=3.1.1 18:30:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:30:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:30:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:30:05 INFO - Installing collected packages: psutil 18:30:05 INFO - Running setup.py install for psutil 18:30:05 INFO - building 'psutil._psutil_osx' extension 18:30:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 18:30:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 18:30:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 18:30:06 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 18:30:06 INFO - building 'psutil._psutil_posix' extension 18:30:06 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 18:30:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:30:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:30:06 INFO - ^ 18:30:06 INFO - 1 warning generated. 18:30:06 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 18:30:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:30:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:30:06 INFO - Successfully installed psutil 18:30:06 INFO - Cleaning up... 18:30:06 INFO - Return code: 0 18:30:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:30:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fbdca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbe2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a285b6c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa80d30>, '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 0x10f8a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa77b20>, '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.XkzYqhHBRZ/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.DqrkuV5Lfy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:30:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:30:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:30:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XkzYqhHBRZ/Render', 18:30:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:06 INFO - 'HOME': '/Users/cltbld', 18:30:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:06 INFO - 'LOGNAME': 'cltbld', 18:30:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:06 INFO - 'MOZ_NO_REMOTE': '1', 18:30:06 INFO - 'NO_EM_RESTART': '1', 18:30:06 INFO - 'PAGER': '/bin/cat', 18:30:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:06 INFO - 'PWD': '/builds/slave/test', 18:30:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:06 INFO - 'SHELL': '/bin/bash', 18:30:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DqrkuV5Lfy/Listeners', 18:30:06 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 18:30:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:06 INFO - 'USER': 'cltbld', 18:30:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:06 INFO - 'XPC_FLAGS': '0x0', 18:30:06 INFO - 'XPC_SERVICE_NAME': '0', 18:30:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:30:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:30:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:30:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:30:09 INFO - Installing collected packages: mozsystemmonitor 18:30:09 INFO - Running setup.py install for mozsystemmonitor 18:30:09 INFO - Successfully installed mozsystemmonitor 18:30:09 INFO - Cleaning up... 18:30:09 INFO - Return code: 0 18:30:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:30:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:09 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 0x10fbdca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbe2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a285b6c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa80d30>, '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 0x10f8a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa77b20>, '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.XkzYqhHBRZ/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.DqrkuV5Lfy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:30:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:30:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:30:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XkzYqhHBRZ/Render', 18:30:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:09 INFO - 'HOME': '/Users/cltbld', 18:30:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:09 INFO - 'LOGNAME': 'cltbld', 18:30:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:09 INFO - 'MOZ_NO_REMOTE': '1', 18:30:09 INFO - 'NO_EM_RESTART': '1', 18:30:09 INFO - 'PAGER': '/bin/cat', 18:30:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:09 INFO - 'PWD': '/builds/slave/test', 18:30:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:09 INFO - 'SHELL': '/bin/bash', 18:30:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DqrkuV5Lfy/Listeners', 18:30:09 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 18:30:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:09 INFO - 'USER': 'cltbld', 18:30:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:09 INFO - 'XPC_FLAGS': '0x0', 18:30:09 INFO - 'XPC_SERVICE_NAME': '0', 18:30:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:09 INFO - Downloading/unpacking blobuploader==1.2.4 18:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:12 INFO - Downloading blobuploader-1.2.4.tar.gz 18:30:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:30:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:30:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:30:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:30:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:13 INFO - Downloading docopt-0.6.1.tar.gz 18:30:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:30:13 INFO - Installing collected packages: blobuploader, requests, docopt 18:30:13 INFO - Running setup.py install for blobuploader 18:30:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:30:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:30:13 INFO - Running setup.py install for requests 18:30:13 INFO - Running setup.py install for docopt 18:30:14 INFO - Successfully installed blobuploader requests docopt 18:30:14 INFO - Cleaning up... 18:30:14 INFO - Return code: 0 18:30:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:30:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:14 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 0x10fbdca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbe2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a285b6c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa80d30>, '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 0x10f8a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa77b20>, '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.XkzYqhHBRZ/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.DqrkuV5Lfy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:30:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:30:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:30:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XkzYqhHBRZ/Render', 18:30:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:14 INFO - 'HOME': '/Users/cltbld', 18:30:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:14 INFO - 'LOGNAME': 'cltbld', 18:30:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:14 INFO - 'MOZ_NO_REMOTE': '1', 18:30:14 INFO - 'NO_EM_RESTART': '1', 18:30:14 INFO - 'PAGER': '/bin/cat', 18:30:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:14 INFO - 'PWD': '/builds/slave/test', 18:30:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:14 INFO - 'SHELL': '/bin/bash', 18:30:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DqrkuV5Lfy/Listeners', 18:30:14 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 18:30:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:14 INFO - 'USER': 'cltbld', 18:30:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:14 INFO - 'XPC_FLAGS': '0x0', 18:30:14 INFO - 'XPC_SERVICE_NAME': '0', 18:30:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:30:14 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-g5p6aj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:30:14 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-FPy39T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:30:14 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-b0XFrw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:30:14 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-2UJTQ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:30:14 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-E1lxwX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:30:15 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-GX3F1K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:30:15 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-c6LzF8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:30:15 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-AHR_gS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:30:15 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-DSzUNZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:30:15 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-OmIojs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:30:15 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-9Xdo5W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:30:15 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-9bUmO6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:30:15 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-9VzPsN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:30:15 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-kwBBuM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:30:15 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-0nD5_q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:30:16 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-BXGTkC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:30:16 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-FiTrbo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:30:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:30:16 INFO - Running setup.py install for manifestparser 18:30:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:30:16 INFO - Running setup.py install for mozcrash 18:30:16 INFO - Running setup.py install for mozdebug 18:30:16 INFO - Running setup.py install for mozdevice 18:30:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:30:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:30:16 INFO - Running setup.py install for mozfile 18:30:17 INFO - Running setup.py install for mozhttpd 18:30:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:30:17 INFO - Running setup.py install for mozinfo 18:30:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:30:17 INFO - Running setup.py install for mozInstall 18:30:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:30:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:30:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:30:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:30:17 INFO - Running setup.py install for mozleak 18:30:17 INFO - Running setup.py install for mozlog 18:30:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:30:17 INFO - Running setup.py install for moznetwork 18:30:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:30:17 INFO - Running setup.py install for mozprocess 18:30:18 INFO - Running setup.py install for mozprofile 18:30:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:30:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:30:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:30:18 INFO - Running setup.py install for mozrunner 18:30:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:30:18 INFO - Running setup.py install for mozscreenshot 18:30:18 INFO - Running setup.py install for moztest 18:30:18 INFO - Running setup.py install for mozversion 18:30:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:30:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:30:18 INFO - Cleaning up... 18:30:18 INFO - Return code: 0 18:30:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:30:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:18 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 0x10fbdca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbe2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a285b6c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa80d30>, '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 0x10f8a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa77b20>, '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.XkzYqhHBRZ/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.DqrkuV5Lfy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:30:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:30:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:30:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XkzYqhHBRZ/Render', 18:30:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:18 INFO - 'HOME': '/Users/cltbld', 18:30:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:18 INFO - 'LOGNAME': 'cltbld', 18:30:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:18 INFO - 'MOZ_NO_REMOTE': '1', 18:30:18 INFO - 'NO_EM_RESTART': '1', 18:30:18 INFO - 'PAGER': '/bin/cat', 18:30:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:18 INFO - 'PWD': '/builds/slave/test', 18:30:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:18 INFO - 'SHELL': '/bin/bash', 18:30:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DqrkuV5Lfy/Listeners', 18:30:18 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 18:30:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:18 INFO - 'USER': 'cltbld', 18:30:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:18 INFO - 'XPC_FLAGS': '0x0', 18:30:18 INFO - 'XPC_SERVICE_NAME': '0', 18:30:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:30:19 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-Qxyu4O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:30:19 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-k1IkcK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:30:19 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-Wt6Spm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:30:19 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-V1PQTH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:30:19 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-0K1evn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:30:19 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-rFZ79D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:30:19 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-RVenby-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:30:19 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-xqJSOH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:30:19 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-6B_tpa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:30:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:30:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:30:20 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-s6Vxx4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:30:20 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)) 18:30:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:30:20 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-vziowI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:30:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:30:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:30:20 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-QoVzL4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:30:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:30:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:30:20 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-hn8VpT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:30:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:30:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:30:20 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-YUnSdy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:30:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:30:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:30:20 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-WNgMm0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:30:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:30:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:30:20 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-gm_89y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:30:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:30:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:30:20 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-HBE7wV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:30:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:30:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:30:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:30:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:30:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:30:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:30:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:30:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:23 INFO - Downloading blessings-1.6.tar.gz 18:30:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:30:23 INFO - Installing collected packages: blessings 18:30:23 INFO - Running setup.py install for blessings 18:30:23 INFO - Successfully installed blessings 18:30:23 INFO - Cleaning up... 18:30:23 INFO - Return code: 0 18:30:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:30:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:30:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:30:23 INFO - Reading from file tmpfile_stdout 18:30:23 INFO - Current package versions: 18:30:23 INFO - blessings == 1.6 18:30:23 INFO - blobuploader == 1.2.4 18:30:23 INFO - docopt == 0.6.1 18:30:23 INFO - manifestparser == 1.1 18:30:23 INFO - mozInstall == 1.12 18:30:23 INFO - mozcrash == 0.17 18:30:23 INFO - mozdebug == 0.1 18:30:23 INFO - mozdevice == 0.48 18:30:23 INFO - mozfile == 1.2 18:30:23 INFO - mozhttpd == 0.7 18:30:23 INFO - mozinfo == 0.9 18:30:23 INFO - mozleak == 0.1 18:30:23 INFO - mozlog == 3.2 18:30:23 INFO - moznetwork == 0.27 18:30:23 INFO - mozprocess == 0.22 18:30:23 INFO - mozprofile == 0.28 18:30:23 INFO - mozrunner == 6.11 18:30:23 INFO - mozscreenshot == 0.1 18:30:23 INFO - mozsystemmonitor == 0.3 18:30:23 INFO - moztest == 0.7 18:30:23 INFO - mozversion == 1.4 18:30:23 INFO - psutil == 3.1.1 18:30:23 INFO - requests == 1.2.3 18:30:23 INFO - wsgiref == 0.1.2 18:30:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:30:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:23 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 0x10fbdca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbe2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a285b6c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa80d30>, '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 0x10f8a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa77b20>, '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.XkzYqhHBRZ/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.DqrkuV5Lfy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:30:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:30:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:30:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XkzYqhHBRZ/Render', 18:30:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:23 INFO - 'HOME': '/Users/cltbld', 18:30:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:23 INFO - 'LOGNAME': 'cltbld', 18:30:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:23 INFO - 'MOZ_NO_REMOTE': '1', 18:30:23 INFO - 'NO_EM_RESTART': '1', 18:30:23 INFO - 'PAGER': '/bin/cat', 18:30:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:23 INFO - 'PWD': '/builds/slave/test', 18:30:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:23 INFO - 'SHELL': '/bin/bash', 18:30:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DqrkuV5Lfy/Listeners', 18:30:23 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 18:30:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:23 INFO - 'USER': 'cltbld', 18:30:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:23 INFO - 'XPC_FLAGS': '0x0', 18:30:23 INFO - 'XPC_SERVICE_NAME': '0', 18:30:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:30:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:30:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:30:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:30:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:30:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:30:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:30:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:30:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:30:24 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:30:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:30:24 INFO - Cleaning up... 18:30:24 INFO - Return code: 0 18:30:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:30:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:24 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 0x10fbdca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbe2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a285b6c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa80d30>, '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 0x10f8a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa77b20>, '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.XkzYqhHBRZ/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.DqrkuV5Lfy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:30:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:30:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:30:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XkzYqhHBRZ/Render', 18:30:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:24 INFO - 'HOME': '/Users/cltbld', 18:30:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:24 INFO - 'LOGNAME': 'cltbld', 18:30:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:24 INFO - 'MOZ_NO_REMOTE': '1', 18:30:24 INFO - 'NO_EM_RESTART': '1', 18:30:24 INFO - 'PAGER': '/bin/cat', 18:30:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:24 INFO - 'PWD': '/builds/slave/test', 18:30:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:24 INFO - 'SHELL': '/bin/bash', 18:30:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DqrkuV5Lfy/Listeners', 18:30:24 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 18:30:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:24 INFO - 'USER': 'cltbld', 18:30:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:24 INFO - 'XPC_FLAGS': '0x0', 18:30:24 INFO - 'XPC_SERVICE_NAME': '0', 18:30:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:24 INFO - Downloading/unpacking jsonschema 18:30:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:30:26 INFO - Downloading/unpacking functools32 (from jsonschema) 18:30:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:27 INFO - Downloading functools32-3.2.3-2.tar.gz 18:30:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:30:27 INFO - warning: no files found matching '*.txt' 18:30:27 INFO - no previously-included directories found matching 'build' 18:30:27 INFO - no previously-included directories found matching 'dist' 18:30:27 INFO - no previously-included directories found matching '.git*' 18:30:27 INFO - Installing collected packages: jsonschema, functools32 18:30:27 INFO - Running setup.py install for functools32 18:30:27 INFO - warning: no files found matching '*.txt' 18:30:27 INFO - no previously-included directories found matching 'build' 18:30:27 INFO - no previously-included directories found matching 'dist' 18:30:27 INFO - no previously-included directories found matching '.git*' 18:30:27 INFO - Successfully installed jsonschema functools32 18:30:27 INFO - Cleaning up... 18:30:27 INFO - Return code: 0 18:30:27 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:30:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:27 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 0x10fbdca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbe2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a285b6c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa80d30>, '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 0x10f8a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa77b20>, '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.XkzYqhHBRZ/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.DqrkuV5Lfy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:30:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 18:30:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 18:30:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XkzYqhHBRZ/Render', 18:30:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:27 INFO - 'HOME': '/Users/cltbld', 18:30:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:27 INFO - 'LOGNAME': 'cltbld', 18:30:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:27 INFO - 'MOZ_NO_REMOTE': '1', 18:30:27 INFO - 'NO_EM_RESTART': '1', 18:30:27 INFO - 'PAGER': '/bin/cat', 18:30:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:27 INFO - 'PWD': '/builds/slave/test', 18:30:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:27 INFO - 'SHELL': '/bin/bash', 18:30:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DqrkuV5Lfy/Listeners', 18:30:27 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 18:30:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:27 INFO - 'USER': 'cltbld', 18:30:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:27 INFO - 'XPC_FLAGS': '0x0', 18:30:27 INFO - 'XPC_SERVICE_NAME': '0', 18:30:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:28 INFO - Downloading/unpacking flake8 18:30:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:30 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:30:30 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:30:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:30 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:30:30 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:30:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:31 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:30:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:30:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:30:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:30:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:30:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:30:31 INFO - no previously-included directories found matching 'docs/_build' 18:30:31 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:30:31 INFO - Running setup.py install for pep8 18:30:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:30:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:30:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:30:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:30:31 INFO - no previously-included directories found matching 'docs/_build' 18:30:32 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:30:32 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:30:32 INFO - Cleaning up... 18:30:32 INFO - Return code: 0 18:30:32 INFO - Running post-action listener: _resource_record_post_action 18:30:32 INFO - Running post-action listener: _start_resource_monitoring 18:30:32 INFO - Starting resource monitoring. 18:30:32 INFO - ##### 18:30:32 INFO - ##### Finished create-virtualenv step (success) 18:30:32 INFO - ##### 18:30:32 INFO - ##### 18:30:32 INFO - ##### Running install step. 18:30:32 INFO - ##### 18:30:32 INFO - Running pre-action listener: _resource_record_pre_action 18:30:32 INFO - Running main action method: install 18:30:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:30:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:30:32 INFO - Reading from file tmpfile_stdout 18:30:32 INFO - Detecting whether we're running mozinstall >=1.0... 18:30:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:30:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:30:32 INFO - Reading from file tmpfile_stdout 18:30:32 INFO - Output received: 18:30:32 INFO - Usage: mozinstall [options] installer 18:30:32 INFO - Options: 18:30:32 INFO - -h, --help show this help message and exit 18:30:32 INFO - -d DEST, --destination=DEST 18:30:32 INFO - Directory to install application into. [default: 18:30:32 INFO - "/builds/slave/test"] 18:30:32 INFO - --app=APP Application being installed. [default: firefox] 18:30:32 INFO - mkdir: /builds/slave/test/build/application 18:30:32 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'] 18:30:32 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 18:31:06 INFO - Reading from file tmpfile_stdout 18:31:06 INFO - Output received: 18:31:06 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:31:06 INFO - Running post-action listener: _resource_record_post_action 18:31:06 INFO - ##### 18:31:06 INFO - ##### Finished install step (success) 18:31:06 INFO - ##### 18:31:06 INFO - ##### 18:31:06 INFO - ##### Running run-tests step. 18:31:06 INFO - ##### 18:31:06 INFO - Running pre-action listener: _resource_record_pre_action 18:31:06 INFO - Running main action method: run_tests 18:31:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:31:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:31:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:31:06 INFO - 2016-05-20 18:31:06.016 screenresolution[2234:30193] starting screenresolution argv=screenresolution get 18:31:06 INFO - 2016-05-20 18:31:06.019 screenresolution[2234:30193] Display 0: 1600x1200x32@60 18:31:06 INFO - 2016-05-20 18:31:06.024 screenresolution[2235:30196] starting screenresolution argv=screenresolution list 18:31:06 INFO - Available Modes on Display 0 18:31:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:31:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:31:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:31:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:31:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:31:06 INFO - Intel Iris: 18:31:06 INFO - Chipset Model: Intel Iris 18:31:06 INFO - Type: GPU 18:31:06 INFO - Bus: Built-In 18:31:06 INFO - VRAM (Dynamic, Max): 1536 MB 18:31:06 INFO - Vendor: Intel (0x8086) 18:31:06 INFO - Device ID: 0x0a2e 18:31:06 INFO - Revision ID: 0x0009 18:31:06 INFO - Displays: 18:31:06 INFO - Display: 18:31:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:31:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:31:06 INFO - Display Serial Number: 1600x1200 60 18:31:06 INFO - Main Display: Yes 18:31:06 INFO - Mirror: Off 18:31:06 INFO - Online: Yes 18:31:06 INFO - Rotation: Supported 18:31:06 INFO - Return code: 0 18:31:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:31:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:31:06 INFO - Python 2.7.3 18:31:06 INFO - Return code: 0 18:31:06 INFO - grabbing minidump binary from tooltool 18:31:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:06 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 0x10fa80d30>, '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 0x10f8a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa77b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:31:06 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:31:06 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 18:31:06 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 18:31:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:31:06 INFO - Return code: 0 18:31:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:31:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:31:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:31:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:31:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:31:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:31:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:31:07 INFO - Return code: 0 18:31:07 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/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0336', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 18:31:07 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/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0336 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 18:31:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XkzYqhHBRZ/Render', 18:31:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:07 INFO - 'HOME': '/Users/cltbld', 18:31:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:07 INFO - 'LOGNAME': 'cltbld', 18:31:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:31:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:31:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:07 INFO - 'MOZ_NO_REMOTE': '1', 18:31:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:31:07 INFO - 'NO_EM_RESTART': '1', 18:31:07 INFO - 'PAGER': '/bin/cat', 18:31:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:07 INFO - 'PWD': '/builds/slave/test', 18:31:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:31:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:07 INFO - 'SHELL': '/bin/bash', 18:31:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DqrkuV5Lfy/Listeners', 18:31:07 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 18:31:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:07 INFO - 'USER': 'cltbld', 18:31:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:07 INFO - 'XPC_FLAGS': '0x0', 18:31:07 INFO - 'XPC_SERVICE_NAME': '0', 18:31:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:07 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/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0336', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 18:31:07 INFO - mozversion application_buildid: 20160520162836 18:31:07 INFO - mozversion application_changeset: 9804d9410b32b53e7fc6a497a6c3d731a78495a6 18:31:07 INFO - mozversion application_display_name: Nightly 18:31:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:31:07 INFO - mozversion application_name: Firefox 18:31:07 INFO - mozversion application_remotingname: firefox 18:31:07 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 18:31:07 INFO - mozversion application_vendor: Mozilla 18:31:07 INFO - mozversion application_version: 49.0a1 18:31:07 INFO - mozversion platform_buildid: 20160520162836 18:31:07 INFO - mozversion platform_changeset: 9804d9410b32b53e7fc6a497a6c3d731a78495a6 18:31:07 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 18:31:07 INFO - mozversion platform_version: 49.0a1 18:31:07 INFO - using testdate: 1463794267 18:31:07 INFO - actual date: 1463794267 18:31:07 INFO - starting webserver on 'localhost:51902' 18:31:07 INFO - SUITE-START | Running 2 tests 18:31:07 INFO - TEST-START | dromaeo_css 18:31:07 INFO - operating with platform_type : mac_ 18:31:07 INFO - Initialising browser for dromaeo_css test... 18:31:07 INFO - TEST-INFO | started process 2253 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/tmpMkeQhv/profile http://localhost:51902/getInfo.html) 18:31:10 INFO - PROCESS | 2253 | __metrics Screen width/height:1600/1200 18:31:10 INFO - PROCESS | 2253 | colorDepth:24 18:31:10 INFO - PROCESS | 2253 | Browser inner width/height: 1024/689 18:31:10 INFO - PROCESS | 2253 | __metrics 18:31:10 ERROR - PROCESS | 2253 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 18:31:11 INFO - TEST-INFO | 2253: exit 0 18:31:11 INFO - Browser initialized. 18:31:11 INFO - Running cycle 1/1 for dromaeo_css test... 18:31:11 INFO - TEST-INFO | started process 2256 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/tmpMkeQhv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:31:23 INFO - PROCESS | 2256 | RSS: Main: 249909248 18:31:23 INFO - PROCESS | 2256 | 18:32:12 INFO - PROCESS | 2256 | 1463794332956 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 18:34:24 INFO - PROCESS | 2256 | Cycle 1(1): loaded http://localhost:51902/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51902/tests/dromaeo/cssquery-ext.html) 18:34:25 INFO - PROCESS | 2256 | RSS: Main: 261115904 18:34:25 INFO - PROCESS | 2256 | 18:37:27 INFO - PROCESS | 2256 | Cycle 1(1): loaded http://localhost:51902/tests/dromaeo/cssquery-ext.html (next: http://localhost:51902/tests/dromaeo/cssquery-jquery.html) 18:37:28 INFO - PROCESS | 2256 | RSS: Main: 273608704 18:37:28 INFO - PROCESS | 2256 | 18:40:30 INFO - PROCESS | 2256 | Cycle 1(1): loaded http://localhost:51902/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51902/tests/dromaeo/cssquery-mootools.html) 18:40:30 INFO - PROCESS | 2256 | RSS: Main: 275939328 18:40:30 INFO - PROCESS | 2256 | 18:43:32 INFO - PROCESS | 2256 | Cycle 1(1): loaded http://localhost:51902/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51902/tests/dromaeo/cssquery-prototype.html) 18:43:33 INFO - PROCESS | 2256 | RSS: Main: 277143552 18:43:33 INFO - PROCESS | 2256 | 18:46:36 INFO - PROCESS | 2256 | Cycle 1(1): loaded http://localhost:51902/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51902/tests/dromaeo/cssquery-yui.html) 18:46:36 INFO - PROCESS | 2256 | RSS: Main: 280383488 18:46:36 INFO - PROCESS | 2256 | 18:49:39 INFO - PROCESS | 2256 | Cycle 1(1): loaded http://localhost:51902/tests/dromaeo/cssquery-yui.html (next: http://localhost:51902/tests/dromaeo/cssquery-dojo.html) 18:49:39 INFO - PROCESS | 2256 | RSS: Main: 281063424 18:49:39 INFO - PROCESS | 2256 | 18:49:39 INFO - PROCESS | 2256 | __start_tp_report 18:49:39 INFO - PROCESS | 2256 | _x_x_mozilla_page_load 18:49:39 INFO - PROCESS | 2256 | _x_x_mozilla_page_load_details 18:49:39 INFO - PROCESS | 2256 | |i|pagename|runs| 18:49:39 INFO - PROCESS | 2256 | |0;dojo.html;2900.5214139666955;3487.7209823214143;3493.5633045869267;3511.2275299434123;3539.9646003539956;4718.339432479455;5816.941830581698;5862.677552734598;5866.823995280138;5918.2898052233795;4562.794674239659;5796.50729687977;5811.506021988136;5826.708664566776;5920.851978700534;5235.136202526588;5295.258663787073;5333.093374126388;5382.461753824626;5437.265969094161;5433.891322173574;5459.781608735666;5492.642978206423;5511.476409741068;5516.9448305516835;4692.624590032823;4857.757112144379;5299.6025298102795;5305.151175811851;5350.705711185903;5322.893542129135;5339.092354299778;5346.518813306821;5348.598855085884;5365.356157261153;4679.625629949596;5270.604704647167;5318.095923692981;5351.063563876306;5378.946210537883;5604.775808967636;5685.289338832646;5750.2237197978575;5787.71061446926;5800.332961709415;6256.530760192958;6318.4629306509005;6320.5891617044745;6329.873402531924;6329.905051424233;6237.906431403532;6244.687765611701;6283.560150789401;6341.302456729756;6354.87290254197;5439.292891924025;5958.314793798769;5978.013627751459;5989.431004054652;6030.577859549833;5751.511121554715;6285.937140628626;6313.936860631335;6358.936410635927;6375.521835862329;6114.145302971479;6140.381550880983;6169.753209871645;6228.727362937237;6264.498840092781;6302.1177035215105;6336.778212762531;6343.682815859189;6349.174607301114;6354.555181137276;6123.112148738408;6215.316315205324;6285.465735412449;6305.96847015762;6326.905096423558;6113.266408030976;6249.25008998923;6261.968690156611;6267.341929097471;6333.303336632966;5074.746262686851;5082.567981721595;5089.745512724349;5098.59211263098;5106.131957567222;4300.0969796343115;4552.613027892667;4552.954470455319;4564.452265728134;4634.142683603544;5733.312002559636;5855.765769369177;5904.881902361929;5933.970330148408;5971.611845230046;5197.922031169535;5306.124489459196;6297.622142671455;6325.082862984841;6339.144215530844;5919.6744179070565;5941.910871336933;5942.405759424109;5954.523638108941;5962.821115366546;4558.361829343895;5427.050266203399;5486.753096110685;5563.248961390161;5589.832305030855;6516.641584712792;6556.3443655634055;6564.540482166202;6566.113574667359;6577;6185.536084793748;6291.370862913672;6301.590396624205;6312.936870631235;6320.936790632035;6418.679066046586;6438.034294855622;6505.349465053457;6546.345365463416;6574.243961944438;6187.288461826947;6209.5032397409;6244.250689917239;6264.248290205204;6277.466415354746;6178.66017369031;6199.597026193283;6276.686165691788;6299.181106456132;6312.936870631235;6121.270068921507;6135.785247516315;6180.134781130555;6215.254169499689;6307.873842523216;4728.739919304334;6124.969375153096;6207.6585787782105;6213.937860621336;6240.563160578898;4856.660033797601;5251.739582500249;5918.2898052234;6061.090836374579;6064.272287325372;6134.828431494943;6174.845628859135;6194;6228.906566401598;6247.718852651732;5975.581709280309;6131.816045518642;6145;6145.539084568764;6175.722092505758 18:49:39 INFO - PROCESS | 2256 | |1;ext.html;490.0370771434131;522.3209827224607;538.8845090662326;541.4855886907436;588.8939990801659;34690.479642805316;38840;39641.60358396415;41997;42026.15947681045;42726;43272;43510;43543.78228108855;43809.342859857046;9458.527073646308;9653.565589548452;9695.36980096295;9730.951345243264;9751;9576.856347154799;9676.903230967706;9692;9704.805903881917;9770.658026969057;9648.421094734304;9739.561719722595;9739.951300243489;9758.609655613767;9766.511674416286;9650;9669.274804389699;9670.032996700344;9727.999999999965;9747.97646247141;9478.241740660731;9583.952080239624;9592;9602.231821454267;9610.038996100404;32716.345673086405;39876.60123398758;40336.39495407571;40340.79829600833;40344.596554034375;2995.4608170529295;3075.200447883544;3097.829619370934;3200.679932006804;3293.604767427924;24268.393290167885;29930.251743706365;30204.94282700117;30340.393192136253;30402;2671.6393286906214;2747.2582402751345;2765.778737700999;2774.7918906082123;2776.41695243999;412827;474147;483127;485768;486484;36101.45847812285;36159.4576081359;36230.81884590613;36417.27165456729;36461;36862.631373686454;37159;37245.25509489849;37273.62726372756;37342;15187.696246075016;15377.308021138851;15434.15112168819;15451.922740386226;15456.459023934109;12548.372581370895;12947.5468358607;13022.088453808332;13033.087683862228;13035.869641303656;7778.183290754389;7889.960550197212;8002.759917202493;8098.75703728889;8100.230478104689;3071.769617278713;3221.581194444754;3271.4275001874576;3296.1430028192844;3308.040668206241;898.9190972812347;948.805494873563;958.9136977672043;981.5779214937645;1167.1304877865875;29941.550876736866;30563.38873222523;30693.693063069084;31081.533776993365;31251.68748312488;975.7463059604554;1125.9662210133708;1156.2715489241891;1203.9638810835688;1236.2273579013117;15758.448454304043;15799;15880.682386352208;15948;16194.676106477804;667097;672123;681643;682321;684977;64494;64977.35022649808;65053;65286;65319;1241.8944389726794;1443.5236371997216;1494.140869000329;1495.1851241073423;1496.5285934930462;29770.702292977658;29799;29878;30352.84823575868;30763.230919227197;56243.718781405834;56594.717026414604;57122.42877571171;57137.71431142818;57253;571659;575290;575444;577385;579721;26869.86565067162;27535.586966195922;27802.86098569494;27977.720222798325;28033.738481768778;44643.776781115885;47820.2826957589;48879.266810998575;49452.50547494623;49761.50238497713;30597;40042.79978600088;40508;41173.1765364697;41261;38520.42219366768;38789.6121038786;38907.805460972515;39415.60584394119;39967.2006559873;38172;38809.80595097006;39112.80443597764;39439.2112157761;40740;32277.354452910342;34794.304113918086;34871.47692784661;35409.46885796663;35705.28589428249;21759.782402175777;24686.629700554146;24759.752402475744;25637.61543576885;25774.87112564425 18:49:39 INFO - PROCESS | 2256 | |2;jquery.html;384.7806750152414;385.1276857916818;390.82803566430766;393.75769447393486;411.9217348703746;14678.926605366958;20151.09320080596;21003.844788536626;21544.81503517308;21588.460288492814;21002.68495972558;21152.365429037152;21373;21496.677549836764;21514.354569362906;4896;5076.593872490192;5736.196932429463;5816.302043754756;5943.3462319144855;4649.744264065475;4955.504449555052;5917.5561832862495;5919;5921.111833225029;5665.886682266352;5938;5949.137375080611;5955.404459554054;5957.076653118774;5657.8585535361535;5778.59549831512;5907.734151963151;5922;5936.614120082202;5067.467915868854;5105.132127538291;5120.231965205212;5127.154019586764;5133.358330208724;13077;23056.884715576314;23929.564226146314;24311.392215194763;25749.3562660935;1787.8212178782148;1930.1025023364202;1934.2456441987636;2028.8275496582808;2034.8779073255505;9314.627414903396;9541.904580954171;10309.556662067316;10319.991614632514;14491.710165796625;1712.9314827406888;1733.9219735111951;1761.9471415857545;1807.2228941555127;1814.4647329037834;124530;129416.35291823575;129959;130018.04972925414;130021.34989325088;10940.124790016938;11287.209895307415;11402.714932126763;11756.529738810523;11820.23168494045;12901.096923215286;14556.344964476624;14702.41190352374;14794.112353258839;14859.479918202811;8327.875081873777;8539.444936079135;8583.399162058597;8622.698205562776;8712.259457946018;7351.963240183872;8177.100518942912;8508.829823403621;8518.318534517224;8646.870296945552;4521.751303678264;4532.546745325441;4557.407537020166;4560.33875088117;4575.58819706228;1864.2077117225397;1930.2568511123318;1958.6474434601623;1962.96832778074;1985.702144678281;882.4881568690273;901.5537309032071;1012.731626119079;1016.5730393234859;1087.396494945297;20093.296734614512;20403.38789836307;20499.4875128123;20706.482337941372;20726.48183795387;883.1080608585247;941.5292353823088;950.4914870544226;976.6679329028022;996.4718699089349;10898.182636302308;10932.398718070583;10949.397783121845;10957.397343146044;11002.899710028933;598297.0085149546;604854;605382;611625;623417;121534.3923280378;124527;127745;131137;131745;1019.2101121630648;1030.05749738988;1035.7980193862275;1062.0706881478707;1065.8561094252332;13865.930670346987;18257.08714564293;18417;18432;18625.34811281653;51357.22964155466;52414.73792631165;53785;54224;54254.45745542494;59883;75870.24129758631;75947.24052759401;76388;76824.84762728451;7685.346745526693;7901.446898717265;8041;8126.268635822805;8387.287080598218;9006.729798106067;11481.425928703364;12197.634070977885;12233.510659573518;12407.317597532223;8183.918160818315;9875.851862222216;10657.680269591925;10718.678439646823;10865.293755906157;8911.688090916787;10450.581976720847;10644.946775266075;10789.730256743644;11024.834627480433;10811.45942702846;10865.456727163451;11002;11148.244337734594;11163.33020018769;7699.153093159636;8055.516668999879;8057.113717491188;8059.722263640866;8224.588770561568;8045.959770201346;10281.640142594973;10614.203934705083;10916.399598022184;11132.554697811996 18:49:39 INFO - PROCESS | 2256 | |3;mootools.html;1460.620238737928;1567.2790516362472;1820.7359932809743;1932.4202739178252;1970.5270735023598;8145.592720363988;8658.870116948236;8732.825343493127;9165.816683666322;9213.03263157368;8721.694740684077;8785;8936.64253429862;8945.150210729986;8954.641814327419;1974.4668939386322;2396.580598395283;2411.9276421707377;2413.3122060212845;2414.9396265093424;2129.2121914891513;2280.646499792535;2399.0523743121544;2402.159244264511;2411.8311718179734;2396.3649632847223;2402.0511897800357;2407.9277621671376;2420.4190994161363;2432.1244352033254;2386.009805930537;2397.3287479505766;2409.084547871806;2422.9757702422926;2431.9513609727705;2361.681173041635;2382.070992312982;2392.79661228794;2400.2799160251857;2421.346236516131;8812.559372031374;8848.778780530474;8862.335324850597;8891.644334226623;8979.910200897973;5264.2893209416625;5378.596605254583;5438.619296649236;5480.862978425571;5490.039243132475;3316.8507417166284;3535.982320088409;3567.9840889897123;3593.7125029997537;3606.170580766412;986;1021.7701017271129;1025.9692209233735;1031.339942436841;1038.2835843268122;11734.647960561197;11924.582639607659;11950.760984780256;11974.88025119755;11978.401079945968;4081.163361510883;4128.896777580585;4762.999770048259;4776.593989510861;4863.8540843774745;9905.306628535933;10056.396616203052;10061.899381006097;10068.194544436428;10069.295149339476;5317.521423071942;5352.330958630171;5387.9461205388225;5408.188771684278;5420.972895135499;4454;4549.681522293474;4919.950800491949;4974.626902982219;4984;3948.6643635290748;3958.9010274743364;3970.7021973351534;4050.6151915567975;4094.570070142592;1279.9168054076456;1301.557470460048;1302.5831733845268;1310.92134471932;1312.566852938547;857.2756021162108;875.3653601139123;876.4741155306892;908.8318661047595;920.1350730313484;5658.575606829504;5665.575081868877;5978.222831031938;5983;6015.3383127855905;915.1443400420628;915.3318077803241;934.4953724988566;940.8212439636447;960.846264597675;3802.6007269236334;3902.843886244518;3930.7051971102283;3950.7827069510877;3985.0236692010644;10550.472476376304;10633.202509811796;10887;10895.727606809734;10948.39783811882;10723.410212438392;10740.838887416696;10844.72888177786;10848.349099054081;10855.023047925724;7997.560134192675;8100.756977290691;8300.792480187924;8305;8313.667453302041;5634.605577609528;5710.543156547549;5773.3072031356505;5793.333766616893;5847.26909136358;10041.196704263788;10202.540885660312;10218.131458826083;10220;10247.180225581786;9906.405615663083;10018.248631352531;10384;10405.895941040493;10432.843507347245;5429.7556609953435;6071.241094863142;6421.133147025092;6889.517733758589;7029.648517573998;8473.5339556325;8512.659493620185;8536.44513106646;8614.78463038429;8623.611937462703;7448.366888814511;7591.658375373011;7599.240075992357;7669.080326191541;7696.384289256958;7433;7433.145188303198;7498.700051997859;7576.469647124869;7646.044244469442;7036.894446583203;7089.255628158969;7094.716211351488;7112.431005519443;7112.857742845011;4712.293156026623;4762.76186190696;4843.830465933675;4899.779509922133;5018.899622007466;4856.271559266138;4913.140200464976;4981.402231732215;4994.575461085703;5102.948970510248 18:49:39 INFO - PROCESS | 2256 | |4;prototype.html;313.79760054764677;314.6365947330833;318.9872405103796;320.79469139750563;322.61930921512607;4663.370444989925;4695.67130300879;5556.916401301748;5581.358143813458;5608.915866262009;5371.731413429333;5493.340799104103;5503.394626591072;5513.228148059265;5541.5843811714185;3342.5487559179564;3883.3107598194874;3980.522337319526;4149.3983372410985;4191.81136848841;4161.708680392374;4189.518205406372;4201.6008479194425;4214.452121224236;4320.524742278347;3262.6411094779555;4170.937435938464;4188.455500784894;4194.349875769245;4240.872773816791;3513.912152196203;3825.7321987460887;4133.731307465006;4160.45914031177;4200.642945349634;3378.6452422495777;3553.875614353485;3556.199855032623;3557.092941299952;3562.7327950403824;5285.6300058995885;5289.233061206142;5324;5351.3043304370185;5360.91958620621;4052.865197744636;4625.58369746721;4715.434147902273;4773.260144677581;4796.664233503656;5080.339555857715;5182.922256166161;5350.742575494755;5835.737391817378;5844.386339434383;3535.0101971448034;3708.311474181224;3786.845012271256;3963.2667956427863;4026.798660066985;4738.739369334685;5749.361431991862;5899.758091925041;5925.2889653241455;6162.198914141133;3988.9202215956097;3994.540627827837;3995.820188091543;4018.1160144768105;4029.7582145071387;4035.9192816143513;4051.6353528182603;4061.3095773718537;4063.329550624114;4080.755154690728;4746.288056791509;4804.6876952997945;4816.20532612115;4825.541573550507;4833.492483289274;5022.698638081727;5385;5393;5886.587938844325;5890.764369425175;1286.6075846866609;1348.191085348783;1375.312343828086;1380.4685196199534;1385.5496963486908;824.7938015496126;846.3356265331685;854.2269246332079;855.3584811391456;901.8737376767258;5171.21593050397;5332.813351532678;5335.199720041947;5364.839054828361;5393.5954803389905;5309.33633295838;5888.793892213752;5890.087036509262;5924.851878703067;5950.375210602824;4513.323001549728;5029.801847319804;5290.65610735301;5300;5365.839024829262;2661.148432501581;2831.768180841341;2876.6835648078695;3131.248500359897;3140.544621029938;5455.645383050089;6008.888355654218;6101.511879049665;6188.350223226496;6193.690315484298;5635.239242702182;6186.566940314135;6207.317195108534;6259.593126446766;6299;5524.392316845144;6341.619502829845;6341.714622841983;6357.554971151975;6371.745130194833;5604.075327570905;5852.473277404883;5905.576657199959;5990.610610310316;6073.240844894388;5584;5621.048949301649;6039.969800150971;6060.848478787889;6068.544859135671;5595.5523558116065;5779.479846813807;6320.5891617044745;6353.522126370812;6364.840878978062;4082.183563287295;4117.547069822377;4119.938200926928;4126.019510634852;4141.503019637663;5978.027251007245;5983.115318674603;6032.276126864629;6053.364396738279;6082.178905847654;5151.201563757623;5653.349864765604;5701.771929122789;5784.929787989236;5796.652200867961;5741.019348324821;5748.930385061458;5775.884482310246;5784.21913041734;5794.594378393472;5148.948510514847;5410.0261952847295;5415.918761218663;5678.488935995781;5688.232088667977;5615.2700148980375;5641;5847.1229315603;5855.9121613176685;5906.527477801851;5449.7275136243825;5740;5746.770129194785;5747.471112710394;5783.479486846204 18:49:39 INFO - PROCESS | 2256 | |5;yui.html;647.2362612117705;672.7880717573964;697.9092717946669;709.6309918842202;723.5152447859933;15106.924465377671;15451.690966180699;15609.531714048582;15620.21898905053;15623;14188.574342769733;14469.927650361733;14475.638109047279;14526.854731452682;14560.199189044599;738.4978214813924;901.0944001278722;925.6760133953096;926.6571368593629;928.2852203803068;732.7472022152349;925.9537023148815;935.0555938502127;938.5307346326837;945.9668911588096;977;984.8522721591783;988.6786794291847;989.539863963257;989.7080361293399;951.3388195204336;954.2699834626517;956.3305686019814;977.5991843344194;979.2606582030556;718.9532680375759;726.0561270348578;729.5914287998685;732.0483371616876;733.435254853765;14168.433262669481;14255.643608909757;14277.500287489887;14277.571672849832;14338.78491822613;12454.439550220262;12587.874121258854;12626.116171867972;12732.299723515203;12755.362231888368;8121.959390203011;9715.319927605071;9840.507974601313;9901.405915645084;10489.737756556071;4012.578679238667;4017.31705610047;4026.8993275168064;4143.047099167208;4150.3566947123245;83499;83984;84350;85431.71852422156;87307.56346218292;9161.358704890727;9236.953815230881;9243.352965292364;9283.25733941283;9702.563384647567;20655.483612909797;20761.688574671392;20762.48093797637;20796.856172910637;20807;6208.689565521706;7156.177039640508;7241.456890733216;7331.266873312733;7331.523450975669;3845.3270677631303;3883.126296583246;3895.182011777502;3897.2205558888336;3933.862314819025;3341.933161336808;3367.898963031064;3427.4344733118755;3431.039308993486;3434.1242983039165;623.183629445428;623.4482483002575;631.9462845658171;636.8025911967305;638.8542223806915;520.3651545114953;555.0093083845317;557.7685260616798;582.5601670738565;587.6532845621105;7281.890771638429;7664.11862635804;7731.149573546905;7974.282314591715;8106.027276726831;502.47240397583124;531.7394476706386;546.5572885962383;564.4045531963818;568.2470726287603;3109.6628449766367;3236.724878385364;3467.878624248139;3683.281760056817;3685.7235707322056;81548;83050.58474707708;84086;85330;85759.57120214359;69405;77314.2268577307;77894.05264868311;80669;81181.59409202916;71036;71478.42607869586;71590.64204678943;71829;71972.92040619395;7292.380147687506;7338.706451742084;7344.632768361667;7364.815879603053;7452.292032256927;33076.83461582677;34447.62209511591;41933.58066419297;43414.34878476762;45447.54552454433;54508.18237726516;61689.69155154195;61858.69070654618;62221.68889155706;62405;9155.77110572241;10987.505562249878;11154.609588664358;11215.046721028477;11430.714232144262;10279.588816447258;12008.699782505508;12067.336296503425;12303.75392492163;12888.355582221038;9211.309151813775;10517.684469465928;10684.83972740425;11394.886051139383;11591.710207244887;11301.71745706364;11418.942905285421;11777.352245626573;11798.469068891749;11825.172237943261;9884.209263258777;10094.39433633955;11331.943340283246;11748.011649772567;11995.520179192734;9585.9041409585;9602.08780165883;9613;9657.227421806097;10013.198944084601;9292.6282948684;9317.428035821695;9566.90433095688;9579.808403832023;9608.9039109608 18:49:39 INFO - PROCESS | 2256 | __end_tp_report 18:49:39 INFO - PROCESS | 2256 | __start_cc_report 18:49:39 INFO - PROCESS | 2256 | _x_x_mozilla_cycle_collect,424 18:49:39 INFO - PROCESS | 2256 | __end_cc_report 18:49:39 INFO - PROCESS | 2256 | __startTimestamp1463795379771__endTimestamp 18:49:39 INFO - PROCESS | 2256 | 18:49:39 INFO - PROCESS | 2256 | ------- Summary: start ------- 18:49:39 INFO - PROCESS | 2256 | Number of tests: 6 18:49:39 INFO - PROCESS | 2256 | 18:49:39 INFO - PROCESS | 2256 | [#0] dojo.html Cycles:165 Average:5788.78 Median:6030.58 stddev:680.55 (11.3%) stddev-sans-first:643.82 18:49:39 INFO - PROCESS | 2256 | Values: 2900.5 3487.7 3493.6 3511.2 3540.0 4718.3 5816.9 5862.7 5866.8 5918.3 4562.8 5796.5 5811.5 5826.7 5920.9 5235.1 5295.3 5333.1 5382.5 5437.3 5433.9 5459.8 5492.6 5511.5 5516.9 4692.6 4857.8 5299.6 5305.2 5350.7 5322.9 5339.1 5346.5 5348.6 5365.4 4679.6 5270.6 5318.1 5351.1 5378.9 5604.8 5685.3 5750.2 5787.7 5800.3 6256.5 6318.5 6320.6 6329.9 6329.9 6237.9 6244.7 6283.6 6341.3 6354.9 5439.3 5958.3 5978.0 5989.4 6030.6 5751.5 6285.9 6313.9 6358.9 6375.5 6114.1 6140.4 6169.8 6228.7 6264.5 6302.1 6336.8 6343.7 6349.2 6354.6 6123.1 6215.3 6285.5 6306.0 6326.9 6113.3 6249.3 6262.0 6267.3 6333.3 5074.7 5082.6 5089.7 5098.6 5106.1 4300.1 4552.6 4553.0 4564.5 4634.1 5733.3 5855.8 5904.9 5934.0 5971.6 5197.9 5306.1 6297.6 6325.1 6339.1 5919.7 5941.9 5942.4 5954.5 5962.8 4558.4 5427.1 5486.8 5563.2 5589.8 6516.6 6556.3 6564.5 6566.1 6577.0 6185.5 6291.4 6301.6 6312.9 6320.9 6418.7 6438.0 6505.3 6546.3 6574.2 6187.3 6209.5 6244.3 6264.2 6277.5 6178.7 6199.6 6276.7 6299.2 6312.9 6121.3 6135.8 6180.1 6215.3 6307.9 4728.7 6125.0 6207.7 6213.9 6240.6 4856.7 5251.7 5918.3 6061.1 6064.3 6134.8 6174.8 6194.0 6228.9 6247.7 5975.6 6131.8 6145.0 6145.5 6175.7 18:49:39 INFO - PROCESS | 2256 | 18:49:39 INFO - PROCESS | 2256 | [#1] ext.html Cycles:180 Average:69290.47 Median:39427.41 stddev:155604.89 (394.7%) stddev-sans-first:155955.66 18:49:39 INFO - PROCESS | 2256 | Values: 490.0 522.3 538.9 541.5 588.9 34690.5 38840.0 39641.6 41997.0 42026.2 42726.0 43272.0 43510.0 43543.8 43809.3 9458.5 9653.6 9695.4 9731.0 9751.0 9576.9 9676.9 9692.0 9704.8 9770.7 9648.4 9739.6 9740.0 9758.6 9766.5 9650.0 9669.3 9670.0 9728.0 9748.0 9478.2 9584.0 9592.0 9602.2 9610.0 32716.3 39876.6 40336.4 40340.8 40344.6 2995.5 3075.2 3097.8 3200.7 3293.6 24268.4 29930.3 30204.9 30340.4 30402.0 2671.6 2747.3 2765.8 2774.8 2776.4 412827.0 474147.0 483127.0 485768.0 486484.0 36101.5 36159.5 36230.8 36417.3 36461.0 36862.6 37159.0 37245.3 37273.6 37342.0 15187.7 15377.3 15434.2 15451.9 15456.5 12548.4 12947.5 13022.1 13033.1 13035.9 7778.2 7890.0 8002.8 8098.8 8100.2 3071.8 3221.6 3271.4 3296.1 3308.0 898.9 948.8 958.9 981.6 1167.1 29941.6 30563.4 30693.7 31081.5 31251.7 975.7 1126.0 1156.3 1204.0 1236.2 15758.4 15799.0 15880.7 15948.0 16194.7 667097.0 672123.0 681643.0 682321.0 684977.0 64494.0 64977.4 65053.0 65286.0 65319.0 1241.9 1443.5 1494.1 1495.2 1496.5 29770.7 29799.0 29878.0 30352.8 30763.2 56243.7 56594.7 57122.4 57137.7 57253.0 571659.0 575290.0 575444.0 577385.0 579721.0 26869.9 27535.6 27802.9 27977.7 28033.7 44643.8 47820.3 48879.3 49452.5 49761.5 30597.0 40042.8 40508.0 41173.2 41261.0 38520.4 38789.6 38907.8 39415.6 39967.2 38172.0 38809.8 39112.8 39439.2 40740.0 32277.4 34794.3 34871.5 35409.5 35705.3 21759.8 24686.6 24759.8 25637.6 25774.9 18:49:39 INFO - PROCESS | 2256 | 18:49:39 INFO - PROCESS | 2256 | [#2] jquery.html Cycles:180 Average:35194.93 Median:21566.64 stddev:101762.72 (471.9%) stddev-sans-first:102014.62 18:49:39 INFO - PROCESS | 2256 | Values: 384.8 385.1 390.8 393.8 411.9 14678.9 20151.1 21003.8 21544.8 21588.5 21002.7 21152.4 21373.0 21496.7 21514.4 4896.0 5076.6 5736.2 5816.3 5943.3 4649.7 4955.5 5917.6 5919.0 5921.1 5665.9 5938.0 5949.1 5955.4 5957.1 5657.9 5778.6 5907.7 5922.0 5936.6 5067.5 5105.1 5120.2 5127.2 5133.4 13077.0 23056.9 23929.6 24311.4 25749.4 1787.8 1930.1 1934.2 2028.8 2034.9 9314.6 9541.9 10309.6 10320.0 14491.7 1712.9 1733.9 1761.9 1807.2 1814.5 124530.0 129416.4 129959.0 130018.0 130021.3 10940.1 11287.2 11402.7 11756.5 11820.2 12901.1 14556.3 14702.4 14794.1 14859.5 8327.9 8539.4 8583.4 8622.7 8712.3 7352.0 8177.1 8508.8 8518.3 8646.9 4521.8 4532.5 4557.4 4560.3 4575.6 1864.2 1930.3 1958.6 1963.0 1985.7 882.5 901.6 1012.7 1016.6 1087.4 20093.3 20403.4 20499.5 20706.5 20726.5 883.1 941.5 950.5 976.7 996.5 10898.2 10932.4 10949.4 10957.4 11002.9 598297.0 604854.0 605382.0 611625.0 623417.0 121534.4 124527.0 127745.0 131137.0 131745.0 1019.2 1030.1 1035.8 1062.1 1065.9 13865.9 18257.1 18417.0 18432.0 18625.3 51357.2 52414.7 53785.0 54224.0 54254.5 59883.0 75870.2 75947.2 76388.0 76824.8 7685.3 7901.4 8041.0 8126.3 8387.3 9006.7 11481.4 12197.6 12233.5 12407.3 8183.9 9875.9 10657.7 10718.7 10865.3 8911.7 10450.6 10644.9 10789.7 11024.8 10811.5 10865.5 11002.0 11148.2 11163.3 7699.2 8055.5 8057.1 8059.7 8224.6 8046.0 10281.6 10614.2 10916.4 11132.6 18:49:39 INFO - PROCESS | 2256 | 18:49:39 INFO - PROCESS | 2256 | [#3] mootools.html Cycles:180 Average:5736.69 Median:4850.05 stddev:3244.08 (66.9%) stddev-sans-first:3237.27 18:49:39 INFO - PROCESS | 2256 | Values: 1460.6 1567.3 1820.7 1932.4 1970.5 8145.6 8658.9 8732.8 9165.8 9213.0 8721.7 8785.0 8936.6 8945.2 8954.6 1974.5 2396.6 2411.9 2413.3 2414.9 2129.2 2280.6 2399.1 2402.2 2411.8 2396.4 2402.1 2407.9 2420.4 2432.1 2386.0 2397.3 2409.1 2423.0 2432.0 2361.7 2382.1 2392.8 2400.3 2421.3 8812.6 8848.8 8862.3 8891.6 8979.9 5264.3 5378.6 5438.6 5480.9 5490.0 3316.9 3536.0 3568.0 3593.7 3606.2 986.0 1021.8 1026.0 1031.3 1038.3 11734.6 11924.6 11950.8 11974.9 11978.4 4081.2 4128.9 4763.0 4776.6 4863.9 9905.3 10056.4 10061.9 10068.2 10069.3 5317.5 5352.3 5387.9 5408.2 5421.0 4454.0 4549.7 4920.0 4974.6 4984.0 3948.7 3958.9 3970.7 4050.6 4094.6 1279.9 1301.6 1302.6 1310.9 1312.6 857.3 875.4 876.5 908.8 920.1 5658.6 5665.6 5978.2 5983.0 6015.3 915.1 915.3 934.5 940.8 960.8 3802.6 3902.8 3930.7 3950.8 3985.0 10550.5 10633.2 10887.0 10895.7 10948.4 10723.4 10740.8 10844.7 10848.3 10855.0 7997.6 8100.8 8300.8 8305.0 8313.7 5634.6 5710.5 5773.3 5793.3 5847.3 10041.2 10202.5 10218.1 10220.0 10247.2 9906.4 10018.2 10384.0 10405.9 10432.8 5429.8 6071.2 6421.1 6889.5 7029.6 8473.5 8512.7 8536.4 8614.8 8623.6 7448.4 7591.7 7599.2 7669.1 7696.4 7433.0 7433.1 7498.7 7576.5 7646.0 7036.9 7089.3 7094.7 7112.4 7112.9 4712.3 4762.8 4843.8 4899.8 5018.9 4856.3 4913.1 4981.4 4994.6 5102.9 18:49:39 INFO - PROCESS | 2256 | 18:49:39 INFO - PROCESS | 2256 | [#4] prototype.html Cycles:180 Average:4718.43 Median:5356.11 stddev:1473.60 (27.5%) stddev-sans-first:1440.17 18:49:39 INFO - PROCESS | 2256 | Values: 313.8 314.6 319.0 320.8 322.6 4663.4 4695.7 5556.9 5581.4 5608.9 5371.7 5493.3 5503.4 5513.2 5541.6 3342.5 3883.3 3980.5 4149.4 4191.8 4161.7 4189.5 4201.6 4214.5 4320.5 3262.6 4170.9 4188.5 4194.3 4240.9 3513.9 3825.7 4133.7 4160.5 4200.6 3378.6 3553.9 3556.2 3557.1 3562.7 5285.6 5289.2 5324.0 5351.3 5360.9 4052.9 4625.6 4715.4 4773.3 4796.7 5080.3 5182.9 5350.7 5835.7 5844.4 3535.0 3708.3 3786.8 3963.3 4026.8 4738.7 5749.4 5899.8 5925.3 6162.2 3988.9 3994.5 3995.8 4018.1 4029.8 4035.9 4051.6 4061.3 4063.3 4080.8 4746.3 4804.7 4816.2 4825.5 4833.5 5022.7 5385.0 5393.0 5886.6 5890.8 1286.6 1348.2 1375.3 1380.5 1385.5 824.8 846.3 854.2 855.4 901.9 5171.2 5332.8 5335.2 5364.8 5393.6 5309.3 5888.8 5890.1 5924.9 5950.4 4513.3 5029.8 5290.7 5300.0 5365.8 2661.1 2831.8 2876.7 3131.2 3140.5 5455.6 6008.9 6101.5 6188.4 6193.7 5635.2 6186.6 6207.3 6259.6 6299.0 5524.4 6341.6 6341.7 6357.6 6371.7 5604.1 5852.5 5905.6 5990.6 6073.2 5584.0 5621.0 6040.0 6060.8 6068.5 5595.6 5779.5 6320.6 6353.5 6364.8 4082.2 4117.5 4119.9 4126.0 4141.5 5978.0 5983.1 6032.3 6053.4 6082.2 5151.2 5653.3 5701.8 5784.9 5796.7 5741.0 5748.9 5775.9 5784.2 5794.6 5148.9 5410.0 5415.9 5678.5 5688.2 5615.3 5641.0 5847.1 5855.9 5906.5 5449.7 5740.0 5746.8 5747.5 5783.5 18:49:39 INFO - PROCESS | 2256 | 18:49:39 INFO - PROCESS | 2256 | [#5] yui.html Cycles:180 Average:17596.84 Median:31514.22 stddev:24770.46 (78.6%) stddev-sans-first:24807.26 18:49:39 INFO - PROCESS | 2256 | Values: 647.2 672.8 697.9 709.6 723.5 15106.9 15451.7 15609.5 15620.2 15623.0 14188.6 14469.9 14475.6 14526.9 14560.2 738.5 901.1 925.7 926.7 928.3 732.7 926.0 935.1 938.5 946.0 977.0 984.9 988.7 989.5 989.7 951.3 954.3 956.3 977.6 979.3 719.0 726.1 729.6 732.0 733.4 14168.4 14255.6 14277.5 14277.6 14338.8 12454.4 12587.9 12626.1 12732.3 12755.4 8122.0 9715.3 9840.5 9901.4 10489.7 4012.6 4017.3 4026.9 4143.0 4150.4 83499.0 83984.0 84350.0 85431.7 87307.6 9161.4 9237.0 9243.4 9283.3 9702.6 20655.5 20761.7 20762.5 20796.9 20807.0 6208.7 7156.2 7241.5 7331.3 7331.5 3845.3 3883.1 3895.2 3897.2 3933.9 3341.9 3367.9 3427.4 3431.0 3434.1 623.2 623.4 631.9 636.8 638.9 520.4 555.0 557.8 582.6 587.7 7281.9 7664.1 7731.1 7974.3 8106.0 502.5 531.7 546.6 564.4 568.2 3109.7 3236.7 3467.9 3683.3 3685.7 81548.0 83050.6 84086.0 85330.0 85759.6 69405.0 77314.2 77894.1 80669.0 81181.6 71036.0 71478.4 71590.6 71829.0 71972.9 7292.4 7338.7 7344.6 7364.8 7452.3 33076.8 34447.6 41933.6 43414.3 45447.5 54508.2 61689.7 61858.7 62221.7 62405.0 9155.8 10987.5 11154.6 11215.0 11430.7 10279.6 12008.7 12067.3 12303.8 12888.4 9211.3 10517.7 10684.8 11394.9 11591.7 11301.7 11418.9 11777.4 11798.5 11825.2 9884.2 10094.4 11331.9 11748.0 11995.5 9585.9 9602.1 9613.0 9657.2 10013.2 9292.6 9317.4 9566.9 9579.8 9608.9 18:49:39 INFO - PROCESS | 2256 | -------- Summary: end -------- 18:49:39 INFO - PROCESS | 2256 | 18:49:40 INFO - TEST-INFO | 2256: exit 0 18:49:40 INFO - TEST-OK | dromaeo_css | took 1112588ms 18:49:40 INFO - TEST-START | kraken 18:49:40 INFO - operating with platform_type : mac_ 18:49:40 INFO - Initialising browser for kraken test... 18:49:40 INFO - TEST-INFO | started process 2262 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/tmprfeOa6/profile http://localhost:51902/getInfo.html) 18:49:43 INFO - PROCESS | 2262 | __metrics Screen width/height:1600/1200 18:49:43 INFO - PROCESS | 2262 | colorDepth:24 18:49:43 INFO - PROCESS | 2262 | Browser inner width/height: 1024/689 18:49:43 INFO - PROCESS | 2262 | __metrics 18:49:43 ERROR - PROCESS | 2262 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 18:49:43 INFO - TEST-INFO | 2262: exit 0 18:49:43 INFO - Browser initialized. 18:49:43 INFO - Running cycle 1/1 for kraken test... 18:49:43 INFO - TEST-INFO | started process 2265 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/tmprfeOa6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:49:55 INFO - PROCESS | 2265 | RSS: Main: 249905152 18:49:55 INFO - PROCESS | 2265 | 18:49:58 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:51902/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51902/tests/kraken/driver.html?testName=audio-beat-detection) 18:49:59 INFO - PROCESS | 2265 | RSS: Main: 261513216 18:49:59 INFO - PROCESS | 2265 | 18:50:02 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:51902/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51902/tests/kraken/driver.html?testName=audio-dft) 18:50:03 INFO - PROCESS | 2265 | RSS: Main: 266305536 18:50:03 INFO - PROCESS | 2265 | 18:50:06 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:51902/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51902/tests/kraken/driver.html?testName=audio-fft) 18:50:07 INFO - PROCESS | 2265 | RSS: Main: 266637312 18:50:07 INFO - PROCESS | 2265 | 18:50:09 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:51902/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51902/tests/kraken/driver.html?testName=audio-oscillator) 18:50:10 INFO - PROCESS | 2265 | RSS: Main: 267288576 18:50:10 INFO - PROCESS | 2265 | 18:50:12 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:51902/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51902/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:50:12 INFO - PROCESS | 2265 | RSS: Main: 267853824 18:50:12 INFO - PROCESS | 2265 | 18:50:15 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:51902/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51902/tests/kraken/driver.html?testName=imaging-darkroom) 18:50:16 INFO - PROCESS | 2265 | RSS: Main: 269475840 18:50:16 INFO - PROCESS | 2265 | 18:50:19 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:51902/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51902/tests/kraken/driver.html?testName=imaging-desaturate) 18:50:20 INFO - PROCESS | 2265 | RSS: Main: 270540800 18:50:20 INFO - PROCESS | 2265 | 18:50:23 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:51902/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51902/tests/kraken/driver.html?testName=json-parse-financial) 18:50:23 INFO - PROCESS | 2265 | RSS: Main: 270495744 18:50:23 INFO - PROCESS | 2265 | 18:50:25 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:51902/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51902/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:50:26 INFO - PROCESS | 2265 | RSS: Main: 269860864 18:50:26 INFO - PROCESS | 2265 | 18:50:28 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:51902/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51902/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:50:28 INFO - PROCESS | 2265 | RSS: Main: 270475264 18:50:28 INFO - PROCESS | 2265 | 18:50:31 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:51902/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51902/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:50:31 INFO - PROCESS | 2265 | RSS: Main: 270266368 18:50:31 INFO - PROCESS | 2265 | 18:50:34 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:51902/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51902/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:50:35 INFO - PROCESS | 2265 | RSS: Main: 270442496 18:50:35 INFO - PROCESS | 2265 | 18:50:38 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:51902/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51902/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:50:39 INFO - PROCESS | 2265 | RSS: Main: 270843904 18:50:39 INFO - PROCESS | 2265 | 18:50:40 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:51902/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51902/tests/kraken/driver.html?testName=ai-astar) 18:50:40 INFO - PROCESS | 2265 | RSS: Main: 272252928 18:50:40 INFO - PROCESS | 2265 | 18:50:40 INFO - PROCESS | 2265 | __start_tp_report 18:50:40 INFO - PROCESS | 2265 | _x_x_mozilla_page_load 18:50:40 INFO - PROCESS | 2265 | _x_x_mozilla_page_load_details 18:50:40 INFO - PROCESS | 2265 | |i|pagename|runs| 18:50:40 INFO - PROCESS | 2265 | |0;ai-astar;116;112;146;152;93;142;169;169;120;126 18:50:40 INFO - PROCESS | 2265 | |1;audio-beat-detection;152;156;172;211;120;157;165;149;174;121 18:50:40 INFO - PROCESS | 2265 | |2;audio-dft;202;210;230;241;245;196;197;196;197;194 18:50:40 INFO - PROCESS | 2265 | |3;audio-fft;68;84;98;196;74;51;58;84;160;86 18:50:40 INFO - PROCESS | 2265 | |4;audio-oscillator;82;89;141;82;90;83;123;80;80;124 18:50:40 INFO - PROCESS | 2265 | |5;imaging-gaussian-blur;77;131;134;86;95;87;89;87;89;99 18:50:40 INFO - PROCESS | 2265 | |6;imaging-darkroom;93;95;95;95;94;95;94;94;94;122 18:50:40 INFO - PROCESS | 2265 | |7;imaging-desaturate;100;91;114;116;89;90;91;91;111;113 18:50:40 INFO - PROCESS | 2265 | |8;json-parse-financial;58;62;59;60;58;57;59;120;57;65 18:50:40 INFO - PROCESS | 2265 | |9;json-stringify-tinderbox;68;69;68;82;78;74;70;70;72;69 18:50:40 INFO - PROCESS | 2265 | |10;stanford-crypto-aes;96;102;109;110;86;86;102;110;112;87 18:50:40 INFO - PROCESS | 2265 | |11;stanford-crypto-ccm;149;154;139;138;145;140;136;138;217;144 18:50:40 INFO - PROCESS | 2265 | |12;stanford-crypto-pbkdf2;168;169;164;169;170;167;169;167;167;164 18:50:40 INFO - PROCESS | 2265 | |13;stanford-crypto-sha256-iterative;69;67;68;67;68;70;67;65;68;63 18:50:40 INFO - PROCESS | 2265 | __end_tp_report 18:50:40 INFO - PROCESS | 2265 | __start_cc_report 18:50:40 INFO - PROCESS | 2265 | _x_x_mozilla_cycle_collect,1069 18:50:40 INFO - PROCESS | 2265 | __end_cc_report 18:50:40 INFO - PROCESS | 2265 | __startTimestamp1463795440958__endTimestamp 18:50:40 INFO - PROCESS | 2265 | 18:50:40 INFO - PROCESS | 2265 | ------- Summary: start ------- 18:50:40 INFO - PROCESS | 2265 | Number of tests: 14 18:50:40 INFO - PROCESS | 2265 | 18:50:40 INFO - PROCESS | 2265 | [#0] ai-astar Cycles:10 Average:134.50 Median:149.00 stddev:25.23 (16.9%) stddev-sans-first:25.86 18:50:40 INFO - PROCESS | 2265 | Values: 116.0 112.0 146.0 152.0 93.0 142.0 169.0 169.0 120.0 126.0 18:50:40 INFO - PROCESS | 2265 | 18:50:40 INFO - PROCESS | 2265 | [#1] audio-beat-detection Cycles:10 Average:157.70 Median:161.00 stddev:26.34 (16.4%) stddev-sans-first:27.86 18:50:40 INFO - PROCESS | 2265 | Values: 152.0 156.0 172.0 211.0 120.0 157.0 165.0 149.0 174.0 121.0 18:50:40 INFO - PROCESS | 2265 | 18:50:40 INFO - PROCESS | 2265 | [#2] audio-dft Cycles:10 Average:210.80 Median:206.00 stddev:20.08 (9.7%) stddev-sans-first:21.05 18:50:40 INFO - PROCESS | 2265 | Values: 202.0 210.0 230.0 241.0 245.0 196.0 197.0 196.0 197.0 194.0 18:50:40 INFO - PROCESS | 2265 | 18:50:40 INFO - PROCESS | 2265 | [#3] audio-fft Cycles:10 Average:95.90 Median:79.00 stddev:46.22 (58.5%) stddev-sans-first:47.91 18:50:40 INFO - PROCESS | 2265 | Values: 68.0 84.0 98.0 196.0 74.0 51.0 58.0 84.0 160.0 86.0 18:50:40 INFO - PROCESS | 2265 | 18:50:40 INFO - PROCESS | 2265 | [#4] audio-oscillator Cycles:10 Average:97.40 Median:82.00 stddev:22.79 (27.8%) stddev-sans-first:23.49 18:50:40 INFO - PROCESS | 2265 | Values: 82.0 89.0 141.0 82.0 90.0 83.0 123.0 80.0 80.0 124.0 18:50:40 INFO - PROCESS | 2265 | 18:50:40 INFO - PROCESS | 2265 | [#5] imaging-gaussian-blur Cycles:10 Average:97.40 Median:88.00 stddev:19.38 (22.0%) stddev-sans-first:19.10 18:50:40 INFO - PROCESS | 2265 | Values: 77.0 131.0 134.0 86.0 95.0 87.0 89.0 87.0 89.0 99.0 18:50:40 INFO - PROCESS | 2265 | 18:50:40 INFO - PROCESS | 2265 | [#6] imaging-darkroom Cycles:10 Average:97.10 Median:94.50 stddev:8.77 (9.3%) stddev-sans-first:9.18 18:50:40 INFO - PROCESS | 2265 | Values: 93.0 95.0 95.0 95.0 94.0 95.0 94.0 94.0 94.0 122.0 18:50:40 INFO - PROCESS | 2265 | 18:50:40 INFO - PROCESS | 2265 | [#7] imaging-desaturate Cycles:10 Average:100.60 Median:89.50 stddev:11.56 (12.9%) stddev-sans-first:12.26 18:50:40 INFO - PROCESS | 2265 | Values: 100.0 91.0 114.0 116.0 89.0 90.0 91.0 91.0 111.0 113.0 18:50:40 INFO - PROCESS | 2265 | 18:50:40 INFO - PROCESS | 2265 | [#8] json-parse-financial Cycles:10 Average:65.50 Median:59.00 stddev:19.31 (32.7%) stddev-sans-first:20.29 18:50:40 INFO - PROCESS | 2265 | Values: 58.0 62.0 59.0 60.0 58.0 57.0 59.0 120.0 57.0 65.0 18:50:40 INFO - PROCESS | 2265 | 18:50:40 INFO - PROCESS | 2265 | [#9] json-stringify-tinderbox Cycles:10 Average:72.00 Median:71.00 stddev:4.69 (6.6%) stddev-sans-first:4.75 18:50:40 INFO - PROCESS | 2265 | Values: 68.0 69.0 68.0 82.0 78.0 74.0 70.0 70.0 72.0 69.0 18:50:40 INFO - PROCESS | 2265 | 18:50:40 INFO - PROCESS | 2265 | [#10] stanford-crypto-aes Cycles:10 Average:100.00 Median:99.00 stddev:10.59 (10.7%) stddev-sans-first:11.14 18:50:40 INFO - PROCESS | 2265 | Values: 96.0 102.0 109.0 110.0 86.0 86.0 102.0 110.0 112.0 87.0 18:50:40 INFO - PROCESS | 2265 | 18:50:40 INFO - PROCESS | 2265 | [#11] stanford-crypto-ccm Cycles:10 Average:150.00 Median:144.50 stddev:24.20 (16.7%) stddev-sans-first:25.67 18:50:40 INFO - PROCESS | 2265 | Values: 149.0 154.0 139.0 138.0 145.0 140.0 136.0 138.0 217.0 144.0 18:50:40 INFO - PROCESS | 2265 | 18:50:40 INFO - PROCESS | 2265 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.40 Median:168.50 stddev:2.07 (1.2%) stddev-sans-first:2.18 18:50:40 INFO - PROCESS | 2265 | Values: 168.0 169.0 164.0 169.0 170.0 167.0 169.0 167.0 167.0 164.0 18:50:40 INFO - PROCESS | 2265 | 18:50:40 INFO - PROCESS | 2265 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:68.00 stddev:1.99 (2.9%) stddev-sans-first:2.00 18:50:40 INFO - PROCESS | 2265 | Values: 69.0 67.0 68.0 67.0 68.0 70.0 67.0 65.0 68.0 63.0 18:50:40 INFO - PROCESS | 2265 | -------- Summary: end -------- 18:50:40 INFO - PROCESS | 2265 | 18:50:41 INFO - TEST-INFO | 2265: exit 0 18:50:41 INFO - TEST-OK | kraken | took 61474ms 18:50:41 INFO - SUITE-END | took 1174s 18:50:42 INFO - Completed test suite (00:19:34) 18:50:42 INFO - javascript benchmark 18:50:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7134.011030341968, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2900.5214139666955, 3487.7209823214143, 3493.5633045869267, 3511.2275299434123, 3539.9646003539956, 4718.339432479455, 5816.941830581698, 5862.677552734598, 5866.823995280138, 5918.2898052233795, 4562.794674239659, 5796.50729687977, 5811.506021988136, 5826.708664566776, 5920.851978700534, 5235.136202526588, 5295.258663787073, 5333.093374126388, 5382.461753824626, 5437.265969094161, 5433.891322173574, 5459.781608735666, 5492.642978206423, 5511.476409741068, 5516.9448305516835, 4692.624590032823, 4857.757112144379, 5299.6025298102795, 5305.151175811851, 5350.705711185903, 5322.893542129135, 5339.092354299778, 5346.518813306821, 5348.598855085884, 5365.356157261153, 4679.625629949596, 5270.604704647167, 5318.095923692981, 5351.063563876306, 5378.946210537883, 5604.775808967636, 5685.289338832646, 5750.2237197978575, 5787.71061446926, 5800.332961709415, 6256.530760192958, 6318.4629306509005, 6320.5891617044745, 6329.873402531924, 6329.905051424233, 6237.906431403532, 6244.687765611701, 6283.560150789401, 6341.302456729756, 6354.87290254197, 5439.292891924025, 5958.314793798769, 5978.013627751459, 5989.431004054652, 6030.577859549833, 5751.511121554715, 6285.937140628626, 6313.936860631335, 6358.936410635927, 6375.521835862329, 6114.145302971479, 6140.381550880983, 6169.753209871645, 6228.727362937237, 6264.498840092781, 6302.1177035215105, 6336.778212762531, 6343.682815859189, 6349.174607301114, 6354.555181137276, 6123.112148738408, 6215.316315205324, 6285.465735412449, 6305.96847015762, 6326.905096423558, 6113.266408030976, 6249.25008998923, 6261.968690156611, 6267.341929097471, 6333.303336632966, 5074.746262686851, 5082.567981721595, 5089.745512724349, 5098.59211263098, 5106.131957567222, 4300.0969796343115, 4552.613027892667, 4552.954470455319, 4564.452265728134, 4634.142683603544, 5733.312002559636, 5855.765769369177, 5904.881902361929, 5933.970330148408, 5971.611845230046, 5197.922031169535, 5306.124489459196, 6297.622142671455, 6325.082862984841, 6339.144215530844, 5919.6744179070565, 5941.910871336933, 5942.405759424109, 5954.523638108941, 5962.821115366546, 4558.361829343895, 5427.050266203399, 5486.753096110685, 5563.248961390161, 5589.832305030855, 6516.641584712792, 6556.3443655634055, 6564.540482166202, 6566.113574667359, 6577.0, 6185.536084793748, 6291.370862913672, 6301.590396624205, 6312.936870631235, 6320.936790632035, 6418.679066046586, 6438.034294855622, 6505.349465053457, 6546.345365463416, 6574.243961944438, 6187.288461826947, 6209.5032397409, 6244.250689917239, 6264.248290205204, 6277.466415354746, 6178.66017369031, 6199.597026193283, 6276.686165691788, 6299.181106456132, 6312.936870631235, 6121.270068921507, 6135.785247516315, 6180.134781130555, 6215.254169499689, 6307.873842523216, 4728.739919304334, 6124.969375153096, 6207.6585787782105, 6213.937860621336, 6240.563160578898, 4856.660033797601, 5251.739582500249, 5918.2898052234, 6061.090836374579, 6064.272287325372, 6134.828431494943, 6174.845628859135, 6194.0, 6228.906566401598, 6247.718852651732, 5975.581709280309, 6131.816045518642, 6145.0, 6145.539084568764, 6175.722092505758], "value": 5747.354629505442, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [490.0370771434131, 522.3209827224607, 538.8845090662326, 541.4855886907436, 588.8939990801659, 34690.479642805316, 38840.0, 39641.60358396415, 41997.0, 42026.15947681045, 42726.0, 43272.0, 43510.0, 43543.78228108855, 43809.342859857046, 9458.527073646308, 9653.565589548452, 9695.36980096295, 9730.951345243264, 9751.0, 9576.856347154799, 9676.903230967706, 9692.0, 9704.805903881917, 9770.658026969057, 9648.421094734304, 9739.561719722595, 9739.951300243489, 9758.609655613767, 9766.511674416286, 9650.0, 9669.274804389699, 9670.032996700344, 9727.999999999965, 9747.97646247141, 9478.241740660731, 9583.952080239624, 9592.0, 9602.231821454267, 9610.038996100404, 32716.345673086405, 39876.60123398758, 40336.39495407571, 40340.79829600833, 40344.596554034375, 2995.4608170529295, 3075.200447883544, 3097.829619370934, 3200.679932006804, 3293.604767427924, 24268.393290167885, 29930.251743706365, 30204.94282700117, 30340.393192136253, 30402.0, 2671.6393286906214, 2747.2582402751345, 2765.778737700999, 2774.7918906082123, 2776.41695243999, 412827.0, 474147.0, 483127.0, 485768.0, 486484.0, 36101.45847812285, 36159.4576081359, 36230.81884590613, 36417.27165456729, 36461.0, 36862.631373686454, 37159.0, 37245.25509489849, 37273.62726372756, 37342.0, 15187.696246075016, 15377.308021138851, 15434.15112168819, 15451.922740386226, 15456.459023934109, 12548.372581370895, 12947.5468358607, 13022.088453808332, 13033.087683862228, 13035.869641303656, 7778.183290754389, 7889.960550197212, 8002.759917202493, 8098.75703728889, 8100.230478104689, 3071.769617278713, 3221.581194444754, 3271.4275001874576, 3296.1430028192844, 3308.040668206241, 898.9190972812347, 948.805494873563, 958.9136977672043, 981.5779214937645, 1167.1304877865875, 29941.550876736866, 30563.38873222523, 30693.693063069084, 31081.533776993365, 31251.68748312488, 975.7463059604554, 1125.9662210133708, 1156.2715489241891, 1203.9638810835688, 1236.2273579013117, 15758.448454304043, 15799.0, 15880.682386352208, 15948.0, 16194.676106477804, 667097.0, 672123.0, 681643.0, 682321.0, 684977.0, 64494.0, 64977.35022649808, 65053.0, 65286.0, 65319.0, 1241.8944389726794, 1443.5236371997216, 1494.140869000329, 1495.1851241073423, 1496.5285934930462, 29770.702292977658, 29799.0, 29878.0, 30352.84823575868, 30763.230919227197, 56243.718781405834, 56594.717026414604, 57122.42877571171, 57137.71431142818, 57253.0, 571659.0, 575290.0, 575444.0, 577385.0, 579721.0, 26869.86565067162, 27535.586966195922, 27802.86098569494, 27977.720222798325, 28033.738481768778, 44643.776781115885, 47820.2826957589, 48879.266810998575, 49452.50547494623, 49761.50238497713, 30597.0, 40042.79978600088, 40508.0, 41173.1765364697, 41261.0, 38520.42219366768, 38789.6121038786, 38907.805460972515, 39415.60584394119, 39967.2006559873, 38172.0, 38809.80595097006, 39112.80443597764, 39439.2112157761, 40740.0, 32277.354452910342, 34794.304113918086, 34871.47692784661, 35409.46885796663, 35705.28589428249, 21759.782402175777, 24686.629700554146, 24759.752402475744, 25637.61543576885, 25774.87112564425], "value": 18657.158894193206, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.7806750152414, 385.1276857916818, 390.82803566430766, 393.75769447393486, 411.9217348703746, 14678.926605366958, 20151.09320080596, 21003.844788536626, 21544.81503517308, 21588.460288492814, 21002.68495972558, 21152.365429037152, 21373.0, 21496.677549836764, 21514.354569362906, 4896.0, 5076.593872490192, 5736.196932429463, 5816.302043754756, 5943.3462319144855, 4649.744264065475, 4955.504449555052, 5917.5561832862495, 5919.0, 5921.111833225029, 5665.886682266352, 5938.0, 5949.137375080611, 5955.404459554054, 5957.076653118774, 5657.8585535361535, 5778.59549831512, 5907.734151963151, 5922.0, 5936.614120082202, 5067.467915868854, 5105.132127538291, 5120.231965205212, 5127.154019586764, 5133.358330208724, 13077.0, 23056.884715576314, 23929.564226146314, 24311.392215194763, 25749.3562660935, 1787.8212178782148, 1930.1025023364202, 1934.2456441987636, 2028.8275496582808, 2034.8779073255505, 9314.627414903396, 9541.904580954171, 10309.556662067316, 10319.991614632514, 14491.710165796625, 1712.9314827406888, 1733.9219735111951, 1761.9471415857545, 1807.2228941555127, 1814.4647329037834, 124530.0, 129416.35291823575, 129959.0, 130018.04972925414, 130021.34989325088, 10940.124790016938, 11287.209895307415, 11402.714932126763, 11756.529738810523, 11820.23168494045, 12901.096923215286, 14556.344964476624, 14702.41190352374, 14794.112353258839, 14859.479918202811, 8327.875081873777, 8539.444936079135, 8583.399162058597, 8622.698205562776, 8712.259457946018, 7351.963240183872, 8177.100518942912, 8508.829823403621, 8518.318534517224, 8646.870296945552, 4521.751303678264, 4532.546745325441, 4557.407537020166, 4560.33875088117, 4575.58819706228, 1864.2077117225397, 1930.2568511123318, 1958.6474434601623, 1962.96832778074, 1985.702144678281, 882.4881568690273, 901.5537309032071, 1012.731626119079, 1016.5730393234859, 1087.396494945297, 20093.296734614512, 20403.38789836307, 20499.4875128123, 20706.482337941372, 20726.48183795387, 883.1080608585247, 941.5292353823088, 950.4914870544226, 976.6679329028022, 996.4718699089349, 10898.182636302308, 10932.398718070583, 10949.397783121845, 10957.397343146044, 11002.899710028933, 598297.0085149546, 604854.0, 605382.0, 611625.0, 623417.0, 121534.3923280378, 124527.0, 127745.0, 131137.0, 131745.0, 1019.2101121630648, 1030.05749738988, 1035.7980193862275, 1062.0706881478707, 1065.8561094252332, 13865.930670346987, 18257.08714564293, 18417.0, 18432.0, 18625.34811281653, 51357.22964155466, 52414.73792631165, 53785.0, 54224.0, 54254.45745542494, 59883.0, 75870.24129758631, 75947.24052759401, 76388.0, 76824.84762728451, 7685.346745526693, 7901.446898717265, 8041.0, 8126.268635822805, 8387.287080598218, 9006.729798106067, 11481.425928703364, 12197.634070977885, 12233.510659573518, 12407.317597532223, 8183.918160818315, 9875.851862222216, 10657.680269591925, 10718.678439646823, 10865.293755906157, 8911.688090916787, 10450.581976720847, 10644.946775266075, 10789.730256743644, 11024.834627480433, 10811.45942702846, 10865.456727163451, 11002.0, 11148.244337734594, 11163.33020018769, 7699.153093159636, 8055.516668999879, 8057.113717491188, 8059.722263640866, 8224.588770561568, 8045.959770201346, 10281.640142594973, 10614.203934705083, 10916.399598022184, 11132.554697811996], "value": 9364.30042558528, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1460.620238737928, 1567.2790516362472, 1820.7359932809743, 1932.4202739178252, 1970.5270735023598, 8145.592720363988, 8658.870116948236, 8732.825343493127, 9165.816683666322, 9213.03263157368, 8721.694740684077, 8785.0, 8936.64253429862, 8945.150210729986, 8954.641814327419, 1974.4668939386322, 2396.580598395283, 2411.9276421707377, 2413.3122060212845, 2414.9396265093424, 2129.2121914891513, 2280.646499792535, 2399.0523743121544, 2402.159244264511, 2411.8311718179734, 2396.3649632847223, 2402.0511897800357, 2407.9277621671376, 2420.4190994161363, 2432.1244352033254, 2386.009805930537, 2397.3287479505766, 2409.084547871806, 2422.9757702422926, 2431.9513609727705, 2361.681173041635, 2382.070992312982, 2392.79661228794, 2400.2799160251857, 2421.346236516131, 8812.559372031374, 8848.778780530474, 8862.335324850597, 8891.644334226623, 8979.910200897973, 5264.2893209416625, 5378.596605254583, 5438.619296649236, 5480.862978425571, 5490.039243132475, 3316.8507417166284, 3535.982320088409, 3567.9840889897123, 3593.7125029997537, 3606.170580766412, 986.0, 1021.7701017271129, 1025.9692209233735, 1031.339942436841, 1038.2835843268122, 11734.647960561197, 11924.582639607659, 11950.760984780256, 11974.88025119755, 11978.401079945968, 4081.163361510883, 4128.896777580585, 4762.999770048259, 4776.593989510861, 4863.8540843774745, 9905.306628535933, 10056.396616203052, 10061.899381006097, 10068.194544436428, 10069.295149339476, 5317.521423071942, 5352.330958630171, 5387.9461205388225, 5408.188771684278, 5420.972895135499, 4454.0, 4549.681522293474, 4919.950800491949, 4974.626902982219, 4984.0, 3948.6643635290748, 3958.9010274743364, 3970.7021973351534, 4050.6151915567975, 4094.570070142592, 1279.9168054076456, 1301.557470460048, 1302.5831733845268, 1310.92134471932, 1312.566852938547, 857.2756021162108, 875.3653601139123, 876.4741155306892, 908.8318661047595, 920.1350730313484, 5658.575606829504, 5665.575081868877, 5978.222831031938, 5983.0, 6015.3383127855905, 915.1443400420628, 915.3318077803241, 934.4953724988566, 940.8212439636447, 960.846264597675, 3802.6007269236334, 3902.843886244518, 3930.7051971102283, 3950.7827069510877, 3985.0236692010644, 10550.472476376304, 10633.202509811796, 10887.0, 10895.727606809734, 10948.39783811882, 10723.410212438392, 10740.838887416696, 10844.72888177786, 10848.349099054081, 10855.023047925724, 7997.560134192675, 8100.756977290691, 8300.792480187924, 8305.0, 8313.667453302041, 5634.605577609528, 5710.543156547549, 5773.3072031356505, 5793.333766616893, 5847.26909136358, 10041.196704263788, 10202.540885660312, 10218.131458826083, 10220.0, 10247.180225581786, 9906.405615663083, 10018.248631352531, 10384.0, 10405.895941040493, 10432.843507347245, 5429.7556609953435, 6071.241094863142, 6421.133147025092, 6889.517733758589, 7029.648517573998, 8473.5339556325, 8512.659493620185, 8536.44513106646, 8614.78463038429, 8623.611937462703, 7448.366888814511, 7591.658375373011, 7599.240075992357, 7669.080326191541, 7696.384289256958, 7433.0, 7433.145188303198, 7498.700051997859, 7576.469647124869, 7646.044244469442, 7036.894446583203, 7089.255628158969, 7094.716211351488, 7112.431005519443, 7112.857742845011, 4712.293156026623, 4762.76186190696, 4843.830465933675, 4899.779509922133, 5018.899622007466, 4856.271559266138, 4913.140200464976, 4981.402231732215, 4994.575461085703, 5102.948970510248], "value": 4603.255495709442, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.79760054764677, 314.6365947330833, 318.9872405103796, 320.79469139750563, 322.61930921512607, 4663.370444989925, 4695.67130300879, 5556.916401301748, 5581.358143813458, 5608.915866262009, 5371.731413429333, 5493.340799104103, 5503.394626591072, 5513.228148059265, 5541.5843811714185, 3342.5487559179564, 3883.3107598194874, 3980.522337319526, 4149.3983372410985, 4191.81136848841, 4161.708680392374, 4189.518205406372, 4201.6008479194425, 4214.452121224236, 4320.524742278347, 3262.6411094779555, 4170.937435938464, 4188.455500784894, 4194.349875769245, 4240.872773816791, 3513.912152196203, 3825.7321987460887, 4133.731307465006, 4160.45914031177, 4200.642945349634, 3378.6452422495777, 3553.875614353485, 3556.199855032623, 3557.092941299952, 3562.7327950403824, 5285.6300058995885, 5289.233061206142, 5324.0, 5351.3043304370185, 5360.91958620621, 4052.865197744636, 4625.58369746721, 4715.434147902273, 4773.260144677581, 4796.664233503656, 5080.339555857715, 5182.922256166161, 5350.742575494755, 5835.737391817378, 5844.386339434383, 3535.0101971448034, 3708.311474181224, 3786.845012271256, 3963.2667956427863, 4026.798660066985, 4738.739369334685, 5749.361431991862, 5899.758091925041, 5925.2889653241455, 6162.198914141133, 3988.9202215956097, 3994.540627827837, 3995.820188091543, 4018.1160144768105, 4029.7582145071387, 4035.9192816143513, 4051.6353528182603, 4061.3095773718537, 4063.329550624114, 4080.755154690728, 4746.288056791509, 4804.6876952997945, 4816.20532612115, 4825.541573550507, 4833.492483289274, 5022.698638081727, 5385.0, 5393.0, 5886.587938844325, 5890.764369425175, 1286.6075846866609, 1348.191085348783, 1375.312343828086, 1380.4685196199534, 1385.5496963486908, 824.7938015496126, 846.3356265331685, 854.2269246332079, 855.3584811391456, 901.8737376767258, 5171.21593050397, 5332.813351532678, 5335.199720041947, 5364.839054828361, 5393.5954803389905, 5309.33633295838, 5888.793892213752, 5890.087036509262, 5924.851878703067, 5950.375210602824, 4513.323001549728, 5029.801847319804, 5290.65610735301, 5300.0, 5365.839024829262, 2661.148432501581, 2831.768180841341, 2876.6835648078695, 3131.248500359897, 3140.544621029938, 5455.645383050089, 6008.888355654218, 6101.511879049665, 6188.350223226496, 6193.690315484298, 5635.239242702182, 6186.566940314135, 6207.317195108534, 6259.593126446766, 6299.0, 5524.392316845144, 6341.619502829845, 6341.714622841983, 6357.554971151975, 6371.745130194833, 5604.075327570905, 5852.473277404883, 5905.576657199959, 5990.610610310316, 6073.240844894388, 5584.0, 5621.048949301649, 6039.969800150971, 6060.848478787889, 6068.544859135671, 5595.5523558116065, 5779.479846813807, 6320.5891617044745, 6353.522126370812, 6364.840878978062, 4082.183563287295, 4117.547069822377, 4119.938200926928, 4126.019510634852, 4141.503019637663, 5978.027251007245, 5983.115318674603, 6032.276126864629, 6053.364396738279, 6082.178905847654, 5151.201563757623, 5653.349864765604, 5701.771929122789, 5784.929787989236, 5796.652200867961, 5741.019348324821, 5748.930385061458, 5775.884482310246, 5784.21913041734, 5794.594378393472, 5148.948510514847, 5410.0261952847295, 5415.918761218663, 5678.488935995781, 5688.232088667977, 5615.2700148980375, 5641.0, 5847.1229315603, 5855.9121613176685, 5906.527477801851, 5449.7275136243825, 5740.0, 5746.770129194785, 5747.471112710394, 5783.479486846204], "value": 4240.769241054468, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [647.2362612117705, 672.7880717573964, 697.9092717946669, 709.6309918842202, 723.5152447859933, 15106.924465377671, 15451.690966180699, 15609.531714048582, 15620.21898905053, 15623.0, 14188.574342769733, 14469.927650361733, 14475.638109047279, 14526.854731452682, 14560.199189044599, 738.4978214813924, 901.0944001278722, 925.6760133953096, 926.6571368593629, 928.2852203803068, 732.7472022152349, 925.9537023148815, 935.0555938502127, 938.5307346326837, 945.9668911588096, 977.0, 984.8522721591783, 988.6786794291847, 989.539863963257, 989.7080361293399, 951.3388195204336, 954.2699834626517, 956.3305686019814, 977.5991843344194, 979.2606582030556, 718.9532680375759, 726.0561270348578, 729.5914287998685, 732.0483371616876, 733.435254853765, 14168.433262669481, 14255.643608909757, 14277.500287489887, 14277.571672849832, 14338.78491822613, 12454.439550220262, 12587.874121258854, 12626.116171867972, 12732.299723515203, 12755.362231888368, 8121.959390203011, 9715.319927605071, 9840.507974601313, 9901.405915645084, 10489.737756556071, 4012.578679238667, 4017.31705610047, 4026.8993275168064, 4143.047099167208, 4150.3566947123245, 83499.0, 83984.0, 84350.0, 85431.71852422156, 87307.56346218292, 9161.358704890727, 9236.953815230881, 9243.352965292364, 9283.25733941283, 9702.563384647567, 20655.483612909797, 20761.688574671392, 20762.48093797637, 20796.856172910637, 20807.0, 6208.689565521706, 7156.177039640508, 7241.456890733216, 7331.266873312733, 7331.523450975669, 3845.3270677631303, 3883.126296583246, 3895.182011777502, 3897.2205558888336, 3933.862314819025, 3341.933161336808, 3367.898963031064, 3427.4344733118755, 3431.039308993486, 3434.1242983039165, 623.183629445428, 623.4482483002575, 631.9462845658171, 636.8025911967305, 638.8542223806915, 520.3651545114953, 555.0093083845317, 557.7685260616798, 582.5601670738565, 587.6532845621105, 7281.890771638429, 7664.11862635804, 7731.149573546905, 7974.282314591715, 8106.027276726831, 502.47240397583124, 531.7394476706386, 546.5572885962383, 564.4045531963818, 568.2470726287603, 3109.6628449766367, 3236.724878385364, 3467.878624248139, 3683.281760056817, 3685.7235707322056, 81548.0, 83050.58474707708, 84086.0, 85330.0, 85759.57120214359, 69405.0, 77314.2268577307, 77894.05264868311, 80669.0, 81181.59409202916, 71036.0, 71478.42607869586, 71590.64204678943, 71829.0, 71972.92040619395, 7292.380147687506, 7338.706451742084, 7344.632768361667, 7364.815879603053, 7452.292032256927, 33076.83461582677, 34447.62209511591, 41933.58066419297, 43414.34878476762, 45447.54552454433, 54508.18237726516, 61689.69155154195, 61858.69070654618, 62221.68889155706, 62405.0, 9155.77110572241, 10987.505562249878, 11154.609588664358, 11215.046721028477, 11430.714232144262, 10279.588816447258, 12008.699782505508, 12067.336296503425, 12303.75392492163, 12888.355582221038, 9211.309151813775, 10517.684469465928, 10684.83972740425, 11394.886051139383, 11591.710207244887, 11301.71745706364, 11418.942905285421, 11777.352245626573, 11798.469068891749, 11825.172237943261, 9884.209263258777, 10094.39433633955, 11331.943340283246, 11748.011649772567, 11995.520179192734, 9585.9041409585, 9602.08780165883, 9613.0, 9657.227421806097, 10013.198944084601, 9292.6282948684, 9317.428035821695, 9566.90433095688, 9579.808403832023, 9608.9039109608], "value": 6724.5434961451565, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1613.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 112.0, 146.0, 152.0, 93.0, 142.0, 169.0, 169.0, 120.0, 126.0], "value": 134.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 156.0, 172.0, 211.0, 120.0, 157.0, 165.0, 149.0, 174.0, 121.0], "value": 157.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 210.0, 230.0, 241.0, 245.0, 196.0, 197.0, 196.0, 197.0, 194.0], "value": 210.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 84.0, 98.0, 196.0, 74.0, 51.0, 58.0, 84.0, 160.0, 86.0], "value": 95.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 89.0, 141.0, 82.0, 90.0, 83.0, 123.0, 80.0, 80.0, 124.0], "value": 97.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 131.0, 134.0, 86.0, 95.0, 87.0, 89.0, 87.0, 89.0, 99.0], "value": 97.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 95.0, 95.0, 94.0, 95.0, 94.0, 94.0, 94.0, 122.0], "value": 97.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 91.0, 114.0, 116.0, 89.0, 90.0, 91.0, 91.0, 111.0, 113.0], "value": 100.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 62.0, 59.0, 60.0, 58.0, 57.0, 59.0, 120.0, 57.0, 65.0], "value": 65.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 68.0, 82.0, 78.0, 74.0, 70.0, 70.0, 72.0, 69.0], "value": 72.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 102.0, 109.0, 110.0, 86.0, 86.0, 102.0, 110.0, 112.0, 87.0], "value": 100.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 154.0, 139.0, 138.0, 145.0, 140.0, 136.0, 138.0, 217.0, 144.0], "value": 150.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 169.0, 164.0, 169.0, 170.0, 167.0, 169.0, 167.0, 167.0, 164.0], "value": 167.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 68.0, 67.0, 68.0, 70.0, 67.0, 65.0, 68.0, 63.0], "value": 67.2, "unit": "score"}]}]} 18:50:42 INFO - Return code: 0 18:50:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:50:42 ERROR - # TBPL SUCCESS # 18:50:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:50:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:50:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:50:42 INFO - 2016-05-20 18:50:42.272 screenresolution[2270:32534] starting screenresolution argv=screenresolution get 18:50:42 INFO - 2016-05-20 18:50:42.275 screenresolution[2270:32534] Display 0: 1600x1200x32@60 18:50:42 INFO - 2016-05-20 18:50:42.281 screenresolution[2271:32537] starting screenresolution argv=screenresolution list 18:50:42 INFO - Available Modes on Display 0 18:50:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:50:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:50:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:50:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:50:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:50:42 INFO - Intel Iris: 18:50:42 INFO - Chipset Model: Intel Iris 18:50:42 INFO - Type: GPU 18:50:42 INFO - Bus: Built-In 18:50:42 INFO - VRAM (Dynamic, Max): 1536 MB 18:50:42 INFO - Vendor: Intel (0x8086) 18:50:42 INFO - Device ID: 0x0a2e 18:50:42 INFO - Revision ID: 0x0009 18:50:42 INFO - Displays: 18:50:42 INFO - Display: 18:50:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:50:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:50:42 INFO - Display Serial Number: 1600x1200 60 18:50:42 INFO - Main Display: Yes 18:50:42 INFO - Mirror: Off 18:50:42 INFO - Online: Yes 18:50:42 INFO - Rotation: Supported 18:50:42 INFO - Return code: 0 18:50:42 INFO - Running post-action listener: _resource_record_post_action 18:50:42 INFO - ##### 18:50:42 INFO - ##### Finished run-tests step (success) 18:50:42 INFO - ##### 18:50:42 INFO - Running post-run listener: _resource_record_post_run 18:50:42 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 471040; Write bytes: 696329216; Read time: 6; Write time: 1960 18:50:42 INFO - TinderboxPrint: CPU usage
54.2% 18:50:42 INFO - TinderboxPrint: I/O read bytes / time
471,040 / 6 18:50:42 INFO - TinderboxPrint: I/O write bytes / time
696,329,216 / 1,960 18:50:42 INFO - TinderboxPrint: CPU idle
2,214.0 (45.8%) 18:50:42 INFO - TinderboxPrint: CPU system
971.7 (20.1%) 18:50:42 INFO - TinderboxPrint: CPU user
1,651.9 (34.1%) 18:50:42 INFO - TinderboxPrint: Swap in / out
396,705,792 / 0 18:50:42 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 377436672; Write bytes: 372855808; Read time: 28221; Write time: 763 18:50:42 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 471040; Write bytes: 296415232; Read time: 6; Write time: 1128 18:50:43 INFO - Running post-run listener: _upload_blobber_files 18:50:43 INFO - Blob upload gear active. 18:50:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:50:43 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 18:50:43 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'] 18:50:43 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 18:50:43 INFO - (blobuploader) - INFO - Open directory for files ... 18:50:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:50:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:50:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:50:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:50:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:50:44 INFO - (blobuploader) - INFO - Done attempting. 18:50:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:50:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:50:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:50:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:50:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:50:45 INFO - (blobuploader) - INFO - Done attempting. 18:50:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:50:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:50:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:50:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:50:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:50:48 INFO - (blobuploader) - INFO - Done attempting. 18:50:48 INFO - (blobuploader) - INFO - Iteration through files over. 18:50:48 INFO - Return code: 0 18:50:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:50:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:50:48 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a1759f9f7bd0b09616fbfb9e0560239ef814a74963f111a6acda6b0654ac8e6465b7e8a6c7d2828c17170ffb659aa411aabd8944d42f1a0462c5440d83237d07", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d863f25eea4799d3568841cceac1942708596435fa61febc241f5c662377d71bca01d904fa5407e3dc3c7a5b70b5faacc4e1d5ce7de2984593b77ea6c5769ee3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dc7e7b938cc2bc2ef8b7e069e5ed7f247a12d869a9fac6a4da48f016dea8809d368efcc9522ba66ec696cba5b18ba0e3d7f0578525c9c5becc8572466ed2cc10"} 18:50:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:50:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:50:48 INFO - Contents: 18:50:48 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a1759f9f7bd0b09616fbfb9e0560239ef814a74963f111a6acda6b0654ac8e6465b7e8a6c7d2828c17170ffb659aa411aabd8944d42f1a0462c5440d83237d07", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d863f25eea4799d3568841cceac1942708596435fa61febc241f5c662377d71bca01d904fa5407e3dc3c7a5b70b5faacc4e1d5ce7de2984593b77ea6c5769ee3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dc7e7b938cc2bc2ef8b7e069e5ed7f247a12d869a9fac6a4da48f016dea8809d368efcc9522ba66ec696cba5b18ba0e3d7f0578525c9c5becc8572466ed2cc10"} 18:50:48 INFO - Running post-run listener: copy_logs_to_upload_dir 18:50:48 INFO - Copying logs to upload dir... 18:50:48 INFO - mkdir: /builds/slave/test/build/upload/logs 18:50:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.435555 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-05-20 18:50:48.462152) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 18:50:48.465545) ========= 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.XkzYqhHBRZ/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.DqrkuV5Lfy/Listeners TMPDIR=/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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/a1759f9f7bd0b09616fbfb9e0560239ef814a74963f111a6acda6b0654ac8e6465b7e8a6c7d2828c17170ffb659aa411aabd8944d42f1a0462c5440d83237d07", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d863f25eea4799d3568841cceac1942708596435fa61febc241f5c662377d71bca01d904fa5407e3dc3c7a5b70b5faacc4e1d5ce7de2984593b77ea6c5769ee3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dc7e7b938cc2bc2ef8b7e069e5ed7f247a12d869a9fac6a4da48f016dea8809d368efcc9522ba66ec696cba5b18ba0e3d7f0578525c9c5becc8572466ed2cc10"} build_url:https://queue.taskcluster.net/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011101 build_url: 'https://queue.taskcluster.net/v1/task/XEC0mkToTWmJAY2LSAG4Gg/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/a1759f9f7bd0b09616fbfb9e0560239ef814a74963f111a6acda6b0654ac8e6465b7e8a6c7d2828c17170ffb659aa411aabd8944d42f1a0462c5440d83237d07", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d863f25eea4799d3568841cceac1942708596435fa61febc241f5c662377d71bca01d904fa5407e3dc3c7a5b70b5faacc4e1d5ce7de2984593b77ea6c5769ee3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dc7e7b938cc2bc2ef8b7e069e5ed7f247a12d869a9fac6a4da48f016dea8809d368efcc9522ba66ec696cba5b18ba0e3d7f0578525c9c5becc8572466ed2cc10"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 18:50:48.495484) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 18:50:48.495852) ========= 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.XkzYqhHBRZ/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.DqrkuV5Lfy/Listeners TMPDIR=/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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.005175 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 18:50:48.545260) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 18:50:48.545582) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 18:50:48.545957) ========= ========= Total master_lag: 0.18 =========