builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0292 starttime: 1464797242.61 results: success (0) buildid: 20160601070144 builduid: 707e573078dc4a88bc68cb348d181ace revision: 7520b940afc85c299b6cb500477c8906eecde531 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 09:07:22.610410) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 09:07:22.610896) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 09:07:22.611175) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 09:07:22.633882) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 09:07:22.634183) ========= 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.VTrqcJMI3x/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.BqaLnFvaJP/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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-06-01 09:07:22-- 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% 15.6M=0.001s 2016-06-01 09:07:22 (15.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.093605 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 09:07:22.749270) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 09:07:22.749796) ========= 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.VTrqcJMI3x/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.BqaLnFvaJP/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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.025277 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 09:07:22.798067) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-01 09:07:22.798421) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 7520b940afc85c299b6cb500477c8906eecde531 --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 7520b940afc85c299b6cb500477c8906eecde531 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.VTrqcJMI3x/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.BqaLnFvaJP/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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-06-01 09:07:22,883 truncating revision to first 12 chars 2016-06-01 09:07:22,883 Setting DEBUG logging. 2016-06-01 09:07:22,883 attempt 1/10 2016-06-01 09:07:22,883 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/7520b940afc8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-01 09:07:24,023 unpacking tar archive at: fx-team-7520b940afc8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.399917 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-01 09:07:24.217883) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 09:07:24.218233) ========= script_repo_revision: 7520b940afc85c299b6cb500477c8906eecde531 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 09:07:24.218645) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 09:07:24.218910) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 09:07:24.234932) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-06-01 09:07:24.235237) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.VTrqcJMI3x/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.BqaLnFvaJP/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 09:07:24 INFO - MultiFileLogger online at 20160601 09:07:24 in /builds/slave/test 09:07:24 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 09:07:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:07:24 INFO - {'append_to_log': False, 09:07:24 INFO - 'base_work_dir': '/builds/slave/test', 09:07:24 INFO - 'blob_upload_branch': 'Fx-Team', 09:07:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:07:24 INFO - 'branch': 'Fx-Team', 09:07:24 INFO - 'buildbot_json_path': 'buildprops.json', 09:07:24 INFO - 'config_files': ('talos/mac_config.py',), 09:07:24 INFO - 'default_actions': ('clobber', 09:07:24 INFO - 'read-buildbot-config', 09:07:24 INFO - 'download-and-extract', 09:07:24 INFO - 'populate-webroot', 09:07:24 INFO - 'create-virtualenv', 09:07:24 INFO - 'install', 09:07:24 INFO - 'run-tests'), 09:07:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:07:24 INFO - 'download_minidump_stackwalk': True, 09:07:24 INFO - 'download_symbols': 'ondemand', 09:07:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:07:24 INFO - 'tooltool.py': '/tools/tooltool.py', 09:07:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:07:24 INFO - '/tools/misc-python/virtualenv.py')}, 09:07:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:07:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:07:24 INFO - 'installer_path': 'installer.exe', 09:07:24 INFO - 'log_level': 'info', 09:07:24 INFO - 'log_name': 'talos', 09:07:24 INFO - 'log_to_console': True, 09:07:24 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:07:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:07:24 INFO - 'opt_config_files': (), 09:07:24 INFO - 'pip_index': False, 09:07:24 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:07:24 INFO - 'cmd': ('bash', 09:07:24 INFO - '-c', 09:07:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:07:24 INFO - 'enabled': True, 09:07:24 INFO - 'halt_on_failure': False, 09:07:24 INFO - 'name': 'check_screen_resolution'},), 09:07:24 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:07:24 INFO - 'cmd': ('bash', 09:07:24 INFO - '-c', 09:07:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:07:24 INFO - 'enabled': True, 09:07:24 INFO - 'halt_on_failure': False, 09:07:24 INFO - 'name': 'check_screen_resolution'},), 09:07:24 INFO - 'run_cmd_checks_enabled': True, 09:07:24 INFO - 'sps_profile': False, 09:07:24 INFO - 'sps_profile_interval': 0, 09:07:24 INFO - 'suite': 'dromaeojs', 09:07:24 INFO - 'system_bits': '32', 09:07:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:07:24 INFO - 'title': 't-yosemite-r7-0292', 09:07:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:07:24 INFO - 'use_talos_json': True, 09:07:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:07:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:07:24 INFO - 'work_dir': 'build'} 09:07:24 INFO - ##### 09:07:24 INFO - ##### Running clobber step. 09:07:24 INFO - ##### 09:07:24 INFO - Running pre-action listener: _resource_record_pre_action 09:07:24 INFO - Running main action method: clobber 09:07:24 INFO - rmtree: /builds/slave/test/build 09:07:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:07:26 INFO - Running post-action listener: _resource_record_post_action 09:07:26 INFO - ##### 09:07:26 INFO - ##### Finished clobber step (success) 09:07:26 INFO - ##### 09:07:26 INFO - ##### 09:07:26 INFO - ##### Running read-buildbot-config step. 09:07:26 INFO - ##### 09:07:26 INFO - Running pre-action listener: _resource_record_pre_action 09:07:26 INFO - Running main action method: read_buildbot_config 09:07:26 INFO - Using buildbot properties: 09:07:26 INFO - { 09:07:26 INFO - "project": "", 09:07:26 INFO - "product": "firefox", 09:07:26 INFO - "script_repo_revision": "production", 09:07:26 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 09:07:26 INFO - "repository": "", 09:07:26 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 09:07:26 INFO - "buildid": "20160601070144", 09:07:26 INFO - "pgo_build": "False", 09:07:26 INFO - "basedir": "/builds/slave/test", 09:07:26 INFO - "buildnumber": 30, 09:07:26 INFO - "slavename": "t-yosemite-r7-0292", 09:07:26 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 09:07:26 INFO - "platform": "yosemite_r7", 09:07:26 INFO - "scheduler": "tests-fx-team-macosx64-talos", 09:07:26 INFO - "branch": "fx-team", 09:07:26 INFO - "repo_path": "integration/fx-team", 09:07:26 INFO - "revision": "7520b940afc85c299b6cb500477c8906eecde531", 09:07:26 INFO - "stage_platform": "macosx64", 09:07:26 INFO - "builduid": "707e573078dc4a88bc68cb348d181ace", 09:07:26 INFO - "slavebuilddir": "test" 09:07:26 INFO - } 09:07:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/WUC49xLAS7GbVIbqhFhGrw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 09:07:26 INFO - Running post-action listener: _resource_record_post_action 09:07:26 INFO - ##### 09:07:26 INFO - ##### Finished read-buildbot-config step (success) 09:07:26 INFO - ##### 09:07:26 INFO - ##### 09:07:26 INFO - ##### Running download-and-extract step. 09:07:26 INFO - ##### 09:07:26 INFO - Running pre-action listener: _resource_record_pre_action 09:07:26 INFO - Running main action method: download_and_extract 09:07:26 INFO - mkdir: /builds/slave/test/build/tests 09:07:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:07:26 INFO - https://queue.taskcluster.net/v1/task/WUC49xLAS7GbVIbqhFhGrw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:07:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUC49xLAS7GbVIbqhFhGrw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 09:07:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUC49xLAS7GbVIbqhFhGrw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 09:07:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUC49xLAS7GbVIbqhFhGrw/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 09:07:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUC49xLAS7GbVIbqhFhGrw/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 09:07:27 INFO - Downloaded 1199 bytes. 09:07:27 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 09:07:27 INFO - Using the following test package requirements: 09:07:27 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 09:07:27 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:07:27 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 09:07:27 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:07:27 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 09:07:27 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:07:27 INFO - u'jsshell-mac.zip'], 09:07:27 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:07:27 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 09:07:27 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 09:07:27 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:07:27 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 09:07:27 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:07:27 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 09:07:27 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:07:27 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 09:07:27 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:07:27 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 09:07:27 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:07: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')]} 09:07:27 INFO - https://queue.taskcluster.net/v1/task/WUC49xLAS7GbVIbqhFhGrw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:07:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUC49xLAS7GbVIbqhFhGrw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:07:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUC49xLAS7GbVIbqhFhGrw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:07:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUC49xLAS7GbVIbqhFhGrw/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 09:07:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUC49xLAS7GbVIbqhFhGrw/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 09:07:28 INFO - Downloaded 18696534 bytes. 09:07:28 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'] 09:07:28 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 09:07:31 INFO - Return code: 0 09:07:31 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 09:07:31 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')]} 09:07:31 INFO - https://queue.taskcluster.net/v1/task/WUC49xLAS7GbVIbqhFhGrw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:07:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUC49xLAS7GbVIbqhFhGrw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:07:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUC49xLAS7GbVIbqhFhGrw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:07:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUC49xLAS7GbVIbqhFhGrw/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 09:07:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUC49xLAS7GbVIbqhFhGrw/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 09:07:33 INFO - Downloaded 18696534 bytes. 09:07:33 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'] 09:07:33 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 09:07:36 INFO - Return code: 0 09:07:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:07:36 INFO - https://queue.taskcluster.net/v1/task/WUC49xLAS7GbVIbqhFhGrw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:07:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUC49xLAS7GbVIbqhFhGrw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 09:07:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUC49xLAS7GbVIbqhFhGrw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 09:07:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUC49xLAS7GbVIbqhFhGrw/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 09:07:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUC49xLAS7GbVIbqhFhGrw/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 09:07:37 INFO - Downloaded 10834055 bytes. 09:07:37 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'] 09:07:37 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 09:07:38 INFO - Return code: 0 09:07:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:07:38 INFO - https://queue.taskcluster.net/v1/task/WUC49xLAS7GbVIbqhFhGrw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:07:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUC49xLAS7GbVIbqhFhGrw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:07:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUC49xLAS7GbVIbqhFhGrw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:07:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUC49xLAS7GbVIbqhFhGrw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 09:07:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WUC49xLAS7GbVIbqhFhGrw/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 09:07:42 INFO - Downloaded 111051436 bytes. 09:07:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WUC49xLAS7GbVIbqhFhGrw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:07:42 INFO - mkdir: /builds/slave/test/properties 09:07:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:07:42 INFO - Writing to file /builds/slave/test/properties/build_url 09:07:42 INFO - Contents: 09:07:42 INFO - build_url:https://queue.taskcluster.net/v1/task/WUC49xLAS7GbVIbqhFhGrw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:07:43 INFO - Running post-action listener: _resource_record_post_action 09:07:43 INFO - Running post-action listener: set_extra_try_arguments 09:07:43 INFO - ##### 09:07:43 INFO - ##### Finished download-and-extract step (success) 09:07:43 INFO - ##### 09:07:43 INFO - ##### 09:07:43 INFO - ##### Running populate-webroot step. 09:07:43 INFO - ##### 09:07:43 INFO - Running pre-action listener: _resource_record_pre_action 09:07:43 INFO - Running main action method: populate_webroot 09:07:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:07:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:07:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:07:43 INFO - '--tppagecycles', 09:07:43 INFO - '7'], 09:07:43 INFO - 'tests': ['tsvgm']}}, 09:07:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:07:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:07:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:07:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:07:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:07:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:07:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:07:43 INFO - 'talos_options': ['--disable-e10s'], 09:07:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:07:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:07:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:07:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:07:43 INFO - 'talos_options': ['--disable-e10s'], 09:07:43 INFO - 'tests': ['damp', 'tps']}, 09:07:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:07:43 INFO - 'tests': ['damp', 'tps']}, 09:07:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:07:43 INFO - 'tests': ['dromaeo_dom']}, 09:07:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:07:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:07:43 INFO - 'tests': ['a11yr', 09:07:43 INFO - 'ts_paint', 09:07:43 INFO - 'tpaint', 09:07:43 INFO - 'sessionrestore', 09:07:43 INFO - 'sessionrestore_no_auto_restore', 09:07:43 INFO - 'tabpaint']}, 09:07:43 INFO - 'other-e10s': {'tests': ['a11yr', 09:07:43 INFO - 'ts_paint', 09:07:43 INFO - 'tpaint', 09:07:43 INFO - 'sessionrestore', 09:07:43 INFO - 'sessionrestore_no_auto_restore', 09:07:43 INFO - 'tabpaint']}, 09:07:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:07:43 INFO - 'ts_paint', 09:07:43 INFO - 'tpaint', 09:07:43 INFO - 'sessionrestore', 09:07:43 INFO - 'sessionrestore_no_auto_restore', 09:07:43 INFO - 'tabpaint']}, 09:07:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:07:43 INFO - 'ts_paint', 09:07:43 INFO - 'tpaint', 09:07:43 INFO - 'sessionrestore', 09:07:43 INFO - 'sessionrestore_no_auto_restore', 09:07:43 INFO - 'tabpaint']}, 09:07:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:07:43 INFO - 'tests': ['a11yr', 09:07:43 INFO - 'ts_paint', 09:07:43 INFO - 'tpaint', 09:07:43 INFO - 'sessionrestore', 09:07:43 INFO - 'sessionrestore_no_auto_restore', 09:07:43 INFO - 'tabpaint']}, 09:07:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:07:43 INFO - 'tests': ['a11yr', 09:07:43 INFO - 'ts_paint', 09:07:43 INFO - 'tpaint', 09:07:43 INFO - 'sessionrestore', 09:07:43 INFO - 'sessionrestore_no_auto_restore', 09:07:43 INFO - 'tabpaint']}, 09:07:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:07:43 INFO - 'tests': ['tsvgx', 09:07:43 INFO - 'tsvgr_opacity', 09:07:43 INFO - 'tart', 09:07:43 INFO - 'tscrollx', 09:07:43 INFO - 'cart']}, 09:07:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:07:43 INFO - 'tsvgr_opacity', 09:07:43 INFO - 'tart', 09:07:43 INFO - 'tscrollx', 09:07:43 INFO - 'cart']}, 09:07:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:07:43 INFO - 'talos_options': ['--disable-e10s'], 09:07:43 INFO - 'tests': ['tp5o']}, 09:07:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:07:43 INFO - 'tests': ['tp5o']}, 09:07:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:07:43 INFO - 'talos_options': ['--disable-e10s', 09:07:43 INFO - '--xperf_path', 09:07:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:07:43 INFO - 'tests': ['tp5n']}, 09:07:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:07:43 INFO - 'talos_options': ['--xperf_path', 09:07:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:07:43 INFO - 'tests': ['tp5n']}}, 09:07:43 INFO - 'talos.zip': {'path': '', 09:07:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:07:43 INFO - Running post-action listener: _resource_record_post_action 09:07:43 INFO - ##### 09:07:43 INFO - ##### Finished populate-webroot step (success) 09:07:43 INFO - ##### 09:07:43 INFO - ##### 09:07:43 INFO - ##### Running create-virtualenv step. 09:07:43 INFO - ##### 09:07:43 INFO - Running pre-action listener: _resource_record_pre_action 09:07:43 INFO - Running main action method: create_virtualenv 09:07:43 INFO - Creating virtualenv /builds/slave/test/build/venv 09:07:43 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 09:07:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:07:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:07:43 INFO - Using real prefix '/tools/python27' 09:07:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:07:44 INFO - Installing distribute.............................................................................................................................................................................................done. 09:07:47 INFO - Installing pip.................done. 09:07:47 INFO - Return code: 0 09:07:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:07: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')]} 09:07:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07: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')]} 09:07:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:47 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 0x107848a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10784e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c8b56c7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076ecd30>, '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 0x107510db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076e3b20>, '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.VTrqcJMI3x/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.BqaLnFvaJP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 09:07:47 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 09:07:47 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 09:07:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VTrqcJMI3x/Render', 09:07:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:47 INFO - 'HOME': '/Users/cltbld', 09:07:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:47 INFO - 'LOGNAME': 'cltbld', 09:07:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:47 INFO - 'MOZ_NO_REMOTE': '1', 09:07:47 INFO - 'NO_EM_RESTART': '1', 09:07:47 INFO - 'PAGER': '/bin/cat', 09:07:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:47 INFO - 'PWD': '/builds/slave/test', 09:07:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:47 INFO - 'SHELL': '/bin/bash', 09:07:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BqaLnFvaJP/Listeners', 09:07:47 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 09:07:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:47 INFO - 'USER': 'cltbld', 09:07:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:47 INFO - 'XPC_FLAGS': '0x0', 09:07:47 INFO - 'XPC_SERVICE_NAME': '0', 09:07:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:47 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 09:07:47 INFO - Cleaning up... 09:07:47 INFO - Return code: 0 09:07:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:07: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')]} 09:07:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07: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')]} 09:07:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:47 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 0x107848a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10784e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c8b56c7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076ecd30>, '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 0x107510db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076e3b20>, '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.VTrqcJMI3x/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.BqaLnFvaJP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 09:07:47 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 09:07:47 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 09:07:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VTrqcJMI3x/Render', 09:07:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:47 INFO - 'HOME': '/Users/cltbld', 09:07:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:47 INFO - 'LOGNAME': 'cltbld', 09:07:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:47 INFO - 'MOZ_NO_REMOTE': '1', 09:07:47 INFO - 'NO_EM_RESTART': '1', 09:07:47 INFO - 'PAGER': '/bin/cat', 09:07:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:47 INFO - 'PWD': '/builds/slave/test', 09:07:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:47 INFO - 'SHELL': '/bin/bash', 09:07:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BqaLnFvaJP/Listeners', 09:07:47 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 09:07:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:47 INFO - 'USER': 'cltbld', 09:07:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:47 INFO - 'XPC_FLAGS': '0x0', 09:07:47 INFO - 'XPC_SERVICE_NAME': '0', 09:07:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:47 INFO - Downloading/unpacking psutil>=3.1.1 09:07:47 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 09:07:47 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 09:07:47 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 09:07:47 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 09:07:47 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 09:07:47 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 09:07:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:07:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:07:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:07:50 INFO - Installing collected packages: psutil 09:07:50 INFO - Running setup.py install for psutil 09:07:50 INFO - building 'psutil._psutil_osx' extension 09:07:50 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 09:07:50 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 09:07:51 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 09:07:51 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 09:07:51 INFO - building 'psutil._psutil_posix' extension 09:07:51 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 09:07:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:07:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:07:51 INFO - ^ 09:07:51 INFO - 1 warning generated. 09:07:51 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 09:07:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:07:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:07:51 INFO - Successfully installed psutil 09:07:51 INFO - Cleaning up... 09:07:51 INFO - Return code: 0 09:07:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:07:51 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')]} 09:07:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:51 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')]} 09:07:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:51 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 0x107848a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10784e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c8b56c7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076ecd30>, '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 0x107510db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076e3b20>, '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.VTrqcJMI3x/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.BqaLnFvaJP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 09:07:51 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 09:07:51 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 09:07:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VTrqcJMI3x/Render', 09:07:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:51 INFO - 'HOME': '/Users/cltbld', 09:07:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:51 INFO - 'LOGNAME': 'cltbld', 09:07:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:51 INFO - 'MOZ_NO_REMOTE': '1', 09:07:51 INFO - 'NO_EM_RESTART': '1', 09:07:51 INFO - 'PAGER': '/bin/cat', 09:07:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:51 INFO - 'PWD': '/builds/slave/test', 09:07:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:51 INFO - 'SHELL': '/bin/bash', 09:07:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BqaLnFvaJP/Listeners', 09:07:51 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 09:07:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:51 INFO - 'USER': 'cltbld', 09:07:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:51 INFO - 'XPC_FLAGS': '0x0', 09:07:51 INFO - 'XPC_SERVICE_NAME': '0', 09:07:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:07:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:07:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:07:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:07:54 INFO - Installing collected packages: mozsystemmonitor 09:07:54 INFO - Running setup.py install for mozsystemmonitor 09:07:54 INFO - Successfully installed mozsystemmonitor 09:07:54 INFO - Cleaning up... 09:07:54 INFO - Return code: 0 09:07:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:07:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:07:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:07:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107848a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10784e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c8b56c7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076ecd30>, '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 0x107510db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076e3b20>, '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.VTrqcJMI3x/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.BqaLnFvaJP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 09:07:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:07:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:07:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VTrqcJMI3x/Render', 09:07:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:54 INFO - 'HOME': '/Users/cltbld', 09:07:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:54 INFO - 'LOGNAME': 'cltbld', 09:07:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:54 INFO - 'MOZ_NO_REMOTE': '1', 09:07:54 INFO - 'NO_EM_RESTART': '1', 09:07:54 INFO - 'PAGER': '/bin/cat', 09:07:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:54 INFO - 'PWD': '/builds/slave/test', 09:07:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:54 INFO - 'SHELL': '/bin/bash', 09:07:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BqaLnFvaJP/Listeners', 09:07:54 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 09:07:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:54 INFO - 'USER': 'cltbld', 09:07:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:54 INFO - 'XPC_FLAGS': '0x0', 09:07:54 INFO - 'XPC_SERVICE_NAME': '0', 09:07:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:54 INFO - Downloading/unpacking blobuploader==1.2.4 09:07:54 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 09:07:54 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 09:07:54 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 09:07:54 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 09:07:54 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 09:07:54 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 09:07:57 INFO - Downloading blobuploader-1.2.4.tar.gz 09:07:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:07:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:07:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:07:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:07:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:58 INFO - Downloading docopt-0.6.1.tar.gz 09:07:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:07:58 INFO - Installing collected packages: blobuploader, requests, docopt 09:07:58 INFO - Running setup.py install for blobuploader 09:07:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:07:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:07:58 INFO - Running setup.py install for requests 09:07:59 INFO - Running setup.py install for docopt 09:07:59 INFO - Successfully installed blobuploader requests docopt 09:07:59 INFO - Cleaning up... 09:07:59 INFO - Return code: 0 09:07:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:07:59 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')]} 09:07:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:59 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')]} 09:07:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:59 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 0x107848a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10784e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c8b56c7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076ecd30>, '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 0x107510db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076e3b20>, '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.VTrqcJMI3x/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.BqaLnFvaJP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 09:07:59 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 09:07:59 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 09:07:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VTrqcJMI3x/Render', 09:07:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:59 INFO - 'HOME': '/Users/cltbld', 09:07:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:59 INFO - 'LOGNAME': 'cltbld', 09:07:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:59 INFO - 'MOZ_NO_REMOTE': '1', 09:07:59 INFO - 'NO_EM_RESTART': '1', 09:07:59 INFO - 'PAGER': '/bin/cat', 09:07:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:59 INFO - 'PWD': '/builds/slave/test', 09:07:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:59 INFO - 'SHELL': '/bin/bash', 09:07:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BqaLnFvaJP/Listeners', 09:07:59 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 09:07:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:59 INFO - 'USER': 'cltbld', 09:07:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:59 INFO - 'XPC_FLAGS': '0x0', 09:07:59 INFO - 'XPC_SERVICE_NAME': '0', 09:07:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:07:59 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-mBt9ZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:07:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:07:59 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-iAl4lN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:07:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:07:59 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-aPGsy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:08:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:08:00 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-DMjm5a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:08:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:08:00 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-Bs3gVU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:08:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:08:00 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-lFVvbW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:08:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:08:00 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-80CpFp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:08:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:08:00 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-nAijk6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:08:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:08:00 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-gGILU3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:08:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:08:00 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-HUItgG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:08:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:08:00 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-GcNmvS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:08:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:08:00 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-UlpPHs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:08:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:08:01 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-YwaF7D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:08:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:08:01 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-xcy1ie-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:08:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:08:01 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-B_Zm3i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:08:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:08:01 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-TWSWhR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:08:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:08:01 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-l3Jz5e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:08:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:08:01 INFO - Running setup.py install for manifestparser 09:08:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:08:01 INFO - Running setup.py install for mozcrash 09:08:01 INFO - Running setup.py install for mozdebug 09:08:01 INFO - Running setup.py install for mozdevice 09:08:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:08:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:08:02 INFO - Running setup.py install for mozfile 09:08:02 INFO - Running setup.py install for mozhttpd 09:08:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:08:02 INFO - Running setup.py install for mozinfo 09:08:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:08:02 INFO - Running setup.py install for mozInstall 09:08:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:08:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:08:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:08:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:08:02 INFO - Running setup.py install for mozleak 09:08:02 INFO - Running setup.py install for mozlog 09:08:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:08:03 INFO - Running setup.py install for moznetwork 09:08:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:08:03 INFO - Running setup.py install for mozprocess 09:08:03 INFO - Running setup.py install for mozprofile 09:08:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:08:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:08:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:08:03 INFO - Running setup.py install for mozrunner 09:08:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:08:03 INFO - Running setup.py install for mozscreenshot 09:08:03 INFO - Running setup.py install for moztest 09:08:03 INFO - Running setup.py install for mozversion 09:08:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:08:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:08:04 INFO - Cleaning up... 09:08:04 INFO - Return code: 0 09:08:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:08:04 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')]} 09:08:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:04 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')]} 09:08:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:04 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 0x107848a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10784e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c8b56c7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076ecd30>, '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 0x107510db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076e3b20>, '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.VTrqcJMI3x/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.BqaLnFvaJP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 09:08:04 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 09:08:04 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 09:08:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VTrqcJMI3x/Render', 09:08:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:08:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:08:04 INFO - 'HOME': '/Users/cltbld', 09:08:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:08:04 INFO - 'LOGNAME': 'cltbld', 09:08:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:04 INFO - 'MOZ_NO_REMOTE': '1', 09:08:04 INFO - 'NO_EM_RESTART': '1', 09:08:04 INFO - 'PAGER': '/bin/cat', 09:08:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:08:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:04 INFO - 'PWD': '/builds/slave/test', 09:08:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:08:04 INFO - 'SHELL': '/bin/bash', 09:08:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BqaLnFvaJP/Listeners', 09:08:04 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 09:08:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:08:04 INFO - 'USER': 'cltbld', 09:08:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:08:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:08:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:04 INFO - 'XPC_FLAGS': '0x0', 09:08:04 INFO - 'XPC_SERVICE_NAME': '0', 09:08:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:08:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:08:04 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-DssrJE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:08:04 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)) 09:08:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:08:04 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-y5kRcJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:08:04 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)) 09:08:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:08:04 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-_Rmjpl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:08:04 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)) 09:08:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:08:04 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-FqkheT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:08:04 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)) 09:08:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:08:04 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-Xvmocz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:08:04 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)) 09:08:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:08:04 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-w0TVG8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:08:05 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)) 09:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:08:05 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-8Tman2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:08:05 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)) 09:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:08:05 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-KIviUU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:08:05 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)) 09:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:08:05 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-uge3A6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:08:05 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)) 09:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:08:05 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-gyzmMx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:08:05 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)) 09:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:08:05 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-4abP7i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:08:05 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)) 09:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:08:05 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-LfFzhN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:08:05 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)) 09:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:08:05 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-E_qWuP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:08:05 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)) 09:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:08:05 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-8VqEJF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:08:05 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)) 09:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:08:05 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-lIgilZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:08:06 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)) 09:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:08:06 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-6pDAT5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:08:06 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)) 09:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:08:06 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-WFuYt_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:08:06 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)) 09:08:06 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)) 09:08:06 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)) 09:08:06 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)) 09:08:06 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)) 09:08:06 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)) 09:08:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:08: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 09:08: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 09:08: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 09:08: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 09:08: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 09:08: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 09:08:08 INFO - Downloading blessings-1.6.tar.gz 09:08:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:08:08 INFO - Installing collected packages: blessings 09:08:08 INFO - Running setup.py install for blessings 09:08:08 INFO - Successfully installed blessings 09:08:08 INFO - Cleaning up... 09:08:09 INFO - Return code: 0 09:08:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:08:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:08:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:08:09 INFO - Reading from file tmpfile_stdout 09:08:09 INFO - Current package versions: 09:08:09 INFO - blessings == 1.6 09:08:09 INFO - blobuploader == 1.2.4 09:08:09 INFO - docopt == 0.6.1 09:08:09 INFO - manifestparser == 1.1 09:08:09 INFO - mozInstall == 1.12 09:08:09 INFO - mozcrash == 0.17 09:08:09 INFO - mozdebug == 0.1 09:08:09 INFO - mozdevice == 0.48 09:08:09 INFO - mozfile == 1.2 09:08:09 INFO - mozhttpd == 0.7 09:08:09 INFO - mozinfo == 0.9 09:08:09 INFO - mozleak == 0.1 09:08:09 INFO - mozlog == 3.2 09:08:09 INFO - moznetwork == 0.27 09:08:09 INFO - mozprocess == 0.22 09:08:09 INFO - mozprofile == 0.28 09:08:09 INFO - mozrunner == 6.11 09:08:09 INFO - mozscreenshot == 0.1 09:08:09 INFO - mozsystemmonitor == 0.3 09:08:09 INFO - moztest == 0.7 09:08:09 INFO - mozversion == 1.4 09:08:09 INFO - psutil == 3.1.1 09:08:09 INFO - requests == 1.2.3 09:08:09 INFO - wsgiref == 0.1.2 09:08:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:08: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')]} 09:08:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08: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')]} 09:08:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:09 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 0x107848a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10784e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c8b56c7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076ecd30>, '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 0x107510db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076e3b20>, '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.VTrqcJMI3x/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.BqaLnFvaJP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 09:08:09 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 09:08:09 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 09:08:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VTrqcJMI3x/Render', 09:08:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:08:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:08:09 INFO - 'HOME': '/Users/cltbld', 09:08:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:08:09 INFO - 'LOGNAME': 'cltbld', 09:08:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:09 INFO - 'MOZ_NO_REMOTE': '1', 09:08:09 INFO - 'NO_EM_RESTART': '1', 09:08:09 INFO - 'PAGER': '/bin/cat', 09:08:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:08:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:09 INFO - 'PWD': '/builds/slave/test', 09:08:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:08:09 INFO - 'SHELL': '/bin/bash', 09:08:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BqaLnFvaJP/Listeners', 09:08:09 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 09:08:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:08:09 INFO - 'USER': 'cltbld', 09:08:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:08:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:08:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:09 INFO - 'XPC_FLAGS': '0x0', 09:08:09 INFO - 'XPC_SERVICE_NAME': '0', 09:08:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:08:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:09 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)) 09:08:09 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)) 09:08:09 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)) 09:08:09 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)) 09:08:09 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)) 09:08:09 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)) 09:08:09 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)) 09:08:09 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)) 09:08:09 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)) 09:08:09 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)) 09:08:09 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)) 09:08:09 INFO - Cleaning up... 09:08:09 INFO - Return code: 0 09:08:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:08: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')]} 09:08:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08: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')]} 09:08:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107848a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10784e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c8b56c7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076ecd30>, '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 0x107510db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076e3b20>, '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.VTrqcJMI3x/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.BqaLnFvaJP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 09:08: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', 'jsonschema'] in /builds/slave/test/build 09:08: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 jsonschema 09:08:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VTrqcJMI3x/Render', 09:08:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:08:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:08:09 INFO - 'HOME': '/Users/cltbld', 09:08:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:08:09 INFO - 'LOGNAME': 'cltbld', 09:08:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:09 INFO - 'MOZ_NO_REMOTE': '1', 09:08:09 INFO - 'NO_EM_RESTART': '1', 09:08:09 INFO - 'PAGER': '/bin/cat', 09:08:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:08:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:09 INFO - 'PWD': '/builds/slave/test', 09:08:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:08:09 INFO - 'SHELL': '/bin/bash', 09:08:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BqaLnFvaJP/Listeners', 09:08:09 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 09:08:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:08:09 INFO - 'USER': 'cltbld', 09:08:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:08:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:08:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:09 INFO - 'XPC_FLAGS': '0x0', 09:08:09 INFO - 'XPC_SERVICE_NAME': '0', 09:08:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:08:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:09 INFO - Downloading/unpacking jsonschema 09:08: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 09:08: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 09:08: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 09:08: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 09:08: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 09:08: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 09:08:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:08:12 INFO - Downloading/unpacking functools32 (from jsonschema) 09:08: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 09:08: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 09:08: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 09:08: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 09:08: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 09:08: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 09:08:12 INFO - Downloading functools32-3.2.3-2.tar.gz 09:08:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:08:12 INFO - warning: no files found matching '*.txt' 09:08:12 INFO - no previously-included directories found matching 'build' 09:08:12 INFO - no previously-included directories found matching 'dist' 09:08:12 INFO - no previously-included directories found matching '.git*' 09:08:12 INFO - Installing collected packages: jsonschema, functools32 09:08:12 INFO - Running setup.py install for functools32 09:08:12 INFO - warning: no files found matching '*.txt' 09:08:12 INFO - no previously-included directories found matching 'build' 09:08:12 INFO - no previously-included directories found matching 'dist' 09:08:12 INFO - no previously-included directories found matching '.git*' 09:08:12 INFO - Successfully installed jsonschema functools32 09:08:12 INFO - Cleaning up... 09:08:13 INFO - Return code: 0 09:08:13 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:08:13 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')]} 09:08:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:13 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')]} 09:08:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:13 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 0x107848a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10784e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c8b56c7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076ecd30>, '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 0x107510db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076e3b20>, '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.VTrqcJMI3x/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.BqaLnFvaJP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 09:08:13 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 09:08:13 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 09:08:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VTrqcJMI3x/Render', 09:08:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:08:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:08:13 INFO - 'HOME': '/Users/cltbld', 09:08:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:08:13 INFO - 'LOGNAME': 'cltbld', 09:08:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:13 INFO - 'MOZ_NO_REMOTE': '1', 09:08:13 INFO - 'NO_EM_RESTART': '1', 09:08:13 INFO - 'PAGER': '/bin/cat', 09:08:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:08:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:13 INFO - 'PWD': '/builds/slave/test', 09:08:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:08:13 INFO - 'SHELL': '/bin/bash', 09:08:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BqaLnFvaJP/Listeners', 09:08:13 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 09:08:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:08:13 INFO - 'USER': 'cltbld', 09:08:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:08:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:08:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:13 INFO - 'XPC_FLAGS': '0x0', 09:08:13 INFO - 'XPC_SERVICE_NAME': '0', 09:08:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:08:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:13 INFO - Downloading/unpacking flake8 09:08:13 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 09:08:13 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 09:08:13 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 09:08:13 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 09:08:13 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 09:08:13 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 09:08:15 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:08:15 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:08:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:16 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:08:16 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:08:16 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 09:08:16 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 09:08:16 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 09:08:16 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 09:08:16 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 09:08:16 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 09:08:16 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:08:16 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 09:08:16 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 09:08:16 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 09:08:16 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 09:08:16 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 09:08:16 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 09:08:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:08:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:08:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:08:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:08:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:08:16 INFO - no previously-included directories found matching 'docs/_build' 09:08:16 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:08:17 INFO - Running setup.py install for pep8 09:08:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:08:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:08:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:08:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:08:17 INFO - no previously-included directories found matching 'docs/_build' 09:08:17 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:08:17 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:08:17 INFO - Cleaning up... 09:08:17 INFO - Return code: 0 09:08:17 INFO - Running post-action listener: _resource_record_post_action 09:08:17 INFO - Running post-action listener: _start_resource_monitoring 09:08:17 INFO - Starting resource monitoring. 09:08:17 INFO - ##### 09:08:17 INFO - ##### Finished create-virtualenv step (success) 09:08:17 INFO - ##### 09:08:17 INFO - ##### 09:08:17 INFO - ##### Running install step. 09:08:17 INFO - ##### 09:08:17 INFO - Running pre-action listener: _resource_record_pre_action 09:08:17 INFO - Running main action method: install 09:08:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:08:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:08:17 INFO - Reading from file tmpfile_stdout 09:08:17 INFO - Detecting whether we're running mozinstall >=1.0... 09:08:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:08:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:08:17 INFO - Reading from file tmpfile_stdout 09:08:17 INFO - Output received: 09:08:17 INFO - Usage: mozinstall [options] installer 09:08:17 INFO - Options: 09:08:17 INFO - -h, --help show this help message and exit 09:08:17 INFO - -d DEST, --destination=DEST 09:08:17 INFO - Directory to install application into. [default: 09:08:17 INFO - "/builds/slave/test"] 09:08:17 INFO - --app=APP Application being installed. [default: firefox] 09:08:17 INFO - mkdir: /builds/slave/test/build/application 09:08:17 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'] 09:08:17 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 09:08:51 INFO - Reading from file tmpfile_stdout 09:08:51 INFO - Output received: 09:08:51 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:08:51 INFO - Running post-action listener: _resource_record_post_action 09:08:51 INFO - ##### 09:08:51 INFO - ##### Finished install step (success) 09:08:51 INFO - ##### 09:08:51 INFO - ##### 09:08:51 INFO - ##### Running run-tests step. 09:08:51 INFO - ##### 09:08:51 INFO - Running pre-action listener: _resource_record_pre_action 09:08:51 INFO - Running main action method: run_tests 09:08:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:08:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:08:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:08:51 INFO - 2016-06-01 09:08:51.508 screenresolution[1640:7558] starting screenresolution argv=screenresolution get 09:08:51 INFO - 2016-06-01 09:08:51.511 screenresolution[1640:7558] Display 0: 1600x1200x32@60 09:08:51 INFO - 2016-06-01 09:08:51.517 screenresolution[1641:7560] starting screenresolution argv=screenresolution list 09:08:51 INFO - Available Modes on Display 0 09:08:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:08:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:08:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:08:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:08:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:08:51 INFO - Intel Iris: 09:08:51 INFO - Chipset Model: Intel Iris 09:08:51 INFO - Type: GPU 09:08:51 INFO - Bus: Built-In 09:08:51 INFO - VRAM (Dynamic, Max): 1536 MB 09:08:51 INFO - Vendor: Intel (0x8086) 09:08:51 INFO - Device ID: 0x0a2e 09:08:51 INFO - Revision ID: 0x0009 09:08:51 INFO - Displays: 09:08:51 INFO - Display: 09:08:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:08:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:08:51 INFO - Display Serial Number: 1600x1200 60 09:08:51 INFO - Main Display: Yes 09:08:51 INFO - Mirror: Off 09:08:51 INFO - Online: Yes 09:08:51 INFO - Rotation: Supported 09:08:51 INFO - Return code: 0 09:08:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:08:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:08:51 INFO - Python 2.7.3 09:08:51 INFO - Return code: 0 09:08:51 INFO - grabbing minidump binary from tooltool 09:08:51 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')]} 09:08:51 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 0x1076ecd30>, '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 0x107510db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076e3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:08:51 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 09:08:51 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 09:08:51 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 09:08:51 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:08:51 INFO - Return code: 0 09:08:51 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:08:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:08:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:08:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:08:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:08:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:08:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:08:52 INFO - Return code: 0 09:08:52 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', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WUC49xLAS7GbVIbqhFhGrw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0292', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:08:52 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 --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WUC49xLAS7GbVIbqhFhGrw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0292 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:08:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VTrqcJMI3x/Render', 09:08:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:08:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:08:52 INFO - 'HOME': '/Users/cltbld', 09:08:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:08:52 INFO - 'LOGNAME': 'cltbld', 09:08:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:08:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:08:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:52 INFO - 'MOZ_NO_REMOTE': '1', 09:08:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:08:52 INFO - 'NO_EM_RESTART': '1', 09:08:52 INFO - 'PAGER': '/bin/cat', 09:08:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:08:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:52 INFO - 'PWD': '/builds/slave/test', 09:08:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:08:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:08:52 INFO - 'SHELL': '/bin/bash', 09:08:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BqaLnFvaJP/Listeners', 09:08:52 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 09:08:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:08:52 INFO - 'USER': 'cltbld', 09:08:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:08:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:08:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:52 INFO - 'XPC_FLAGS': '0x0', 09:08:52 INFO - 'XPC_SERVICE_NAME': '0', 09:08:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:08:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WUC49xLAS7GbVIbqhFhGrw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0292', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:08:53 INFO - mozversion application_buildid: 20160601070144 09:08:53 INFO - mozversion application_changeset: 7520b940afc85c299b6cb500477c8906eecde531 09:08:53 INFO - mozversion application_display_name: Nightly 09:08:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:08:53 INFO - mozversion application_name: Firefox 09:08:53 INFO - mozversion application_remotingname: firefox 09:08:53 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:08:53 INFO - mozversion application_vendor: Mozilla 09:08:53 INFO - mozversion application_version: 49.0a1 09:08:53 INFO - mozversion platform_buildid: 20160601070144 09:08:53 INFO - mozversion platform_changeset: 7520b940afc85c299b6cb500477c8906eecde531 09:08:53 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:08:53 INFO - mozversion platform_version: 49.0a1 09:08:53 INFO - using testdate: 1464797333 09:08:53 INFO - actual date: 1464797333 09:08:53 INFO - starting webserver on 'localhost:49270' 09:08:53 INFO - SUITE-START | Running 2 tests 09:08:53 INFO - TEST-START | dromaeo_css 09:08:53 INFO - operating with platform_type : mac_ 09:08:53 INFO - Initialising browser for dromaeo_css test... 09:08:53 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/tmpUQzY4K/profile http://localhost:49270/getInfo.html) 09:08:55 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 09:08:55 INFO - PROCESS | 1659 | colorDepth:24 09:08:55 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 09:08:55 INFO - PROCESS | 1659 | __metrics 09:08:55 INFO - PROCESS | 1659 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:08:55 ERROR - PROCESS | 1659 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:08:56 INFO - TEST-INFO | 1659: exit 0 09:08:56 INFO - Browser initialized. 09:08:56 INFO - Running cycle 1/1 for dromaeo_css test... 09:08:56 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/tmpUQzY4K/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:09:08 INFO - PROCESS | 1661 | RSS: Main: 241639424 09:09:08 INFO - PROCESS | 1661 | 09:09:59 INFO - PROCESS | 1661 | 1464797399794 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] 09:12:10 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 09:12:11 INFO - PROCESS | 1661 | RSS: Main: 372199424 09:12:11 INFO - PROCESS | 1661 | 09:15:14 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 09:15:14 INFO - PROCESS | 1661 | RSS: Main: 378515456 09:15:14 INFO - PROCESS | 1661 | 09:18:17 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 09:18:17 INFO - PROCESS | 1661 | RSS: Main: 374026240 09:18:17 INFO - PROCESS | 1661 | 09:21:20 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 09:21:20 INFO - PROCESS | 1661 | RSS: Main: 371695616 09:21:20 INFO - PROCESS | 1661 | 09:24:23 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 09:24:24 INFO - PROCESS | 1661 | RSS: Main: 340434944 09:24:24 INFO - PROCESS | 1661 | 09:27:27 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 09:27:27 INFO - PROCESS | 1661 | RSS: Main: 381202432 09:27:27 INFO - PROCESS | 1661 | 09:27:27 INFO - PROCESS | 1661 | __start_tp_report 09:27:27 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 09:27:27 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 09:27:27 INFO - PROCESS | 1661 | |i|pagename|runs| 09:27:27 INFO - PROCESS | 1661 | |0;dojo.html;3434.6050204226513;3461.5326930864326;3475.6176820549736;3483.111806489345;3541.698955588775;4215.810288537015;5543.89122175565;5567.554595632349;5664.830055098354;5717.19959205711;5638.323401191863;5650.604457687974;5714.628549144302;5762.3373312069125;5795.0438177700735;5049.192129259319;5083.389993200821;5154.948450515484;5173.793048278064;5250.159974404096;5203.427622961472;5248.8425347239645;5268.762905669254;5281.155015197569;5302.814401495948;5035.773390197432;5097.872553186163;5124.461931497213;5128.256402821588;5151.330327057496;5129.769160387754;5138.974305128488;5141.974290128525;5144.717040562768;5149.974250128763;5129.384473863123;5156.484351564814;5158.329417175781;5174.430812610647;5221.06020916235;5524.972375138139;5598.552115830724;5717.085266357404;5804.071348584206;5810.680412577307;6176.227971503562;6189.9381006189815;6205.627662340274;6206.968965155191;6220.284667263232;6109.59479319759;6163.3528479509905;6166.814995550096;6178.9691051544905;6197.0704394340755;5696.2594862668;5740.741666625012;5746.367899531048;5903.114532820068;5950.434708702666;6188.381161883865;6212.565120441525;6230.626162430269;6248.218972628421;6267.561270711007;6170;6186.845328866814;6186.938130618636;6191.690415479208;6193.907091393633;6205.844853878689;6223.471004964538;6281.183446151971;6310.432061114522;6311.495080393557;6024.879502409927;6133.907991380132;6138.631682099089;6154.138420621117;6223.96888015566;5100.260462232955;6180.505559555225;6189.04069869162;6207.875842483216;6261.373862613702;4903.975480122648;4958.652894297365;4971.2543118531785;4978.054169707744;4994.800207991712;4447.911041779146;4454.977725111354;4469.128519938646;4472.7092738972515;4484.506704262529;5534.280543529396;5598;5616.101423772258;5697.088465845528;5705.258316418839;5951.434613711691;6305.857502964132;6347.492200623939;6383.106365108889;6390.009548519987;5682.176084467812;5702.0591602385975;5707.828765137053;5715.257016587817;5722.084466485342;5217.478252174752;5233.2935053768015;5252.763625636779;5293.550048245942;5302.761375738101;6249.718762655601;6249.906266403361;6313.715882785377;6332.271788744353;6344.777932772331;5250.947490525045;6289.213848268966;6315.147455093687;6351.96824015877;6376.617402955838;6302.716377763103;6337.081123237197;6338.176037115146;6348.904766428415;6355.87288254223;6008.459238668541;6060.575759696955;6117.035835319846;6189.94770888959;6196.628202307876;6201.131841542278;6243.469305109116;6321.11504389395;6325.652089135142;6334.683265836782;6093.29927058394;6124.265088189446;6136.907946380718;6147.323794382703;6168.25980882297;5948;5952.494038006818;6012.248468941382;6054.303755068224;6118.112873633388;5877.177195192591;5878.794242201502;5880.705964701833;5898.233229680286;5941.643501389931;5882.558808089325;6082.120104647088;6105.511559075175;6113.754702409093;6128.953419355383;5802.332731735904;5848.707564621667;5896.557758168069;5905.586608937505;5907.232059832194 09:27:27 INFO - PROCESS | 1661 | |1;ext.html;500.90983622947857;507.35312476592316;509.41417370024493;515.2245869965701;562.3336346429481;25594.36014099646;41988.95027624311;43206.91982700426;43218.351724724154;43273.35089973653;44736.55263447364;45070;45106.7744661277;45135.54864451355;45166.54833451664;9544.665936692234;9770;9773.853392199122;9782.070703283212;9794.118529332358;9468.763280917963;9627.614895404175;9628;9632.988536203702;9644.469554174517;8145.592720363988;9583.089606487407;9646.9517652412;9672.854907176397;9679.983601721824;9604.951975240114;9645.951770241103;9647.986961369026;9651.613935442574;9686.32195746298;9583;9653.082957119062;9687.370320929118;9703.90296097037;9731.318807683465;41211.7939410304;41587;41651.37522937158;41658.583414165776;41666.79166604148;3471.218975730466;3500.7724497907557;3555.3244883472316;3999.900002499932;4359.041010977611;31420.842895785376;31885.521717174262;31903.042908712774;32251.35497290041;33014.50478242828;3030.5151175811984;3033.8331391773445;3069.6009518762417;3110.3001824589514;3169.31859650174;306690.8661826751;342299;352967;353166;353979;61347.69326153341;62150;62315.37684623186;62437.751244975756;62439.6878015607;34506;34638.30723385518;35256;36914.8154259227;36986.630133698854;15935.760963585555;15978.280977356048;16088.597785055466;16178;16201.675966480605;12076.215046022156;12138.393080345948;12161;12231.449584768707;12235.327057011771;19987.900060499604;20121.49696257576;20163.798362016485;20174;20187;8046.316063134582;8053.436259461899;8059.435839491297;8132.227438393344;8226.506409615442;902.9954850225838;978.9118979291898;994;1005.562580277567;1018.3024628129755;30649.080527584207;30671;31265.374692506477;31419.528707069414;31589.368212635618;1082.8429877667695;1117.6144230240554;1141.1726498288674;1158.7276989907366;1168.339887963298;15811.209439528207;15901.840981590267;16022.27899744491;16274.511764647079;16371.918140409221;648431;652650;653540;655235;656029.7198513977;56975.29074127783;60988;61714.531418743645;61767.691161544804;61879.6906015467;1272.1603741530546;1318.5516924245803;1382.9930850345886;1416.4560628914533;1430.7853821926585;25376.873115634302;31200.531992019685;31575.842120790167;31779;32524.512132317563;48857.267140992204;52203;52282.21576676429;52451.95096098133;52467.47532524626;536941.0988285749;554893;555522;556129;557791;21799.673004905257;23806.928688208725;25601.359966001;26331.341716457242;26876.865615672577;48588.27117593168;49225.769355766395;49451.50548494469;49783;50239;40415.39376909407;40435.7978210107;40455.393169101895;40684.77945661635;41542.58457415387;35487.46768798522;39753.40369894396;39824;40124.398134027426;40663.39004914988;37966.43050354302;38843.22313553769;39089.80455097706;39128.80435597918;39327.016824579616;33177.83411082929;34467.13832154216;35181.82409087938;35418;35517.11207219737;25545.361365966;25853;25974.480510390065;26367.310818671383;26387.73612263853 09:27:27 INFO - PROCESS | 1661 | |2;jquery.html;382.4053696087316;383.53209084916404;388.63080073929757;402.3421705511488;411.91761647670455;19267.32564360248;20867.686984695243;21128;21166.89416552919;21667.45831354213;20388.286409975655;20410.387688369334;21314.680279795775;21344.932753362347;21345.786542134574;5751.626144300607;5789.594728369006;6189.9381006189815;6190.288116866551;6199.349068347826;3899.3566061599945;4129.339305711087;5820.767169313243;6144.324124346318;6153.261608606974;6176.505879529649;6178.907316390235;6182.876342473171;6184.165137706397;6188.443040126388;6495.382938620824;6533.379328963741;6715.059891615178;6777.322267773233;6785.321467853225;3758.267137908109;5509.3664228613825;5864.530837532987;5867.735951882176;5955.553333499962;13172;13415.128016678982;13433.66415839602;20884.791152088437;24889.377765556004;2137.487003119256;2145.817605503534;2246.539459410817;2320.6867072945106;2913.077368936492;9476.905230947741;10032.247581431422;10217.33587316822;10229.539670714836;10427.165826733843;1656.3291866793968;1710.3671641492726;1852.935147269839;1905.2283825050877;1940.7477027986272;124865;126770.36614816867;128498;128627;128707.35646321802;11210.607628732956;11600.767984640428;11624.558842642971;11646.475908584134;11739.765204696028;14467.638309042359;14489.492867749577;14513.782293265609;14532;14567.344469498898;8297.502149871027;8319.292860106838;8366.623501942428;8384.371172162113;8477.915220847835;8116.75649730509;8173.591320433955;8196.729506926436;8283.420160588821;8307.543085130255;4481.983107601593;4557.840475583339;4594;4622.260438329851;4636.281376386665;1463.7365274250524;1792.8587054276857;1834.8623853211063;1929.7394851694842;1968.8818670879507;946.4415994563241;992.6525715999343;1002.4085789384299;1006.7885743993697;1061.7770268243755;18707.15817788203;20346.491337716674;21128.26051088235;21260.724356538656;21286.78713212879;921.2675922641516;977.3354119199013;983.3018556824592;1019.9184065274909;1051.7791263834526;11077.439739284313;11356.602518911965;11465.369404682655;11551.480183391599;11564.479598418091;560619;567402;577047;577173;580488;70822.64588677023;123025;124393.37803310926;126645;127625.72374276324;884.7390019944132;993.8310487217191;1000.1848493477793;1006.9043440873252;1017.6438246613626;18309.908450457664;18314.725279120557;18355.81644183541;18383.72424413608;18495.44513664592;39991;49786.75106624444;50929.04315010617;50994.49005509898;51030;73810.52378952499;76885;77144.0658985134;77383;78529.60735196287;7017.96491017562;7169.996200531826;7759.573223472763;8285.130061343472;8306.127856575215;12851.293178874877;13276.26980516081;13279.269640169498;13566.389512471766;13574.660633483847;11382.14633902444;11395.487203075716;11429;11595.884041159481;11632.476538555955;11339.886601133883;11391.658250252505;11418.371989540656;11465.14011449128;11531;9580.377275477073;11045.005949464587;11737.706557336134;12021.278723276631;12209.755804884031;7442.776716698508;7465.992091067854;7506.962465187639;7674.15584285717;7681.308682218627;10834;11492;11593.072554195476;11698.06415486776;11708.356040417859 09:27:27 INFO - PROCESS | 1661 | |3;mootools.html;1375.6217040313918;1517.6357674158198;1549.3105568022227;1659.8091219509752;1757.3058641836476;7929.484583502075;8435.283000944919;8437.198466145714;8443;8448.746537603882;7471.962640186805;8793.95603021984;8803.471791692504;8825.779355516115;8956.776080597989;2325.6744055832196;2437.70747510299;2438.926832195037;2440.987795061022;2445.3153117127144;2381.714194296687;2414.915477958272;2429.331933718233;2435.1111844176835;2437.317551085699;2398.4603464220586;2407.1935901473025;2419.2984034630026;2423.309356833303;2427.1626288930293;1997.9400617981485;2386.331827088418;2417.746136655652;2419.709634843812;2423.6243382275775;2399.7240317363376;2407.7110746710505;2437.8659173745436;2438;2440.73151953285;8804.559772011375;8859.689910853154;8871.911280887172;8881.555922203865;8882.378233523656;5243.292155559027;5276.261323414725;5438.809641662562;5443.510084092451;5443.965646527147;3595;3695.8336874840697;3748.2878253131817;3757.4927384802977;3769.3968964965475;1076.4994277660926;1097.5554900265406;1097.9890201097967;1101.3336931156696;1117.9552817887275;11695.298282103016;11907.047436205083;11919.940400297943;11923.463444144947;11924.105692073043;4853.417589889236;4858.416989961227;4960.206366981266;4964.131277026579;4987.202047672355;10378.948105259577;10426.009529094876;10447.164226861833;10454.529546170288;10487.161027117812;5526.281583394133;5530.917036244459;5539.861503462445;5545.916811247835;5546.584006199551;4260.403543503912;4521.434820647419;4947.233178857283;4978.850634480971;4995.575376093;3501.949415175437;4065.654419374386;4087.816048277835;4171.186618609342;4178.331466965271;1274.8916342110838;1307.9542216022394;1319.5909268126911;1319.9802002969964;1327.2567362276964;648.5622205011598;804.8309854930413;900.7612982559627;932.2681694869494;953.9983017831249;5142.1258386073705;5597.104463285936;5861.6189947653265;5922.466977971917;5994.280686317583;464.55402813298934;468.1736734663322;468.73516463198183;489.5814078962507;644.874693659567;3760.6615404613717;3933.449317095609;3936.7637941723588;3996.4604778355133;4064.146529228895;10568.207384446197;10807.243492955418;10930.289531180448;10955.34267943926;10972.122230221563;10862.076723478434;10898.782024359469;10899.400532970763;10910.23628346008;10910.23628346024;7870.763877083697;8336.916630833735;8365.874511882448;8395.916040839636;8447.281981031558;6098.878022439438;6100.786472473442;6160.9383906160365;6169.96915015422;6217.067439883873;10087.949560252151;10307.94846025765;10385.948070259601;10400.323978941344;10420;10230.130438912478;10446.52990615409;10468;10681.465926703479;10696.144308455461;7671.961640191764;7755.418343624364;7825.765227043198;7832.295093441618;7835;8325.440940938373;8443.408961372645;8445.366597505335;8474.830503390021;8488.363372747192;5669.829905102853;6889.379955804002;7507.399408047452;7545.396368290414;7714.961425192838;6592.670366481752;7328.377087947584;7520.435967302365;7548.4716069874585;7602.163761986067;6476.190476190476;7172.892406613801;7243.492955493065;7256.600886951057;7271.672774725044;4871.561559459666;4889.997550502209;4974.278729584263;5022.84931452057;5100.566451851634;4113.691473139466;4437.445319335083;5046.873828154326;5197.558207552401;5218.165093585008 09:27:27 INFO - PROCESS | 1661 | |4;prototype.html;290.2135213571222;294.9557566365045;302.6277678455501;320.988765393211;322.7305200157868;4683.9840511626;5329.946700532993;5344.652597581155;5383.919241211385;5408.05359062164;4978.303037574733;5215.713135777531;5254.658447200929;5256.448072952342;5285.048691235576;4022.6926248969057;4039.8182081806244;4058.5941405859326;4062.837486500536;4075.8398049033913;4064.878053658395;4075.3071977763702;4084.7344922579937;4089.9386509202386;4112.293398239727;3313.718333941618;3826.9808650956847;4013.1973605278913;4018.7990600470007;4023.859164929228;3560.412531932241;3754.924901501982;3785.921012511421;3960.5643379228263;3972.483577134983;2893.5804308375377;2966.7984466290973;3140.3248301615035;3522.929541409183;3523.1896663767475;4442.378067070613;4856.538628830255;4989.376327959005;5124.846254612367;5155.937624950013;4079.8780335408;4375.387445757597;4656.996237426841;4669.365721997307;4672.2057250267535;4878;4881.633877459205;5508.366537848158;5560.666360018412;5599.496045355938;3576.535050443452;3885.0870045539277;3910.1006768443417;3916.2167566486814;3917.275304068728;4806.423229212517;5512.558995280408;5669.659820410789;5765.250517432749;5842.604532141844;3346.966530334714;3812.694984401241;3819.2743378757946;3834.118152824866;3843.461915331856;3373.9325213495595;3537.3279076975805;4005.439238506611;4015.594541910308;4022.8390864365683;4541.863744087683;4582.404287442678;4599.895070827179;4622.838200663028;4640.350350950869;5083.593312534988;5398.460153984649;5500.844822587302;5611.80358687444;5620.655709852631;1090.2804149261449;1178.3106882473678;1280.8014757712383;1375.8417781955204;1377.6900197455693;768.7770546541441;825.5418242875262;853.6981103816729;860.0539406652757;866.4324867211986;3965.325894597925;4122.464079669624;4342.826286948487;4346.369776382407;4356.629686476622;4375.5405682403525;4398.8020539075815;4460.442444694413;4830.347903033045;4916.5575098240615;4009.67922566194;4100.5489396166395;4191.685623578243;4324.675649326313;4363.930836944905;2228.6099932511747;2703;2705.7294270572784;3018.0684362008333;3023.712747289048;5973.192599478239;6003.909941350883;6012.152222577466;6024.006039003603;6028.377655719776;5226.63413561047;5557.916631250535;5913.970430147821;5936.317323507766;5965.433283838115;5068.898622027613;5396.676199427969;5964.1372141372385;5969.79105731306;5978.383078237777;5106.948930510647;5369.704666243394;5680.500579744899;5730.627509211888;5751.827445176651;4608.746518941356;4741.478437371955;5290.81482148123;5601.383847776823;5616.859578510406;5204.531592156724;5579.69311687861;5814;5898.557608179318;5958.821235362946;3245.8801713408834;3432.8626854926524;3498.2478767065204;3799.525059367579;3838.5797255015823;5742.540596752333;5757.65454072757;5768.826935191783;5783.913241301468;5808.244928159313;4909.4354149273295;5312.415634280149;5547.861303467445;5576.86057848557;5589.944100558942;5526.889462210814;5534.335879694462;5582.246396736388;5594.356648985418;5596.832095037155;4746.169420351355;5004.424491183415;5270.472952704852;5362.640438207176;5480.479354461319;4840.273958906192;5284.128118860345;5566.582506312124;5581.916271255853;5586.357568879631;4646.395968524071;5485;5485.835424937259;5539.861503462445;5543.251661025871 09:27:27 INFO - PROCESS | 1661 | |5;yui.html;761.0600907878772;771.9421043421743;779.7660701789464;835.817348161499;858.8883445152129;14878.47925322614;15076.849231507682;15274.923625381885;15319.923400382997;15332.540023799276;14070;14077.155370677767;14079.718405631882;14086.859131408683;14135.151890886555;709.5529816215802;735.6432130416744;871.8866547348852;875.7329014650529;893.714011516315;910.3127139010038;921.8018126102886;921.8478950973081;922.0963455813308;922.5156792683861;940.666063547441;958.9328746987713;959.2278216036088;959.3524371049514;959.8752162218901;740.9259074092602;919.5172534419416;949.9287553433451;954.4988880837547;954.8138113067954;712.4051417066755;712.6864179760888;713.4114355656604;713.5255055388161;714.2393351081082;14575.927120364437;14612.853871461255;14624.926875365662;14628.634284142876;14659.48691795782;11225.943870280678;13233.669158271025;13455.394507247198;13476.39356228972;13612.727745445134;10077.798444031077;11754.177207595472;12047.939760301142;12070.577529786504;12346;3511.6839484446523;4436.245838207513;4483.50681425043;4554.658400619966;4637.026224492827;62392.68803655953;79883.60058199729;82103.7684434734;82407.58796206041;83012.3397532052;10766.73083172927;11000.779984400428;11057.281276716987;11172.10623150146;11243.83134252987;21598.136074557155;21641.56716865654;21641.675374869388;21667.024983875763;21715.674264886038;6585.242697089896;7816.921830781733;7965.721199757981;7987.8402431951035;7991.0810256821205;4392.802323895432;4408.316710909814;4411.18393097278;4443.844465443694;4601.585857272795;3374.814385208837;3495.8252087395526;3505.211327451354;3508.526348942911;3509.2630547585295;656.6060363781693;663.7013343995221;665.1818263535878;684.9143857017873;685.8216863615396;562.7692646015196;639.650416711976;640.4684112186874;665.8069159943659;670.0929037359142;8671;8815.779605509913;8894.59974301158;8966.820663586692;9037.728868133967;615.3600255734076;622.5082185073741;644.7324360390479;663.8482233325177;670.3665036540519;4234.174336004449;4236.237477254123;4265.445492086071;4274.358846173099;4411.338299255073;85993.14006859977;86982;88271.67592486118;88377.55811221032;90312.5484372574;73969.89045164327;75881;76580;76730;77826.2217377819;65359.01961470486;66034;67997.66001169963;68046.65976670085;69323.26691832644;8041.356691464785;8118.918810811816;8119.878201827096;8313.958430208053;8425.157484251771;33886.64453421836;43537.78231108824;43774.99999999873;44385;45008.77495612501;57727.711361442925;58689.70655146697;59016.40983590109;60124.69937650284;60289.69855150696;11244.325340479598;11306.717332066435;11328.03711684516;11548.769024619629;11709.297442153158;11970.401479926142;11986;12007.159498834997;12097.879021209676;12329.815052774036;11207.66377008691;11319.773604528027;11422.600208992646;11529.481173347052;11779.587714429954;10643.829178790482;11377.374244416636;11523.481443334902;11923.523059077539;11943.16397852142;11056.778864422828;11337.093032557541;11487.25332853362;11686.357250351311;11789.115816313639;9398.483083430478;9586.66446674363;9662.889998305991;9933.907270200416;9978.351407158512;9439.19766819828;9642.42145471274;9694.903050969682;9708.5145742714;9732.976444336775 09:27:27 INFO - PROCESS | 1661 | __end_tp_report 09:27:27 INFO - PROCESS | 1661 | __start_cc_report 09:27:27 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,1349 09:27:27 INFO - PROCESS | 1661 | __end_cc_report 09:27:27 INFO - PROCESS | 1661 | __startTimestamp1464798447627__endTimestamp 09:27:27 INFO - PROCESS | 1661 | 09:27:27 INFO - PROCESS | 1661 | ------- Summary: start ------- 09:27:27 INFO - PROCESS | 1661 | Number of tests: 6 09:27:27 INFO - PROCESS | 1661 | 09:27:27 INFO - PROCESS | 1661 | [#0] dojo.html Cycles:165 Average:5710.62 Median:5898.23 stddev:640.12 (10.9%) stddev-sans-first:616.68 09:27:27 INFO - PROCESS | 1661 | Values: 3434.6 3461.5 3475.6 3483.1 3541.7 4215.8 5543.9 5567.6 5664.8 5717.2 5638.3 5650.6 5714.6 5762.3 5795.0 5049.2 5083.4 5154.9 5173.8 5250.2 5203.4 5248.8 5268.8 5281.2 5302.8 5035.8 5097.9 5124.5 5128.3 5151.3 5129.8 5139.0 5142.0 5144.7 5150.0 5129.4 5156.5 5158.3 5174.4 5221.1 5525.0 5598.6 5717.1 5804.1 5810.7 6176.2 6189.9 6205.6 6207.0 6220.3 6109.6 6163.4 6166.8 6179.0 6197.1 5696.3 5740.7 5746.4 5903.1 5950.4 6188.4 6212.6 6230.6 6248.2 6267.6 6170.0 6186.8 6186.9 6191.7 6193.9 6205.8 6223.5 6281.2 6310.4 6311.5 6024.9 6133.9 6138.6 6154.1 6224.0 5100.3 6180.5 6189.0 6207.9 6261.4 4904.0 4958.7 4971.3 4978.1 4994.8 4447.9 4455.0 4469.1 4472.7 4484.5 5534.3 5598.0 5616.1 5697.1 5705.3 5951.4 6305.9 6347.5 6383.1 6390.0 5682.2 5702.1 5707.8 5715.3 5722.1 5217.5 5233.3 5252.8 5293.6 5302.8 6249.7 6249.9 6313.7 6332.3 6344.8 5250.9 6289.2 6315.1 6352.0 6376.6 6302.7 6337.1 6338.2 6348.9 6355.9 6008.5 6060.6 6117.0 6189.9 6196.6 6201.1 6243.5 6321.1 6325.7 6334.7 6093.3 6124.3 6136.9 6147.3 6168.3 5948.0 5952.5 6012.2 6054.3 6118.1 5877.2 5878.8 5880.7 5898.2 5941.6 5882.6 6082.1 6105.5 6113.8 6129.0 5802.3 5848.7 5896.6 5905.6 5907.2 09:27:27 INFO - PROCESS | 1661 | 09:27:27 INFO - PROCESS | 1661 | [#1] ext.html Cycles:180 Average:65622.29 Median:40269.90 stddev:142298.10 (353.4%) stddev-sans-first:142613.29 09:27:27 INFO - PROCESS | 1661 | Values: 500.9 507.4 509.4 515.2 562.3 25594.4 41989.0 43206.9 43218.4 43273.4 44736.6 45070.0 45106.8 45135.5 45166.5 9544.7 9770.0 9773.9 9782.1 9794.1 9468.8 9627.6 9628.0 9633.0 9644.5 8145.6 9583.1 9647.0 9672.9 9680.0 9605.0 9646.0 9648.0 9651.6 9686.3 9583.0 9653.1 9687.4 9703.9 9731.3 41211.8 41587.0 41651.4 41658.6 41666.8 3471.2 3500.8 3555.3 3999.9 4359.0 31420.8 31885.5 31903.0 32251.4 33014.5 3030.5 3033.8 3069.6 3110.3 3169.3 306690.9 342299.0 352967.0 353166.0 353979.0 61347.7 62150.0 62315.4 62437.8 62439.7 34506.0 34638.3 35256.0 36914.8 36986.6 15935.8 15978.3 16088.6 16178.0 16201.7 12076.2 12138.4 12161.0 12231.4 12235.3 19987.9 20121.5 20163.8 20174.0 20187.0 8046.3 8053.4 8059.4 8132.2 8226.5 903.0 978.9 994.0 1005.6 1018.3 30649.1 30671.0 31265.4 31419.5 31589.4 1082.8 1117.6 1141.2 1158.7 1168.3 15811.2 15901.8 16022.3 16274.5 16371.9 648431.0 652650.0 653540.0 655235.0 656029.7 56975.3 60988.0 61714.5 61767.7 61879.7 1272.2 1318.6 1383.0 1416.5 1430.8 25376.9 31200.5 31575.8 31779.0 32524.5 48857.3 52203.0 52282.2 52452.0 52467.5 536941.1 554893.0 555522.0 556129.0 557791.0 21799.7 23806.9 25601.4 26331.3 26876.9 48588.3 49225.8 49451.5 49783.0 50239.0 40415.4 40435.8 40455.4 40684.8 41542.6 35487.5 39753.4 39824.0 40124.4 40663.4 37966.4 38843.2 39089.8 39128.8 39327.0 33177.8 34467.1 35181.8 35418.0 35517.1 25545.4 25853.0 25974.5 26367.3 26387.7 09:27:27 INFO - PROCESS | 1661 | 09:27:27 INFO - PROCESS | 1661 | [#2] jquery.html Cycles:180 Average:33875.22 Median:21329.81 stddev:95771.53 (449.0%) stddev-sans-first:96007.18 09:27:27 INFO - PROCESS | 1661 | Values: 382.4 383.5 388.6 402.3 411.9 19267.3 20867.7 21128.0 21166.9 21667.5 20388.3 20410.4 21314.7 21344.9 21345.8 5751.6 5789.6 6189.9 6190.3 6199.3 3899.4 4129.3 5820.8 6144.3 6153.3 6176.5 6178.9 6182.9 6184.2 6188.4 6495.4 6533.4 6715.1 6777.3 6785.3 3758.3 5509.4 5864.5 5867.7 5955.6 13172.0 13415.1 13433.7 20884.8 24889.4 2137.5 2145.8 2246.5 2320.7 2913.1 9476.9 10032.2 10217.3 10229.5 10427.2 1656.3 1710.4 1852.9 1905.2 1940.7 124865.0 126770.4 128498.0 128627.0 128707.4 11210.6 11600.8 11624.6 11646.5 11739.8 14467.6 14489.5 14513.8 14532.0 14567.3 8297.5 8319.3 8366.6 8384.4 8477.9 8116.8 8173.6 8196.7 8283.4 8307.5 4482.0 4557.8 4594.0 4622.3 4636.3 1463.7 1792.9 1834.9 1929.7 1968.9 946.4 992.7 1002.4 1006.8 1061.8 18707.2 20346.5 21128.3 21260.7 21286.8 921.3 977.3 983.3 1019.9 1051.8 11077.4 11356.6 11465.4 11551.5 11564.5 560619.0 567402.0 577047.0 577173.0 580488.0 70822.6 123025.0 124393.4 126645.0 127625.7 884.7 993.8 1000.2 1006.9 1017.6 18309.9 18314.7 18355.8 18383.7 18495.4 39991.0 49786.8 50929.0 50994.5 51030.0 73810.5 76885.0 77144.1 77383.0 78529.6 7018.0 7170.0 7759.6 8285.1 8306.1 12851.3 13276.3 13279.3 13566.4 13574.7 11382.1 11395.5 11429.0 11595.9 11632.5 11339.9 11391.7 11418.4 11465.1 11531.0 9580.4 11045.0 11737.7 12021.3 12209.8 7442.8 7466.0 7507.0 7674.2 7681.3 10834.0 11492.0 11593.1 11698.1 11708.4 09:27:27 INFO - PROCESS | 1661 | 09:27:27 INFO - PROCESS | 1661 | [#3] mootools.html Cycles:180 Average:5772.63 Median:468.45 stddev:3282.58 (700.7%) stddev-sans-first:3275.16 09:27:27 INFO - PROCESS | 1661 | Values: 1375.6 1517.6 1549.3 1659.8 1757.3 7929.5 8435.3 8437.2 8443.0 8448.7 7472.0 8794.0 8803.5 8825.8 8956.8 2325.7 2437.7 2438.9 2441.0 2445.3 2381.7 2414.9 2429.3 2435.1 2437.3 2398.5 2407.2 2419.3 2423.3 2427.2 1997.9 2386.3 2417.7 2419.7 2423.6 2399.7 2407.7 2437.9 2438.0 2440.7 8804.6 8859.7 8871.9 8881.6 8882.4 5243.3 5276.3 5438.8 5443.5 5444.0 3595.0 3695.8 3748.3 3757.5 3769.4 1076.5 1097.6 1098.0 1101.3 1118.0 11695.3 11907.0 11919.9 11923.5 11924.1 4853.4 4858.4 4960.2 4964.1 4987.2 10378.9 10426.0 10447.2 10454.5 10487.2 5526.3 5530.9 5539.9 5545.9 5546.6 4260.4 4521.4 4947.2 4978.9 4995.6 3501.9 4065.7 4087.8 4171.2 4178.3 1274.9 1308.0 1319.6 1320.0 1327.3 648.6 804.8 900.8 932.3 954.0 5142.1 5597.1 5861.6 5922.5 5994.3 464.6 468.2 468.7 489.6 644.9 3760.7 3933.4 3936.8 3996.5 4064.1 10568.2 10807.2 10930.3 10955.3 10972.1 10862.1 10898.8 10899.4 10910.2 10910.2 7870.8 8336.9 8365.9 8395.9 8447.3 6098.9 6100.8 6160.9 6170.0 6217.1 10087.9 10307.9 10385.9 10400.3 10420.0 10230.1 10446.5 10468.0 10681.5 10696.1 7672.0 7755.4 7825.8 7832.3 7835.0 8325.4 8443.4 8445.4 8474.8 8488.4 5669.8 6889.4 7507.4 7545.4 7715.0 6592.7 7328.4 7520.4 7548.5 7602.2 6476.2 7172.9 7243.5 7256.6 7271.7 4871.6 4890.0 4974.3 5022.8 5100.6 4113.7 4437.4 5046.9 5197.6 5218.2 09:27:27 INFO - PROCESS | 1661 | 09:27:27 INFO - PROCESS | 1661 | [#4] prototype.html Cycles:180 Average:4421.18 Median:4835.31 stddev:1389.85 (28.7%) stddev-sans-first:1358.72 09:27:27 INFO - PROCESS | 1661 | Values: 290.2 295.0 302.6 321.0 322.7 4684.0 5329.9 5344.7 5383.9 5408.1 4978.3 5215.7 5254.7 5256.4 5285.0 4022.7 4039.8 4058.6 4062.8 4075.8 4064.9 4075.3 4084.7 4089.9 4112.3 3313.7 3827.0 4013.2 4018.8 4023.9 3560.4 3754.9 3785.9 3960.6 3972.5 2893.6 2966.8 3140.3 3522.9 3523.2 4442.4 4856.5 4989.4 5124.8 5155.9 4079.9 4375.4 4657.0 4669.4 4672.2 4878.0 4881.6 5508.4 5560.7 5599.5 3576.5 3885.1 3910.1 3916.2 3917.3 4806.4 5512.6 5669.7 5765.3 5842.6 3347.0 3812.7 3819.3 3834.1 3843.5 3373.9 3537.3 4005.4 4015.6 4022.8 4541.9 4582.4 4599.9 4622.8 4640.4 5083.6 5398.5 5500.8 5611.8 5620.7 1090.3 1178.3 1280.8 1375.8 1377.7 768.8 825.5 853.7 860.1 866.4 3965.3 4122.5 4342.8 4346.4 4356.6 4375.5 4398.8 4460.4 4830.3 4916.6 4009.7 4100.5 4191.7 4324.7 4363.9 2228.6 2703.0 2705.7 3018.1 3023.7 5973.2 6003.9 6012.2 6024.0 6028.4 5226.6 5557.9 5914.0 5936.3 5965.4 5068.9 5396.7 5964.1 5969.8 5978.4 5106.9 5369.7 5680.5 5730.6 5751.8 4608.7 4741.5 5290.8 5601.4 5616.9 5204.5 5579.7 5814.0 5898.6 5958.8 3245.9 3432.9 3498.2 3799.5 3838.6 5742.5 5757.7 5768.8 5783.9 5808.2 4909.4 5312.4 5547.9 5576.9 5589.9 5526.9 5534.3 5582.2 5594.4 5596.8 4746.2 5004.4 5270.5 5362.6 5480.5 4840.3 5284.1 5566.6 5581.9 5586.4 4646.4 5485.0 5485.8 5539.9 5543.3 09:27:27 INFO - PROCESS | 1661 | 09:27:27 INFO - PROCESS | 1661 | [#5] yui.html Cycles:180 Average:17712.53 Median:30452.53 stddev:24166.02 (79.4%) stddev-sans-first:24200.29 09:27:27 INFO - PROCESS | 1661 | Values: 761.1 771.9 779.8 835.8 858.9 14878.5 15076.8 15274.9 15319.9 15332.5 14070.0 14077.2 14079.7 14086.9 14135.2 709.6 735.6 871.9 875.7 893.7 910.3 921.8 921.8 922.1 922.5 940.7 958.9 959.2 959.4 959.9 740.9 919.5 949.9 954.5 954.8 712.4 712.7 713.4 713.5 714.2 14575.9 14612.9 14624.9 14628.6 14659.5 11225.9 13233.7 13455.4 13476.4 13612.7 10077.8 11754.2 12047.9 12070.6 12346.0 3511.7 4436.2 4483.5 4554.7 4637.0 62392.7 79883.6 82103.8 82407.6 83012.3 10766.7 11000.8 11057.3 11172.1 11243.8 21598.1 21641.6 21641.7 21667.0 21715.7 6585.2 7816.9 7965.7 7987.8 7991.1 4392.8 4408.3 4411.2 4443.8 4601.6 3374.8 3495.8 3505.2 3508.5 3509.3 656.6 663.7 665.2 684.9 685.8 562.8 639.7 640.5 665.8 670.1 8671.0 8815.8 8894.6 8966.8 9037.7 615.4 622.5 644.7 663.8 670.4 4234.2 4236.2 4265.4 4274.4 4411.3 85993.1 86982.0 88271.7 88377.6 90312.5 73969.9 75881.0 76580.0 76730.0 77826.2 65359.0 66034.0 67997.7 68046.7 69323.3 8041.4 8118.9 8119.9 8314.0 8425.2 33886.6 43537.8 43775.0 44385.0 45008.8 57727.7 58689.7 59016.4 60124.7 60289.7 11244.3 11306.7 11328.0 11548.8 11709.3 11970.4 11986.0 12007.2 12097.9 12329.8 11207.7 11319.8 11422.6 11529.5 11779.6 10643.8 11377.4 11523.5 11923.5 11943.2 11056.8 11337.1 11487.3 11686.4 11789.1 9398.5 9586.7 9662.9 9933.9 9978.4 9439.2 9642.4 9694.9 9708.5 9733.0 09:27:27 INFO - PROCESS | 1661 | -------- Summary: end -------- 09:27:27 INFO - PROCESS | 1661 | 09:27:28 INFO - TEST-INFO | 1661: exit 0 09:27:28 INFO - TEST-OK | dromaeo_css | took 1115049ms 09:27:28 INFO - TEST-START | kraken 09:27:28 INFO - operating with platform_type : mac_ 09:27:28 INFO - Initialising browser for kraken test... 09:27:28 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/tmpXNBDs9/profile http://localhost:49270/getInfo.html) 09:27:30 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 09:27:30 INFO - PROCESS | 1670 | colorDepth:24 09:27:30 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 09:27:30 INFO - PROCESS | 1670 | __metrics 09:27:30 INFO - PROCESS | 1670 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:27:30 ERROR - PROCESS | 1670 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:27:31 INFO - TEST-INFO | 1670: exit 0 09:27:31 INFO - Browser initialized. 09:27:31 INFO - Running cycle 1/1 for kraken test... 09:27:31 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/tmpXNBDs9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:27:43 INFO - PROCESS | 1671 | RSS: Main: 241491968 09:27:43 INFO - PROCESS | 1671 | 09:27:46 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 09:27:47 INFO - PROCESS | 1671 | RSS: Main: 291717120 09:27:47 INFO - PROCESS | 1671 | 09:27:49 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 09:27:50 INFO - PROCESS | 1671 | RSS: Main: 301416448 09:27:50 INFO - PROCESS | 1671 | 09:27:53 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 09:27:54 INFO - PROCESS | 1671 | RSS: Main: 387989504 09:27:54 INFO - PROCESS | 1671 | 09:27:56 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 09:27:57 INFO - PROCESS | 1671 | RSS: Main: 290856960 09:27:57 INFO - PROCESS | 1671 | 09:27:59 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:27:59 INFO - PROCESS | 1671 | RSS: Main: 293519360 09:27:59 INFO - PROCESS | 1671 | 09:28:02 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 09:28:03 INFO - PROCESS | 1671 | RSS: Main: 331341824 09:28:03 INFO - PROCESS | 1671 | 09:28:06 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 09:28:07 INFO - PROCESS | 1671 | RSS: Main: 333086720 09:28:07 INFO - PROCESS | 1671 | 09:28:09 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 09:28:10 INFO - PROCESS | 1671 | RSS: Main: 332283904 09:28:10 INFO - PROCESS | 1671 | 09:28:12 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:28:12 INFO - PROCESS | 1671 | RSS: Main: 309219328 09:28:12 INFO - PROCESS | 1671 | 09:28:14 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:28:15 INFO - PROCESS | 1671 | RSS: Main: 315805696 09:28:15 INFO - PROCESS | 1671 | 09:28:17 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:28:18 INFO - PROCESS | 1671 | RSS: Main: 320372736 09:28:18 INFO - PROCESS | 1671 | 09:28:21 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:28:21 INFO - PROCESS | 1671 | RSS: Main: 323457024 09:28:21 INFO - PROCESS | 1671 | 09:28:24 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:28:25 INFO - PROCESS | 1671 | RSS: Main: 322932736 09:28:25 INFO - PROCESS | 1671 | 09:28:27 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 09:28:27 INFO - PROCESS | 1671 | RSS: Main: 366948352 09:28:27 INFO - PROCESS | 1671 | 09:28:27 INFO - PROCESS | 1671 | __start_tp_report 09:28:27 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 09:28:27 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 09:28:27 INFO - PROCESS | 1671 | |i|pagename|runs| 09:28:27 INFO - PROCESS | 1671 | |0;ai-astar;113;105;113;91;155;167;156;111;113;113 09:28:27 INFO - PROCESS | 1671 | |1;audio-beat-detection;155;120;119;176;108;143;165;142;179;120 09:28:27 INFO - PROCESS | 1671 | |2;audio-dft;181;185;185;181;183;185;182;182;182;182 09:28:27 INFO - PROCESS | 1671 | |3;audio-fft;78;75;57;109;57;56;58;59;128;55 09:28:27 INFO - PROCESS | 1671 | |4;audio-oscillator;84;86;94;70;74;67;95;93;99;134 09:28:27 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;88;86;96;131;112;85;89;86;92;131 09:28:27 INFO - PROCESS | 1671 | |6;imaging-darkroom;93;94;95;97;93;94;94;93;93;94 09:28:27 INFO - PROCESS | 1671 | |7;imaging-desaturate;84;84;84;109;133;134;85;84;84;92 09:28:27 INFO - PROCESS | 1671 | |8;json-parse-financial;56;58;55;57;55;56;54;54;53;54 09:28:27 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;65;75;70;79;70;71;68;68;69;70 09:28:27 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;87;86;87;86;86;89;95;105;151;87 09:28:27 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;138;159;157;145;142;137;137;123;153;129 09:28:27 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;167;166;167;167;165;163;166;172;225;168 09:28:27 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;68;67;68;66;64;64;66;66;65;66 09:28:27 INFO - PROCESS | 1671 | __end_tp_report 09:28:27 INFO - PROCESS | 1671 | __start_cc_report 09:28:27 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,1940 09:28:27 INFO - PROCESS | 1671 | __end_cc_report 09:28:27 INFO - PROCESS | 1671 | __startTimestamp1464798507342__endTimestamp 09:28:27 INFO - PROCESS | 1671 | 09:28:27 INFO - PROCESS | 1671 | ------- Summary: start ------- 09:28:27 INFO - PROCESS | 1671 | Number of tests: 14 09:28:27 INFO - PROCESS | 1671 | 09:28:27 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:123.70 Median:134.00 stddev:25.68 (19.2%) stddev-sans-first:26.95 09:28:27 INFO - PROCESS | 1671 | Values: 113.0 105.0 113.0 91.0 155.0 167.0 156.0 111.0 113.0 113.0 09:28:27 INFO - PROCESS | 1671 | 09:28:27 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:142.70 Median:149.00 stddev:25.54 (17.1%) stddev-sans-first:26.70 09:28:27 INFO - PROCESS | 1671 | Values: 155.0 120.0 119.0 176.0 108.0 143.0 165.0 142.0 179.0 120.0 09:28:27 INFO - PROCESS | 1671 | 09:28:27 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:182.80 Median:182.50 stddev:1.62 (0.9%) stddev-sans-first:1.58 09:28:27 INFO - PROCESS | 1671 | Values: 181.0 185.0 185.0 181.0 183.0 185.0 182.0 182.0 182.0 182.0 09:28:27 INFO - PROCESS | 1671 | 09:28:27 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:73.20 Median:57.50 stddev:25.59 (44.5%) stddev-sans-first:27.09 09:28:27 INFO - PROCESS | 1671 | Values: 78.0 75.0 57.0 109.0 57.0 56.0 58.0 59.0 128.0 55.0 09:28:27 INFO - PROCESS | 1671 | 09:28:27 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:89.60 Median:89.50 stddev:19.16 (21.4%) stddev-sans-first:20.21 09:28:27 INFO - PROCESS | 1671 | Values: 84.0 86.0 94.0 70.0 74.0 67.0 95.0 93.0 99.0 134.0 09:28:27 INFO - PROCESS | 1671 | 09:28:27 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:99.60 Median:87.00 stddev:18.34 (21.1%) stddev-sans-first:18.96 09:28:27 INFO - PROCESS | 1671 | Values: 88.0 86.0 96.0 131.0 112.0 85.0 89.0 86.0 92.0 131.0 09:28:27 INFO - PROCESS | 1671 | 09:28:27 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:94.00 Median:94.00 stddev:1.25 (1.3%) stddev-sans-first:1.27 09:28:27 INFO - PROCESS | 1671 | Values: 93.0 94.0 95.0 97.0 93.0 94.0 94.0 93.0 93.0 94.0 09:28:27 INFO - PROCESS | 1671 | 09:28:27 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:97.30 Median:84.00 stddev:20.61 (24.5%) stddev-sans-first:21.29 09:28:27 INFO - PROCESS | 1671 | Values: 84.0 84.0 84.0 109.0 133.0 134.0 85.0 84.0 84.0 92.0 09:28:27 INFO - PROCESS | 1671 | 09:28:27 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:55.20 Median:55.50 stddev:1.55 (2.8%) stddev-sans-first:1.62 09:28:27 INFO - PROCESS | 1671 | Values: 56.0 58.0 55.0 57.0 55.0 56.0 54.0 54.0 53.0 54.0 09:28:27 INFO - PROCESS | 1671 | 09:28:27 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:70.50 Median:70.00 stddev:3.92 (5.6%) stddev-sans-first:3.62 09:28:27 INFO - PROCESS | 1671 | Values: 65.0 75.0 70.0 79.0 70.0 71.0 68.0 68.0 69.0 70.0 09:28:27 INFO - PROCESS | 1671 | 09:28:27 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:95.90 Median:87.00 stddev:20.27 (23.3%) stddev-sans-first:21.25 09:28:27 INFO - PROCESS | 1671 | Values: 87.0 86.0 87.0 86.0 86.0 89.0 95.0 105.0 151.0 87.0 09:28:27 INFO - PROCESS | 1671 | 09:28:27 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:142.00 Median:143.50 stddev:11.74 (8.2%) stddev-sans-first:12.36 09:28:27 INFO - PROCESS | 1671 | Values: 138.0 159.0 157.0 145.0 142.0 137.0 137.0 123.0 153.0 129.0 09:28:27 INFO - PROCESS | 1671 | 09:28:27 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.60 Median:167.00 stddev:18.55 (11.1%) stddev-sans-first:19.57 09:28:27 INFO - PROCESS | 1671 | Values: 167.0 166.0 167.0 167.0 165.0 163.0 166.0 172.0 225.0 168.0 09:28:27 INFO - PROCESS | 1671 | 09:28:27 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:66.00 stddev:1.41 (2.1%) stddev-sans-first:1.30 09:28:27 INFO - PROCESS | 1671 | Values: 68.0 67.0 68.0 66.0 64.0 64.0 66.0 66.0 65.0 66.0 09:28:27 INFO - PROCESS | 1671 | -------- Summary: end -------- 09:28:27 INFO - PROCESS | 1671 | 09:28:28 INFO - TEST-INFO | 1671: exit 0 09:28:28 INFO - TEST-OK | kraken | took 59898ms 09:28:28 INFO - SUITE-END | took 1174s 09:28:28 INFO - Completed test suite (00:19:35) 09:28:28 INFO - javascript benchmark 09:28:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3434.6050204226513, 3461.5326930864326, 3475.6176820549736, 3483.111806489345, 3541.698955588775, 4215.810288537015, 5543.89122175565, 5567.554595632349, 5664.830055098354, 5717.19959205711, 5638.323401191863, 5650.604457687974, 5714.628549144302, 5762.3373312069125, 5795.0438177700735, 5049.192129259319, 5083.389993200821, 5154.948450515484, 5173.793048278064, 5250.159974404096, 5203.427622961472, 5248.8425347239645, 5268.762905669254, 5281.155015197569, 5302.814401495948, 5035.773390197432, 5097.872553186163, 5124.461931497213, 5128.256402821588, 5151.330327057496, 5129.769160387754, 5138.974305128488, 5141.974290128525, 5144.717040562768, 5149.974250128763, 5129.384473863123, 5156.484351564814, 5158.329417175781, 5174.430812610647, 5221.06020916235, 5524.972375138139, 5598.552115830724, 5717.085266357404, 5804.071348584206, 5810.680412577307, 6176.227971503562, 6189.9381006189815, 6205.627662340274, 6206.968965155191, 6220.284667263232, 6109.59479319759, 6163.3528479509905, 6166.814995550096, 6178.9691051544905, 6197.0704394340755, 5696.2594862668, 5740.741666625012, 5746.367899531048, 5903.114532820068, 5950.434708702666, 6188.381161883865, 6212.565120441525, 6230.626162430269, 6248.218972628421, 6267.561270711007, 6170.0, 6186.845328866814, 6186.938130618636, 6191.690415479208, 6193.907091393633, 6205.844853878689, 6223.471004964538, 6281.183446151971, 6310.432061114522, 6311.495080393557, 6024.879502409927, 6133.907991380132, 6138.631682099089, 6154.138420621117, 6223.96888015566, 5100.260462232955, 6180.505559555225, 6189.04069869162, 6207.875842483216, 6261.373862613702, 4903.975480122648, 4958.652894297365, 4971.2543118531785, 4978.054169707744, 4994.800207991712, 4447.911041779146, 4454.977725111354, 4469.128519938646, 4472.7092738972515, 4484.506704262529, 5534.280543529396, 5598.0, 5616.101423772258, 5697.088465845528, 5705.258316418839, 5951.434613711691, 6305.857502964132, 6347.492200623939, 6383.106365108889, 6390.009548519987, 5682.176084467812, 5702.0591602385975, 5707.828765137053, 5715.257016587817, 5722.084466485342, 5217.478252174752, 5233.2935053768015, 5252.763625636779, 5293.550048245942, 5302.761375738101, 6249.718762655601, 6249.906266403361, 6313.715882785377, 6332.271788744353, 6344.777932772331, 5250.947490525045, 6289.213848268966, 6315.147455093687, 6351.96824015877, 6376.617402955838, 6302.716377763103, 6337.081123237197, 6338.176037115146, 6348.904766428415, 6355.87288254223, 6008.459238668541, 6060.575759696955, 6117.035835319846, 6189.94770888959, 6196.628202307876, 6201.131841542278, 6243.469305109116, 6321.11504389395, 6325.652089135142, 6334.683265836782, 6093.29927058394, 6124.265088189446, 6136.907946380718, 6147.323794382703, 6168.25980882297, 5948.0, 5952.494038006818, 6012.248468941382, 6054.303755068224, 6118.112873633388, 5877.177195192591, 5878.794242201502, 5880.705964701833, 5898.233229680286, 5941.643501389931, 5882.558808089325, 6082.120104647088, 6105.511559075175, 6113.754702409093, 6128.953419355383, 5802.332731735904, 5848.707564621667, 5896.557758168069, 5905.586608937505, 5907.232059832194], "value": 5671.674746685658, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.90983622947857, 507.35312476592316, 509.41417370024493, 515.2245869965701, 562.3336346429481, 25594.36014099646, 41988.95027624311, 43206.91982700426, 43218.351724724154, 43273.35089973653, 44736.55263447364, 45070.0, 45106.7744661277, 45135.54864451355, 45166.54833451664, 9544.665936692234, 9770.0, 9773.853392199122, 9782.070703283212, 9794.118529332358, 9468.763280917963, 9627.614895404175, 9628.0, 9632.988536203702, 9644.469554174517, 8145.592720363988, 9583.089606487407, 9646.9517652412, 9672.854907176397, 9679.983601721824, 9604.951975240114, 9645.951770241103, 9647.986961369026, 9651.613935442574, 9686.32195746298, 9583.0, 9653.082957119062, 9687.370320929118, 9703.90296097037, 9731.318807683465, 41211.7939410304, 41587.0, 41651.37522937158, 41658.583414165776, 41666.79166604148, 3471.218975730466, 3500.7724497907557, 3555.3244883472316, 3999.900002499932, 4359.041010977611, 31420.842895785376, 31885.521717174262, 31903.042908712774, 32251.35497290041, 33014.50478242828, 3030.5151175811984, 3033.8331391773445, 3069.6009518762417, 3110.3001824589514, 3169.31859650174, 306690.8661826751, 342299.0, 352967.0, 353166.0, 353979.0, 61347.69326153341, 62150.0, 62315.37684623186, 62437.751244975756, 62439.6878015607, 34506.0, 34638.30723385518, 35256.0, 36914.8154259227, 36986.630133698854, 15935.760963585555, 15978.280977356048, 16088.597785055466, 16178.0, 16201.675966480605, 12076.215046022156, 12138.393080345948, 12161.0, 12231.449584768707, 12235.327057011771, 19987.900060499604, 20121.49696257576, 20163.798362016485, 20174.0, 20187.0, 8046.316063134582, 8053.436259461899, 8059.435839491297, 8132.227438393344, 8226.506409615442, 902.9954850225838, 978.9118979291898, 994.0, 1005.562580277567, 1018.3024628129755, 30649.080527584207, 30671.0, 31265.374692506477, 31419.528707069414, 31589.368212635618, 1082.8429877667695, 1117.6144230240554, 1141.1726498288674, 1158.7276989907366, 1168.339887963298, 15811.209439528207, 15901.840981590267, 16022.27899744491, 16274.511764647079, 16371.918140409221, 648431.0, 652650.0, 653540.0, 655235.0, 656029.7198513977, 56975.29074127783, 60988.0, 61714.531418743645, 61767.691161544804, 61879.6906015467, 1272.1603741530546, 1318.5516924245803, 1382.9930850345886, 1416.4560628914533, 1430.7853821926585, 25376.873115634302, 31200.531992019685, 31575.842120790167, 31779.0, 32524.512132317563, 48857.267140992204, 52203.0, 52282.21576676429, 52451.95096098133, 52467.47532524626, 536941.0988285749, 554893.0, 555522.0, 556129.0, 557791.0, 21799.673004905257, 23806.928688208725, 25601.359966001, 26331.341716457242, 26876.865615672577, 48588.27117593168, 49225.769355766395, 49451.50548494469, 49783.0, 50239.0, 40415.39376909407, 40435.7978210107, 40455.393169101895, 40684.77945661635, 41542.58457415387, 35487.46768798522, 39753.40369894396, 39824.0, 40124.398134027426, 40663.39004914988, 37966.43050354302, 38843.22313553769, 39089.80455097706, 39128.80435597918, 39327.016824579616, 33177.83411082929, 34467.13832154216, 35181.82409087938, 35418.0, 35517.11207219737, 25545.361365966, 25853.0, 25974.480510390065, 26367.310818671383, 26387.73612263853], "value": 19836.06270729923, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.4053696087316, 383.53209084916404, 388.63080073929757, 402.3421705511488, 411.91761647670455, 19267.32564360248, 20867.686984695243, 21128.0, 21166.89416552919, 21667.45831354213, 20388.286409975655, 20410.387688369334, 21314.680279795775, 21344.932753362347, 21345.786542134574, 5751.626144300607, 5789.594728369006, 6189.9381006189815, 6190.288116866551, 6199.349068347826, 3899.3566061599945, 4129.339305711087, 5820.767169313243, 6144.324124346318, 6153.261608606974, 6176.505879529649, 6178.907316390235, 6182.876342473171, 6184.165137706397, 6188.443040126388, 6495.382938620824, 6533.379328963741, 6715.059891615178, 6777.322267773233, 6785.321467853225, 3758.267137908109, 5509.3664228613825, 5864.530837532987, 5867.735951882176, 5955.553333499962, 13172.0, 13415.128016678982, 13433.66415839602, 20884.791152088437, 24889.377765556004, 2137.487003119256, 2145.817605503534, 2246.539459410817, 2320.6867072945106, 2913.077368936492, 9476.905230947741, 10032.247581431422, 10217.33587316822, 10229.539670714836, 10427.165826733843, 1656.3291866793968, 1710.3671641492726, 1852.935147269839, 1905.2283825050877, 1940.7477027986272, 124865.0, 126770.36614816867, 128498.0, 128627.0, 128707.35646321802, 11210.607628732956, 11600.767984640428, 11624.558842642971, 11646.475908584134, 11739.765204696028, 14467.638309042359, 14489.492867749577, 14513.782293265609, 14532.0, 14567.344469498898, 8297.502149871027, 8319.292860106838, 8366.623501942428, 8384.371172162113, 8477.915220847835, 8116.75649730509, 8173.591320433955, 8196.729506926436, 8283.420160588821, 8307.543085130255, 4481.983107601593, 4557.840475583339, 4594.0, 4622.260438329851, 4636.281376386665, 1463.7365274250524, 1792.8587054276857, 1834.8623853211063, 1929.7394851694842, 1968.8818670879507, 946.4415994563241, 992.6525715999343, 1002.4085789384299, 1006.7885743993697, 1061.7770268243755, 18707.15817788203, 20346.491337716674, 21128.26051088235, 21260.724356538656, 21286.78713212879, 921.2675922641516, 977.3354119199013, 983.3018556824592, 1019.9184065274909, 1051.7791263834526, 11077.439739284313, 11356.602518911965, 11465.369404682655, 11551.480183391599, 11564.479598418091, 560619.0, 567402.0, 577047.0, 577173.0, 580488.0, 70822.64588677023, 123025.0, 124393.37803310926, 126645.0, 127625.72374276324, 884.7390019944132, 993.8310487217191, 1000.1848493477793, 1006.9043440873252, 1017.6438246613626, 18309.908450457664, 18314.725279120557, 18355.81644183541, 18383.72424413608, 18495.44513664592, 39991.0, 49786.75106624444, 50929.04315010617, 50994.49005509898, 51030.0, 73810.52378952499, 76885.0, 77144.0658985134, 77383.0, 78529.60735196287, 7017.96491017562, 7169.996200531826, 7759.573223472763, 8285.130061343472, 8306.127856575215, 12851.293178874877, 13276.26980516081, 13279.269640169498, 13566.389512471766, 13574.660633483847, 11382.14633902444, 11395.487203075716, 11429.0, 11595.884041159481, 11632.476538555955, 11339.886601133883, 11391.658250252505, 11418.371989540656, 11465.14011449128, 11531.0, 9580.377275477073, 11045.005949464587, 11737.706557336134, 12021.278723276631, 12209.755804884031, 7442.776716698508, 7465.992091067854, 7506.962465187639, 7674.15584285717, 7681.308682218627, 10834.0, 11492.0, 11593.072554195476, 11698.06415486776, 11708.356040417859], "value": 9479.489186732731, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1375.6217040313918, 1517.6357674158198, 1549.3105568022227, 1659.8091219509752, 1757.3058641836476, 7929.484583502075, 8435.283000944919, 8437.198466145714, 8443.0, 8448.746537603882, 7471.962640186805, 8793.95603021984, 8803.471791692504, 8825.779355516115, 8956.776080597989, 2325.6744055832196, 2437.70747510299, 2438.926832195037, 2440.987795061022, 2445.3153117127144, 2381.714194296687, 2414.915477958272, 2429.331933718233, 2435.1111844176835, 2437.317551085699, 2398.4603464220586, 2407.1935901473025, 2419.2984034630026, 2423.309356833303, 2427.1626288930293, 1997.9400617981485, 2386.331827088418, 2417.746136655652, 2419.709634843812, 2423.6243382275775, 2399.7240317363376, 2407.7110746710505, 2437.8659173745436, 2438.0, 2440.73151953285, 8804.559772011375, 8859.689910853154, 8871.911280887172, 8881.555922203865, 8882.378233523656, 5243.292155559027, 5276.261323414725, 5438.809641662562, 5443.510084092451, 5443.965646527147, 3595.0, 3695.8336874840697, 3748.2878253131817, 3757.4927384802977, 3769.3968964965475, 1076.4994277660926, 1097.5554900265406, 1097.9890201097967, 1101.3336931156696, 1117.9552817887275, 11695.298282103016, 11907.047436205083, 11919.940400297943, 11923.463444144947, 11924.105692073043, 4853.417589889236, 4858.416989961227, 4960.206366981266, 4964.131277026579, 4987.202047672355, 10378.948105259577, 10426.009529094876, 10447.164226861833, 10454.529546170288, 10487.161027117812, 5526.281583394133, 5530.917036244459, 5539.861503462445, 5545.916811247835, 5546.584006199551, 4260.403543503912, 4521.434820647419, 4947.233178857283, 4978.850634480971, 4995.575376093, 3501.949415175437, 4065.654419374386, 4087.816048277835, 4171.186618609342, 4178.331466965271, 1274.8916342110838, 1307.9542216022394, 1319.5909268126911, 1319.9802002969964, 1327.2567362276964, 648.5622205011598, 804.8309854930413, 900.7612982559627, 932.2681694869494, 953.9983017831249, 5142.1258386073705, 5597.104463285936, 5861.6189947653265, 5922.466977971917, 5994.280686317583, 464.55402813298934, 468.1736734663322, 468.73516463198183, 489.5814078962507, 644.874693659567, 3760.6615404613717, 3933.449317095609, 3936.7637941723588, 3996.4604778355133, 4064.146529228895, 10568.207384446197, 10807.243492955418, 10930.289531180448, 10955.34267943926, 10972.122230221563, 10862.076723478434, 10898.782024359469, 10899.400532970763, 10910.23628346008, 10910.23628346024, 7870.763877083697, 8336.916630833735, 8365.874511882448, 8395.916040839636, 8447.281981031558, 6098.878022439438, 6100.786472473442, 6160.9383906160365, 6169.96915015422, 6217.067439883873, 10087.949560252151, 10307.94846025765, 10385.948070259601, 10400.323978941344, 10420.0, 10230.130438912478, 10446.52990615409, 10468.0, 10681.465926703479, 10696.144308455461, 7671.961640191764, 7755.418343624364, 7825.765227043198, 7832.295093441618, 7835.0, 8325.440940938373, 8443.408961372645, 8445.366597505335, 8474.830503390021, 8488.363372747192, 5669.829905102853, 6889.379955804002, 7507.399408047452, 7545.396368290414, 7714.961425192838, 6592.670366481752, 7328.377087947584, 7520.435967302365, 7548.4716069874585, 7602.163761986067, 6476.190476190476, 7172.892406613801, 7243.492955493065, 7256.600886951057, 7271.672774725044, 4871.561559459666, 4889.997550502209, 4974.278729584263, 5022.84931452057, 5100.566451851634, 4113.691473139466, 4437.445319335083, 5046.873828154326, 5197.558207552401, 5218.165093585008], "value": 4560.663947702601, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [290.2135213571222, 294.9557566365045, 302.6277678455501, 320.988765393211, 322.7305200157868, 4683.9840511626, 5329.946700532993, 5344.652597581155, 5383.919241211385, 5408.05359062164, 4978.303037574733, 5215.713135777531, 5254.658447200929, 5256.448072952342, 5285.048691235576, 4022.6926248969057, 4039.8182081806244, 4058.5941405859326, 4062.837486500536, 4075.8398049033913, 4064.878053658395, 4075.3071977763702, 4084.7344922579937, 4089.9386509202386, 4112.293398239727, 3313.718333941618, 3826.9808650956847, 4013.1973605278913, 4018.7990600470007, 4023.859164929228, 3560.412531932241, 3754.924901501982, 3785.921012511421, 3960.5643379228263, 3972.483577134983, 2893.5804308375377, 2966.7984466290973, 3140.3248301615035, 3522.929541409183, 3523.1896663767475, 4442.378067070613, 4856.538628830255, 4989.376327959005, 5124.846254612367, 5155.937624950013, 4079.8780335408, 4375.387445757597, 4656.996237426841, 4669.365721997307, 4672.2057250267535, 4878.0, 4881.633877459205, 5508.366537848158, 5560.666360018412, 5599.496045355938, 3576.535050443452, 3885.0870045539277, 3910.1006768443417, 3916.2167566486814, 3917.275304068728, 4806.423229212517, 5512.558995280408, 5669.659820410789, 5765.250517432749, 5842.604532141844, 3346.966530334714, 3812.694984401241, 3819.2743378757946, 3834.118152824866, 3843.461915331856, 3373.9325213495595, 3537.3279076975805, 4005.439238506611, 4015.594541910308, 4022.8390864365683, 4541.863744087683, 4582.404287442678, 4599.895070827179, 4622.838200663028, 4640.350350950869, 5083.593312534988, 5398.460153984649, 5500.844822587302, 5611.80358687444, 5620.655709852631, 1090.2804149261449, 1178.3106882473678, 1280.8014757712383, 1375.8417781955204, 1377.6900197455693, 768.7770546541441, 825.5418242875262, 853.6981103816729, 860.0539406652757, 866.4324867211986, 3965.325894597925, 4122.464079669624, 4342.826286948487, 4346.369776382407, 4356.629686476622, 4375.5405682403525, 4398.8020539075815, 4460.442444694413, 4830.347903033045, 4916.5575098240615, 4009.67922566194, 4100.5489396166395, 4191.685623578243, 4324.675649326313, 4363.930836944905, 2228.6099932511747, 2703.0, 2705.7294270572784, 3018.0684362008333, 3023.712747289048, 5973.192599478239, 6003.909941350883, 6012.152222577466, 6024.006039003603, 6028.377655719776, 5226.63413561047, 5557.916631250535, 5913.970430147821, 5936.317323507766, 5965.433283838115, 5068.898622027613, 5396.676199427969, 5964.1372141372385, 5969.79105731306, 5978.383078237777, 5106.948930510647, 5369.704666243394, 5680.500579744899, 5730.627509211888, 5751.827445176651, 4608.746518941356, 4741.478437371955, 5290.81482148123, 5601.383847776823, 5616.859578510406, 5204.531592156724, 5579.69311687861, 5814.0, 5898.557608179318, 5958.821235362946, 3245.8801713408834, 3432.8626854926524, 3498.2478767065204, 3799.525059367579, 3838.5797255015823, 5742.540596752333, 5757.65454072757, 5768.826935191783, 5783.913241301468, 5808.244928159313, 4909.4354149273295, 5312.415634280149, 5547.861303467445, 5576.86057848557, 5589.944100558942, 5526.889462210814, 5534.335879694462, 5582.246396736388, 5594.356648985418, 5596.832095037155, 4746.169420351355, 5004.424491183415, 5270.472952704852, 5362.640438207176, 5480.479354461319, 4840.273958906192, 5284.128118860345, 5566.582506312124, 5581.916271255853, 5586.357568879631, 4646.395968524071, 5485.0, 5485.835424937259, 5539.861503462445, 5543.251661025871], "value": 3978.057534802588, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [761.0600907878772, 771.9421043421743, 779.7660701789464, 835.817348161499, 858.8883445152129, 14878.47925322614, 15076.849231507682, 15274.923625381885, 15319.923400382997, 15332.540023799276, 14070.0, 14077.155370677767, 14079.718405631882, 14086.859131408683, 14135.151890886555, 709.5529816215802, 735.6432130416744, 871.8866547348852, 875.7329014650529, 893.714011516315, 910.3127139010038, 921.8018126102886, 921.8478950973081, 922.0963455813308, 922.5156792683861, 940.666063547441, 958.9328746987713, 959.2278216036088, 959.3524371049514, 959.8752162218901, 740.9259074092602, 919.5172534419416, 949.9287553433451, 954.4988880837547, 954.8138113067954, 712.4051417066755, 712.6864179760888, 713.4114355656604, 713.5255055388161, 714.2393351081082, 14575.927120364437, 14612.853871461255, 14624.926875365662, 14628.634284142876, 14659.48691795782, 11225.943870280678, 13233.669158271025, 13455.394507247198, 13476.39356228972, 13612.727745445134, 10077.798444031077, 11754.177207595472, 12047.939760301142, 12070.577529786504, 12346.0, 3511.6839484446523, 4436.245838207513, 4483.50681425043, 4554.658400619966, 4637.026224492827, 62392.68803655953, 79883.60058199729, 82103.7684434734, 82407.58796206041, 83012.3397532052, 10766.73083172927, 11000.779984400428, 11057.281276716987, 11172.10623150146, 11243.83134252987, 21598.136074557155, 21641.56716865654, 21641.675374869388, 21667.024983875763, 21715.674264886038, 6585.242697089896, 7816.921830781733, 7965.721199757981, 7987.8402431951035, 7991.0810256821205, 4392.802323895432, 4408.316710909814, 4411.18393097278, 4443.844465443694, 4601.585857272795, 3374.814385208837, 3495.8252087395526, 3505.211327451354, 3508.526348942911, 3509.2630547585295, 656.6060363781693, 663.7013343995221, 665.1818263535878, 684.9143857017873, 685.8216863615396, 562.7692646015196, 639.650416711976, 640.4684112186874, 665.8069159943659, 670.0929037359142, 8671.0, 8815.779605509913, 8894.59974301158, 8966.820663586692, 9037.728868133967, 615.3600255734076, 622.5082185073741, 644.7324360390479, 663.8482233325177, 670.3665036540519, 4234.174336004449, 4236.237477254123, 4265.445492086071, 4274.358846173099, 4411.338299255073, 85993.14006859977, 86982.0, 88271.67592486118, 88377.55811221032, 90312.5484372574, 73969.89045164327, 75881.0, 76580.0, 76730.0, 77826.2217377819, 65359.01961470486, 66034.0, 67997.66001169963, 68046.65976670085, 69323.26691832644, 8041.356691464785, 8118.918810811816, 8119.878201827096, 8313.958430208053, 8425.157484251771, 33886.64453421836, 43537.78231108824, 43774.99999999873, 44385.0, 45008.77495612501, 57727.711361442925, 58689.70655146697, 59016.40983590109, 60124.69937650284, 60289.69855150696, 11244.325340479598, 11306.717332066435, 11328.03711684516, 11548.769024619629, 11709.297442153158, 11970.401479926142, 11986.0, 12007.159498834997, 12097.879021209676, 12329.815052774036, 11207.66377008691, 11319.773604528027, 11422.600208992646, 11529.481173347052, 11779.587714429954, 10643.829178790482, 11377.374244416636, 11523.481443334902, 11923.523059077539, 11943.16397852142, 11056.778864422828, 11337.093032557541, 11487.25332853362, 11686.357250351311, 11789.115816313639, 9398.483083430478, 9586.66446674363, 9662.889998305991, 9933.907270200416, 9978.351407158512, 9439.19766819828, 9642.42145471274, 9694.903050969682, 9708.5145742714, 9732.976444336775], "value": 7036.432468730078, "unit": "score"}], "name": "dromaeo_css", "value": 7172.574682277994, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 105.0, 113.0, 91.0, 155.0, 167.0, 156.0, 111.0, 113.0, 113.0], "value": 123.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 120.0, 119.0, 176.0, 108.0, 143.0, 165.0, 142.0, 179.0, 120.0], "value": 142.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 185.0, 185.0, 181.0, 183.0, 185.0, 182.0, 182.0, 182.0, 182.0], "value": 182.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 75.0, 57.0, 109.0, 57.0, 56.0, 58.0, 59.0, 128.0, 55.0], "value": 73.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 94.0, 70.0, 74.0, 67.0, 95.0, 93.0, 99.0, 134.0], "value": 89.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 96.0, 131.0, 112.0, 85.0, 89.0, 86.0, 92.0, 131.0], "value": 99.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 95.0, 97.0, 93.0, 94.0, 94.0, 93.0, 93.0, 94.0], "value": 94.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 109.0, 133.0, 134.0, 85.0, 84.0, 84.0, 92.0], "value": 97.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 55.0, 57.0, 55.0, 56.0, 54.0, 54.0, 53.0, 54.0], "value": 55.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 75.0, 70.0, 79.0, 70.0, 71.0, 68.0, 68.0, 69.0, 70.0], "value": 70.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 87.0, 86.0, 86.0, 89.0, 95.0, 105.0, 151.0, 87.0], "value": 95.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 159.0, 157.0, 145.0, 142.0, 137.0, 137.0, 123.0, 153.0, 129.0], "value": 142.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 166.0, 167.0, 167.0, 165.0, 163.0, 166.0, 172.0, 225.0, 168.0], "value": 172.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 66.0, 64.0, 64.0, 66.0, 66.0, 65.0, 66.0], "value": 66.0, "unit": "score"}], "name": "kraken", "value": 1505.1, "alertThreshold": 2.0}]} 09:28:28 INFO - Return code: 0 09:28:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:28:28 ERROR - # TBPL SUCCESS # 09:28:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:28:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:28:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:28:28 INFO - 2016-06-01 09:28:28.644 screenresolution[1675:9875] starting screenresolution argv=screenresolution get 09:28:28 INFO - 2016-06-01 09:28:28.647 screenresolution[1675:9875] Display 0: 1600x1200x32@60 09:28:28 INFO - 2016-06-01 09:28:28.653 screenresolution[1676:9878] starting screenresolution argv=screenresolution list 09:28:28 INFO - Available Modes on Display 0 09:28:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:28:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:28:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:28:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:28:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:28:28 INFO - Intel Iris: 09:28:28 INFO - Chipset Model: Intel Iris 09:28:28 INFO - Type: GPU 09:28:28 INFO - Bus: Built-In 09:28:28 INFO - VRAM (Dynamic, Max): 1536 MB 09:28:28 INFO - Vendor: Intel (0x8086) 09:28:28 INFO - Device ID: 0x0a2e 09:28:28 INFO - Revision ID: 0x0009 09:28:28 INFO - Displays: 09:28:28 INFO - Display: 09:28:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:28:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:28:28 INFO - Display Serial Number: 1600x1200 60 09:28:28 INFO - Main Display: Yes 09:28:28 INFO - Mirror: Off 09:28:28 INFO - Online: Yes 09:28:28 INFO - Rotation: Supported 09:28:28 INFO - Return code: 0 09:28:28 INFO - Running post-action listener: _resource_record_post_action 09:28:28 INFO - ##### 09:28:28 INFO - ##### Finished run-tests step (success) 09:28:28 INFO - ##### 09:28:28 INFO - Running post-run listener: _resource_record_post_run 09:28:28 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 36754944; Write bytes: 695625216; Read time: 240; Write time: 1870 09:28:28 INFO - TinderboxPrint: CPU usage
53.9% 09:28:28 INFO - TinderboxPrint: I/O read bytes / time
36,754,944 / 240 09:28:28 INFO - TinderboxPrint: I/O write bytes / time
695,625,216 / 1,870 09:28:28 INFO - TinderboxPrint: CPU idle
2,235.6 (46.1%) 09:28:28 INFO - TinderboxPrint: CPU system
967.7 (20.0%) 09:28:28 INFO - TinderboxPrint: CPU user
1,642.3 (33.9%) 09:28:28 INFO - TinderboxPrint: Swap in / out
436,252,672 / 0 09:28:28 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 400491008; Write bytes: 401623040; Read time: 29866; Write time: 795 09:28:28 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 34940416; Write bytes: 292986368; Read time: 223; Write time: 1071 09:28:29 INFO - Running post-run listener: _upload_blobber_files 09:28:29 INFO - Blob upload gear active. 09:28:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:28:29 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 09:28:29 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'] 09:28:29 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 09:28:29 INFO - (blobuploader) - INFO - Open directory for files ... 09:28:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:28:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:28:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:28:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:28:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:28:30 INFO - (blobuploader) - INFO - Done attempting. 09:28:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:28:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:28:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:28:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:28:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:28:31 INFO - (blobuploader) - INFO - Done attempting. 09:28:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:28:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:28:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:28:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:28:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:28:34 INFO - (blobuploader) - INFO - Done attempting. 09:28:34 INFO - (blobuploader) - INFO - Iteration through files over. 09:28:34 INFO - Return code: 0 09:28:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:28:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:28:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/451c262f44dacb259d885845b1625d9ce3761d61bf2c984e75dfddbcf57ec71f74323949c0dd4277030cb0ff4fb283fcbb96fd213c3f392365f0fc04bfc100d6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aa8c217456513f3eee4564e55a2e75c811564929f75a333b2e737cf4648538921a9b9b1a7379aeb25ec6caea1560b457e98ecb9bfb135bb2c185105f7f0b8fc0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3f5a016825f94473fcb9445b002e107254c526c9446c0319f62e7fb8a2fb5d7f2128e3bea68ba3de5fbc822bb69f1feb9458dd4754bb55b1951d8a709cd22de0"} 09:28:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:28:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:28:34 INFO - Contents: 09:28:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/451c262f44dacb259d885845b1625d9ce3761d61bf2c984e75dfddbcf57ec71f74323949c0dd4277030cb0ff4fb283fcbb96fd213c3f392365f0fc04bfc100d6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aa8c217456513f3eee4564e55a2e75c811564929f75a333b2e737cf4648538921a9b9b1a7379aeb25ec6caea1560b457e98ecb9bfb135bb2c185105f7f0b8fc0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3f5a016825f94473fcb9445b002e107254c526c9446c0319f62e7fb8a2fb5d7f2128e3bea68ba3de5fbc822bb69f1feb9458dd4754bb55b1951d8a709cd22de0"} 09:28:34 INFO - Running post-run listener: copy_logs_to_upload_dir 09:28:34 INFO - Copying logs to upload dir... 09:28:34 INFO - mkdir: /builds/slave/test/build/upload/logs 09:28:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.064625 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-06-01 09:28:34.345997) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 09:28:34.349780) ========= 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.VTrqcJMI3x/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.BqaLnFvaJP/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/451c262f44dacb259d885845b1625d9ce3761d61bf2c984e75dfddbcf57ec71f74323949c0dd4277030cb0ff4fb283fcbb96fd213c3f392365f0fc04bfc100d6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aa8c217456513f3eee4564e55a2e75c811564929f75a333b2e737cf4648538921a9b9b1a7379aeb25ec6caea1560b457e98ecb9bfb135bb2c185105f7f0b8fc0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3f5a016825f94473fcb9445b002e107254c526c9446c0319f62e7fb8a2fb5d7f2128e3bea68ba3de5fbc822bb69f1feb9458dd4754bb55b1951d8a709cd22de0"} build_url:https://queue.taskcluster.net/v1/task/WUC49xLAS7GbVIbqhFhGrw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010923 build_url: 'https://queue.taskcluster.net/v1/task/WUC49xLAS7GbVIbqhFhGrw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/451c262f44dacb259d885845b1625d9ce3761d61bf2c984e75dfddbcf57ec71f74323949c0dd4277030cb0ff4fb283fcbb96fd213c3f392365f0fc04bfc100d6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aa8c217456513f3eee4564e55a2e75c811564929f75a333b2e737cf4648538921a9b9b1a7379aeb25ec6caea1560b457e98ecb9bfb135bb2c185105f7f0b8fc0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3f5a016825f94473fcb9445b002e107254c526c9446c0319f62e7fb8a2fb5d7f2128e3bea68ba3de5fbc822bb69f1feb9458dd4754bb55b1951d8a709cd22de0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 09:28:34.384978) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 09:28:34.385756) ========= 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.VTrqcJMI3x/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.BqaLnFvaJP/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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.005004 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 09:28:34.438541) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 09:28:34.439114) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 09:28:34.439467) ========= ========= Total master_lag: 0.18 =========