builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0033 starttime: 1463263340.21 results: success (0) buildid: 20160514130834 builduid: 689ff90b8cf74d23b3cfa22bda78770d revision: 403912ca555eb65f814b18ecf38ad8e8e98569f5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-14 15:02:20.205784) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-14 15:02:20.206275) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-14 15:02:20.206584) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-14 15:02:20.256396) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 15:02:20.256705) ========= 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.ksrh0O08c0/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.7komNO0YGo/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-14 15:02:20-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 52.2M=0s 2016-05-14 15:02:20 (52.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.479140 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 15:02:20.753804) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 15:02:20.754147) ========= 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.ksrh0O08c0/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.7komNO0YGo/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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.026576 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 15:02:20.813091) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-14 15:02:20.813468) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 403912ca555eb65f814b18ecf38ad8e8e98569f5 --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 403912ca555eb65f814b18ecf38ad8e8e98569f5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ksrh0O08c0/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.7komNO0YGo/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-14 15:02:20,902 truncating revision to first 12 chars 2016-05-14 15:02:20,902 Setting DEBUG logging. 2016-05-14 15:02:20,902 attempt 1/10 2016-05-14 15:02:20,902 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/403912ca555e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-14 15:02:21,641 unpacking tar archive at: fx-team-403912ca555e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.984864 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-14 15:02:21.831660) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-14 15:02:21.831972) ========= script_repo_revision: 403912ca555eb65f814b18ecf38ad8e8e98569f5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-14 15:02:21.832464) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-14 15:02:21.832808) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-14 15:02:21.846973) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 28 secs) (at 2016-05-14 15:02:21.847329) ========= /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.ksrh0O08c0/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.7komNO0YGo/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 15:02:22 INFO - MultiFileLogger online at 20160514 15:02:22 in /builds/slave/test 15:02:22 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 15:02:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:02:22 INFO - {'append_to_log': False, 15:02:22 INFO - 'base_work_dir': '/builds/slave/test', 15:02:22 INFO - 'blob_upload_branch': 'Fx-Team', 15:02:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:02:22 INFO - 'branch': 'Fx-Team', 15:02:22 INFO - 'buildbot_json_path': 'buildprops.json', 15:02:22 INFO - 'config_files': ('talos/mac_config.py',), 15:02:22 INFO - 'default_actions': ('clobber', 15:02:22 INFO - 'read-buildbot-config', 15:02:22 INFO - 'download-and-extract', 15:02:22 INFO - 'populate-webroot', 15:02:22 INFO - 'create-virtualenv', 15:02:22 INFO - 'install', 15:02:22 INFO - 'run-tests'), 15:02:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:02:22 INFO - 'download_minidump_stackwalk': True, 15:02:22 INFO - 'download_symbols': 'ondemand', 15:02:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:02:22 INFO - 'tooltool.py': '/tools/tooltool.py', 15:02:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:02:22 INFO - '/tools/misc-python/virtualenv.py')}, 15:02:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:02:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:02:22 INFO - 'installer_path': 'installer.exe', 15:02:22 INFO - 'log_level': 'info', 15:02:22 INFO - 'log_name': 'talos', 15:02:22 INFO - 'log_to_console': True, 15:02:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:02:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:02:22 INFO - 'opt_config_files': (), 15:02:22 INFO - 'pip_index': False, 15:02:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:02:22 INFO - 'cmd': ('bash', 15:02:22 INFO - '-c', 15:02:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:02:22 INFO - 'enabled': True, 15:02:22 INFO - 'halt_on_failure': False, 15:02:22 INFO - 'name': 'check_screen_resolution'},), 15:02:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:02:22 INFO - 'cmd': ('bash', 15:02:22 INFO - '-c', 15:02:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:02:22 INFO - 'enabled': True, 15:02:22 INFO - 'halt_on_failure': False, 15:02:22 INFO - 'name': 'check_screen_resolution'},), 15:02:22 INFO - 'run_cmd_checks_enabled': True, 15:02:22 INFO - 'sps_profile': False, 15:02:22 INFO - 'sps_profile_interval': 0, 15:02:22 INFO - 'suite': 'dromaeojs', 15:02:22 INFO - 'system_bits': '32', 15:02:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:02:22 INFO - 'title': 't-yosemite-r7-0033', 15:02:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:02:22 INFO - 'use_talos_json': True, 15:02:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:02:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:02:22 INFO - 'work_dir': 'build'} 15:02:22 INFO - ##### 15:02:22 INFO - ##### Running clobber step. 15:02:22 INFO - ##### 15:02:22 INFO - Running pre-action listener: _resource_record_pre_action 15:02:22 INFO - Running main action method: clobber 15:02:22 INFO - rmtree: /builds/slave/test/build 15:02:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:02:23 INFO - Running post-action listener: _resource_record_post_action 15:02:23 INFO - ##### 15:02:23 INFO - ##### Finished clobber step (success) 15:02:23 INFO - ##### 15:02:23 INFO - ##### 15:02:23 INFO - ##### Running read-buildbot-config step. 15:02:23 INFO - ##### 15:02:23 INFO - Running pre-action listener: _resource_record_pre_action 15:02:23 INFO - Running main action method: read_buildbot_config 15:02:23 INFO - Using buildbot properties: 15:02:23 INFO - { 15:02:23 INFO - "project": "", 15:02:23 INFO - "product": "firefox", 15:02:23 INFO - "script_repo_revision": "production", 15:02:23 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 15:02:23 INFO - "repository": "", 15:02:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 15:02:23 INFO - "buildid": "20160514130834", 15:02:23 INFO - "pgo_build": "False", 15:02:23 INFO - "basedir": "/builds/slave/test", 15:02:23 INFO - "buildnumber": 18, 15:02:23 INFO - "slavename": "t-yosemite-r7-0033", 15:02:23 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 15:02:23 INFO - "platform": "yosemite_r7", 15:02:23 INFO - "scheduler": "tests-fx-team-macosx64-talos", 15:02:23 INFO - "branch": "fx-team", 15:02:23 INFO - "repo_path": "integration/fx-team", 15:02:23 INFO - "revision": "403912ca555eb65f814b18ecf38ad8e8e98569f5", 15:02:23 INFO - "stage_platform": "macosx64", 15:02:23 INFO - "builduid": "689ff90b8cf74d23b3cfa22bda78770d", 15:02:23 INFO - "slavebuilddir": "test" 15:02:23 INFO - } 15:02:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/Za5LwrsWRoCGj3_UbcTPBQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 15:02:23 INFO - Running post-action listener: _resource_record_post_action 15:02:23 INFO - ##### 15:02:23 INFO - ##### Finished read-buildbot-config step (success) 15:02:23 INFO - ##### 15:02:23 INFO - ##### 15:02:23 INFO - ##### Running download-and-extract step. 15:02:23 INFO - ##### 15:02:23 INFO - Running pre-action listener: _resource_record_pre_action 15:02:23 INFO - Running main action method: download_and_extract 15:02:23 INFO - mkdir: /builds/slave/test/build/tests 15:02:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:02:23 INFO - https://queue.taskcluster.net/v1/task/Za5LwrsWRoCGj3_UbcTPBQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 15:02:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za5LwrsWRoCGj3_UbcTPBQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 15:02:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za5LwrsWRoCGj3_UbcTPBQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 15:02:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za5LwrsWRoCGj3_UbcTPBQ/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 15:02:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za5LwrsWRoCGj3_UbcTPBQ/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 15:02:26 INFO - Downloaded 1199 bytes. 15:02:26 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 15:02:26 INFO - Using the following test package requirements: 15:02:26 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 15:02:26 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:02:26 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 15:02:26 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:02:26 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 15:02:26 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:02:26 INFO - u'jsshell-mac.zip'], 15:02:26 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:02:26 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 15:02:26 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 15:02:26 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:02:26 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 15:02:26 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:02:26 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 15:02:26 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:02:26 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 15:02:26 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:02:26 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 15:02:26 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:02: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')]} 15:02:26 INFO - https://queue.taskcluster.net/v1/task/Za5LwrsWRoCGj3_UbcTPBQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:02:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za5LwrsWRoCGj3_UbcTPBQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:02:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za5LwrsWRoCGj3_UbcTPBQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:02:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za5LwrsWRoCGj3_UbcTPBQ/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 15:02:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za5LwrsWRoCGj3_UbcTPBQ/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 15:02:39 INFO - Downloaded 18267722 bytes. 15:02:39 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'] 15:02:39 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 15:02:42 INFO - Return code: 0 15:02:42 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 15:02:42 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')]} 15:02:42 INFO - https://queue.taskcluster.net/v1/task/Za5LwrsWRoCGj3_UbcTPBQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:02:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za5LwrsWRoCGj3_UbcTPBQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:02:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za5LwrsWRoCGj3_UbcTPBQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:02:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za5LwrsWRoCGj3_UbcTPBQ/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 15:02:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za5LwrsWRoCGj3_UbcTPBQ/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 15:02:44 INFO - Downloaded 18267722 bytes. 15:02:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:02:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:02:48 INFO - Return code: 0 15:02:48 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')]} 15:02:48 INFO - https://queue.taskcluster.net/v1/task/Za5LwrsWRoCGj3_UbcTPBQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 15:02:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za5LwrsWRoCGj3_UbcTPBQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 15:02:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za5LwrsWRoCGj3_UbcTPBQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 15:02:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za5LwrsWRoCGj3_UbcTPBQ/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 15:02:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za5LwrsWRoCGj3_UbcTPBQ/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 15:02:49 INFO - Downloaded 10840194 bytes. 15:02:49 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'] 15:02:49 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 15:02:49 INFO - Return code: 0 15:02:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:02:49 INFO - https://queue.taskcluster.net/v1/task/Za5LwrsWRoCGj3_UbcTPBQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 15:02:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za5LwrsWRoCGj3_UbcTPBQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:02:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za5LwrsWRoCGj3_UbcTPBQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:02:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za5LwrsWRoCGj3_UbcTPBQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 15:02:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Za5LwrsWRoCGj3_UbcTPBQ/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 15:02:53 INFO - Downloaded 109978008 bytes. 15:02:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Za5LwrsWRoCGj3_UbcTPBQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:02:53 INFO - mkdir: /builds/slave/test/properties 15:02:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:02:53 INFO - Writing to file /builds/slave/test/properties/build_url 15:02:53 INFO - Contents: 15:02:53 INFO - build_url:https://queue.taskcluster.net/v1/task/Za5LwrsWRoCGj3_UbcTPBQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:02:55 INFO - Running post-action listener: _resource_record_post_action 15:02:55 INFO - Running post-action listener: set_extra_try_arguments 15:02:55 INFO - ##### 15:02:55 INFO - ##### Finished download-and-extract step (success) 15:02:55 INFO - ##### 15:02:55 INFO - ##### 15:02:55 INFO - ##### Running populate-webroot step. 15:02:55 INFO - ##### 15:02:55 INFO - Running pre-action listener: _resource_record_pre_action 15:02:55 INFO - Running main action method: populate_webroot 15:02:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:02:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:02:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:02:55 INFO - '--tppagecycles', 15:02:55 INFO - '7'], 15:02:55 INFO - 'tests': ['tsvgm']}}, 15:02:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:02:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:02:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:02:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:02:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:02:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:02:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:02:55 INFO - 'talos_options': ['--disable-e10s'], 15:02:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:02:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:02:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:02:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:02:55 INFO - 'talos_options': ['--disable-e10s'], 15:02:55 INFO - 'tests': ['damp', 'tps']}, 15:02:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:02:55 INFO - 'tests': ['damp', 'tps']}, 15:02:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:02:55 INFO - 'tests': ['dromaeo_dom']}, 15:02:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:02:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:02:55 INFO - 'tests': ['a11yr', 15:02:55 INFO - 'ts_paint', 15:02:55 INFO - 'tpaint', 15:02:55 INFO - 'sessionrestore', 15:02:55 INFO - 'sessionrestore_no_auto_restore', 15:02:55 INFO - 'tabpaint']}, 15:02:55 INFO - 'other-e10s': {'tests': ['a11yr', 15:02:55 INFO - 'ts_paint', 15:02:55 INFO - 'tpaint', 15:02:55 INFO - 'sessionrestore', 15:02:55 INFO - 'sessionrestore_no_auto_restore', 15:02:55 INFO - 'tabpaint']}, 15:02:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:02:55 INFO - 'ts_paint', 15:02:55 INFO - 'tpaint', 15:02:55 INFO - 'sessionrestore', 15:02:55 INFO - 'sessionrestore_no_auto_restore', 15:02:55 INFO - 'tabpaint']}, 15:02:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:02:55 INFO - 'ts_paint', 15:02:55 INFO - 'tpaint', 15:02:55 INFO - 'sessionrestore', 15:02:55 INFO - 'sessionrestore_no_auto_restore', 15:02:55 INFO - 'tabpaint']}, 15:02:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:02:55 INFO - 'tests': ['a11yr', 15:02:55 INFO - 'ts_paint', 15:02:55 INFO - 'tpaint', 15:02:55 INFO - 'sessionrestore', 15:02:55 INFO - 'sessionrestore_no_auto_restore', 15:02:55 INFO - 'tabpaint']}, 15:02:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:02:55 INFO - 'tests': ['a11yr', 15:02:55 INFO - 'ts_paint', 15:02:55 INFO - 'tpaint', 15:02:55 INFO - 'sessionrestore', 15:02:55 INFO - 'sessionrestore_no_auto_restore', 15:02:55 INFO - 'tabpaint']}, 15:02:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:02:55 INFO - 'tests': ['tsvgx', 15:02:55 INFO - 'tsvgr_opacity', 15:02:55 INFO - 'tart', 15:02:55 INFO - 'tscrollx', 15:02:55 INFO - 'cart']}, 15:02:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:02:55 INFO - 'tsvgr_opacity', 15:02:55 INFO - 'tart', 15:02:55 INFO - 'tscrollx', 15:02:55 INFO - 'cart']}, 15:02:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:02:55 INFO - 'talos_options': ['--disable-e10s'], 15:02:55 INFO - 'tests': ['tp5o']}, 15:02:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:02:55 INFO - 'tests': ['tp5o']}, 15:02:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:02:55 INFO - 'talos_options': ['--disable-e10s', 15:02:55 INFO - '--xperf_path', 15:02:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:02:55 INFO - 'tests': ['tp5n']}, 15:02:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:02:55 INFO - 'talos_options': ['--xperf_path', 15:02:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:02:55 INFO - 'tests': ['tp5n']}}, 15:02:55 INFO - 'talos.zip': {'path': '', 15:02:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:02:55 INFO - Running post-action listener: _resource_record_post_action 15:02:55 INFO - ##### 15:02:55 INFO - ##### Finished populate-webroot step (success) 15:02:55 INFO - ##### 15:02:55 INFO - ##### 15:02:55 INFO - ##### Running create-virtualenv step. 15:02:55 INFO - ##### 15:02:55 INFO - Running pre-action listener: _resource_record_pre_action 15:02:55 INFO - Running main action method: create_virtualenv 15:02:55 INFO - Creating virtualenv /builds/slave/test/build/venv 15:02:55 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 15:02:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:02:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:02:55 INFO - Using real prefix '/tools/python27' 15:02:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:02:56 INFO - Installing distribute.............................................................................................................................................................................................done. 15:02:58 INFO - Installing pip.................done. 15:02:58 INFO - Return code: 0 15:02:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:02:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:02:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:02:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108d34a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d3a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff08ac80910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bd8d30>, '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 0x1089fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bcfb20>, '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.ksrh0O08c0/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.7komNO0YGo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 15:02:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:02:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:02:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ksrh0O08c0/Render', 15:02:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:02:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:02:58 INFO - 'HOME': '/Users/cltbld', 15:02:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:02:58 INFO - 'LOGNAME': 'cltbld', 15:02:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:58 INFO - 'MOZ_NO_REMOTE': '1', 15:02:58 INFO - 'NO_EM_RESTART': '1', 15:02:58 INFO - 'PAGER': '/bin/cat', 15:02:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:02:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:58 INFO - 'PWD': '/builds/slave/test', 15:02:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:02:58 INFO - 'SHELL': '/bin/bash', 15:02:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7komNO0YGo/Listeners', 15:02:58 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 15:02:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:02:58 INFO - 'USER': 'cltbld', 15:02:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:02:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:02:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:58 INFO - 'XPC_FLAGS': '0x0', 15:02:58 INFO - 'XPC_SERVICE_NAME': '0', 15:02:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:02:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:59 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 15:02:59 INFO - Cleaning up... 15:02:59 INFO - Return code: 0 15:02:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:02: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')]} 15:02:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02: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')]} 15:02:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:59 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 0x108d34a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d3a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff08ac80910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bd8d30>, '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 0x1089fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bcfb20>, '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.ksrh0O08c0/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.7komNO0YGo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 15:02:59 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 15:02:59 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 15:02:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ksrh0O08c0/Render', 15:02:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:02:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:02:59 INFO - 'HOME': '/Users/cltbld', 15:02:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:02:59 INFO - 'LOGNAME': 'cltbld', 15:02:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:59 INFO - 'MOZ_NO_REMOTE': '1', 15:02:59 INFO - 'NO_EM_RESTART': '1', 15:02:59 INFO - 'PAGER': '/bin/cat', 15:02:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:02:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:59 INFO - 'PWD': '/builds/slave/test', 15:02:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:02:59 INFO - 'SHELL': '/bin/bash', 15:02:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7komNO0YGo/Listeners', 15:02:59 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 15:02:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:02:59 INFO - 'USER': 'cltbld', 15:02:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:02:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:02:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:59 INFO - 'XPC_FLAGS': '0x0', 15:02:59 INFO - 'XPC_SERVICE_NAME': '0', 15:02:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:02:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:59 INFO - Downloading/unpacking psutil>=3.1.1 15:02:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:02:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:02:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:02:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:02:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:02:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:03:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:03:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:03:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:03:02 INFO - Installing collected packages: psutil 15:03:02 INFO - Running setup.py install for psutil 15:03:02 INFO - building 'psutil._psutil_osx' extension 15:03:02 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 15:03:03 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 15:03:03 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 15:03:03 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 15:03:03 INFO - building 'psutil._psutil_posix' extension 15:03:03 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 15:03:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:03:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:03:03 INFO - ^ 15:03:03 INFO - 1 warning generated. 15:03:03 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 15:03:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:03:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:03:03 INFO - Successfully installed psutil 15:03:03 INFO - Cleaning up... 15:03:03 INFO - Return code: 0 15:03:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:03:03 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')]} 15:03:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:03:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:03 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')]} 15:03:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:03:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:03 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 0x108d34a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d3a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff08ac80910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bd8d30>, '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 0x1089fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bcfb20>, '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.ksrh0O08c0/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.7komNO0YGo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 15:03:03 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 15:03:03 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 15:03:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ksrh0O08c0/Render', 15:03:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:03:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:03:03 INFO - 'HOME': '/Users/cltbld', 15:03:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:03:03 INFO - 'LOGNAME': 'cltbld', 15:03:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:03:03 INFO - 'MOZ_NO_REMOTE': '1', 15:03:03 INFO - 'NO_EM_RESTART': '1', 15:03:03 INFO - 'PAGER': '/bin/cat', 15:03:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:03:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:03:03 INFO - 'PWD': '/builds/slave/test', 15:03:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:03:03 INFO - 'SHELL': '/bin/bash', 15:03:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7komNO0YGo/Listeners', 15:03:03 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 15:03:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:03:03 INFO - 'USER': 'cltbld', 15:03:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:03:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:03:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:03:03 INFO - 'XPC_FLAGS': '0x0', 15:03:03 INFO - 'XPC_SERVICE_NAME': '0', 15:03:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:03:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:03:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:03:03 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 15:03:03 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 15:03:03 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 15:03:03 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 15:03:03 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 15:03:03 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 15:03:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:03:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:03:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:03:06 INFO - Installing collected packages: mozsystemmonitor 15:03:06 INFO - Running setup.py install for mozsystemmonitor 15:03:06 INFO - Successfully installed mozsystemmonitor 15:03:06 INFO - Cleaning up... 15:03:06 INFO - Return code: 0 15:03:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:03:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:03:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:03:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:03:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:03:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108d34a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d3a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff08ac80910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bd8d30>, '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 0x1089fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bcfb20>, '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.ksrh0O08c0/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.7komNO0YGo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 15:03:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:03:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:03:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ksrh0O08c0/Render', 15:03:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:03:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:03:06 INFO - 'HOME': '/Users/cltbld', 15:03:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:03:06 INFO - 'LOGNAME': 'cltbld', 15:03:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:03:06 INFO - 'MOZ_NO_REMOTE': '1', 15:03:06 INFO - 'NO_EM_RESTART': '1', 15:03:06 INFO - 'PAGER': '/bin/cat', 15:03:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:03:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:03:06 INFO - 'PWD': '/builds/slave/test', 15:03:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:03:06 INFO - 'SHELL': '/bin/bash', 15:03:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7komNO0YGo/Listeners', 15:03:06 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 15:03:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:03:06 INFO - 'USER': 'cltbld', 15:03:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:03:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:03:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:03:06 INFO - 'XPC_FLAGS': '0x0', 15:03:06 INFO - 'XPC_SERVICE_NAME': '0', 15:03:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:03:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:03:06 INFO - Downloading/unpacking blobuploader==1.2.4 15:03: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 15:03: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 15:03: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 15:03: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 15:03: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 15:03: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 15:03:09 INFO - Downloading blobuploader-1.2.4.tar.gz 15:03:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:03:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:03: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 15:03: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 15:03: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 15:03: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 15:03: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 15:03: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 15:03:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:03:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:03:10 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 15:03:10 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 15:03:10 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 15:03:10 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 15:03:10 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 15:03:10 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 15:03:10 INFO - Downloading docopt-0.6.1.tar.gz 15:03:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:03:10 INFO - Installing collected packages: blobuploader, requests, docopt 15:03:10 INFO - Running setup.py install for blobuploader 15:03:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:03:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:03:10 INFO - Running setup.py install for requests 15:03:11 INFO - Running setup.py install for docopt 15:03:11 INFO - Successfully installed blobuploader requests docopt 15:03:11 INFO - Cleaning up... 15:03:11 INFO - Return code: 0 15:03:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:03:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:03:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:03:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:03:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:03:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:11 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 0x108d34a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d3a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff08ac80910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bd8d30>, '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 0x1089fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bcfb20>, '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.ksrh0O08c0/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.7komNO0YGo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 15:03:11 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 15:03:11 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 15:03:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ksrh0O08c0/Render', 15:03:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:03:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:03:11 INFO - 'HOME': '/Users/cltbld', 15:03:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:03:11 INFO - 'LOGNAME': 'cltbld', 15:03:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:03:11 INFO - 'MOZ_NO_REMOTE': '1', 15:03:11 INFO - 'NO_EM_RESTART': '1', 15:03:11 INFO - 'PAGER': '/bin/cat', 15:03:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:03:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:03:11 INFO - 'PWD': '/builds/slave/test', 15:03:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:03:11 INFO - 'SHELL': '/bin/bash', 15:03:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7komNO0YGo/Listeners', 15:03:11 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 15:03:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:03:11 INFO - 'USER': 'cltbld', 15:03:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:03:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:03:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:03:11 INFO - 'XPC_FLAGS': '0x0', 15:03:11 INFO - 'XPC_SERVICE_NAME': '0', 15:03:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:03:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:03:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:03:11 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-GA2il5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:03:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:03:11 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-EdIKz9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:03:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:03:11 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-O53uPu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:03:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:03:11 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-v0MFgJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:03:12 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-VSKEYc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:03:12 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-qO_9ad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:03:12 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-WnwreA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:03:12 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-SG0eSz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:03:12 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-SyR5Wx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:03:12 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-pppUUp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:03:12 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-k0QgaB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:03:12 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-tFi7K1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:03:12 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-1jQUJQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:03:12 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-9TlfWv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:03:13 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-dtEUce-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:03:13 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-2TiRzi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:03:13 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-uqbeSb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:03:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:03:13 INFO - Running setup.py install for manifestparser 15:03:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:03:13 INFO - Running setup.py install for mozcrash 15:03:13 INFO - Running setup.py install for mozdebug 15:03:13 INFO - Running setup.py install for mozdevice 15:03:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:03:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:03:13 INFO - Running setup.py install for mozfile 15:03:14 INFO - Running setup.py install for mozhttpd 15:03:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:03:14 INFO - Running setup.py install for mozinfo 15:03:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:03:14 INFO - Running setup.py install for mozInstall 15:03:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:03:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:03:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:03:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:03:14 INFO - Running setup.py install for mozleak 15:03:14 INFO - Running setup.py install for mozlog 15:03:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:03:14 INFO - Running setup.py install for moznetwork 15:03:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:03:14 INFO - Running setup.py install for mozprocess 15:03:15 INFO - Running setup.py install for mozprofile 15:03:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:03:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:03:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:03:15 INFO - Running setup.py install for mozrunner 15:03:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:03:15 INFO - Running setup.py install for mozscreenshot 15:03:15 INFO - Running setup.py install for moztest 15:03:15 INFO - Running setup.py install for mozversion 15:03:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:03:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:03:15 INFO - Cleaning up... 15:03:15 INFO - Return code: 0 15:03:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:03:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:03:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:03:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:03:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:03:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:15 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 0x108d34a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d3a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff08ac80910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bd8d30>, '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 0x1089fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bcfb20>, '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.ksrh0O08c0/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.7komNO0YGo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 15:03:15 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 15:03:15 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 15:03:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ksrh0O08c0/Render', 15:03:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:03:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:03:15 INFO - 'HOME': '/Users/cltbld', 15:03:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:03:15 INFO - 'LOGNAME': 'cltbld', 15:03:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:03:15 INFO - 'MOZ_NO_REMOTE': '1', 15:03:15 INFO - 'NO_EM_RESTART': '1', 15:03:15 INFO - 'PAGER': '/bin/cat', 15:03:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:03:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:03:15 INFO - 'PWD': '/builds/slave/test', 15:03:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:03:15 INFO - 'SHELL': '/bin/bash', 15:03:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7komNO0YGo/Listeners', 15:03:15 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 15:03:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:03:15 INFO - 'USER': 'cltbld', 15:03:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:03:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:03:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:03:15 INFO - 'XPC_FLAGS': '0x0', 15:03:15 INFO - 'XPC_SERVICE_NAME': '0', 15:03:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:03:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:03:16 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-GJ6brH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:03:16 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)) 15:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:03:16 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-fYd4oe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:03:16 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)) 15:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:03:16 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-AVPn37-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:03:16 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)) 15:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:03:16 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-ODDeQ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:03:16 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)) 15:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:03:16 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-qAtcTH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:03:16 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)) 15:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:03:16 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-xOFiwz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:03:16 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)) 15:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:03:16 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-5Umw2T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:03:16 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)) 15:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:03:16 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-_6nOW_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:03:17 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)) 15:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:03:17 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-7Z5X_W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:03:17 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)) 15:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:03:17 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-dM4cJ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:03:17 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)) 15:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:03:17 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-02QoT8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:03:17 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)) 15:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:03:17 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-fUPcjW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:03:17 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)) 15:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:03:17 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-aojJjV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:03:17 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)) 15:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:03:17 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-WTfFCH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:03:17 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)) 15:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:03:17 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-9mA85e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:03:17 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)) 15:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:03:17 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-GkReGQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:03:17 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)) 15:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:03:17 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-_LMA86-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:03:17 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)) 15:03:17 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)) 15:03:17 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)) 15:03:17 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)) 15:03:17 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)) 15:03:17 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)) 15:03:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:03:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:03:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:03:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:03:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:03:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:03:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:03:20 INFO - Downloading blessings-1.6.tar.gz 15:03:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:03:20 INFO - Installing collected packages: blessings 15:03:20 INFO - Running setup.py install for blessings 15:03:20 INFO - Successfully installed blessings 15:03:20 INFO - Cleaning up... 15:03:20 INFO - Return code: 0 15:03:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:03:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:03:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:03:21 INFO - Reading from file tmpfile_stdout 15:03:21 INFO - Current package versions: 15:03:21 INFO - blessings == 1.6 15:03:21 INFO - blobuploader == 1.2.4 15:03:21 INFO - docopt == 0.6.1 15:03:21 INFO - manifestparser == 1.1 15:03:21 INFO - mozInstall == 1.12 15:03:21 INFO - mozcrash == 0.17 15:03:21 INFO - mozdebug == 0.1 15:03:21 INFO - mozdevice == 0.48 15:03:21 INFO - mozfile == 1.2 15:03:21 INFO - mozhttpd == 0.7 15:03:21 INFO - mozinfo == 0.9 15:03:21 INFO - mozleak == 0.1 15:03:21 INFO - mozlog == 3.2 15:03:21 INFO - moznetwork == 0.27 15:03:21 INFO - mozprocess == 0.22 15:03:21 INFO - mozprofile == 0.28 15:03:21 INFO - mozrunner == 6.11 15:03:21 INFO - mozscreenshot == 0.1 15:03:21 INFO - mozsystemmonitor == 0.3 15:03:21 INFO - moztest == 0.7 15:03:21 INFO - mozversion == 1.4 15:03:21 INFO - psutil == 3.1.1 15:03:21 INFO - requests == 1.2.3 15:03:21 INFO - wsgiref == 0.1.2 15:03:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:03:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:03:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:03:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:03:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:03:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:21 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 0x108d34a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d3a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff08ac80910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bd8d30>, '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 0x1089fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bcfb20>, '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.ksrh0O08c0/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.7komNO0YGo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 15:03:21 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 15:03:21 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 15:03:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ksrh0O08c0/Render', 15:03:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:03:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:03:21 INFO - 'HOME': '/Users/cltbld', 15:03:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:03:21 INFO - 'LOGNAME': 'cltbld', 15:03:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:03:21 INFO - 'MOZ_NO_REMOTE': '1', 15:03:21 INFO - 'NO_EM_RESTART': '1', 15:03:21 INFO - 'PAGER': '/bin/cat', 15:03:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:03:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:03:21 INFO - 'PWD': '/builds/slave/test', 15:03:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:03:21 INFO - 'SHELL': '/bin/bash', 15:03:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7komNO0YGo/Listeners', 15:03:21 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 15:03:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:03:21 INFO - 'USER': 'cltbld', 15:03:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:03:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:03:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:03:21 INFO - 'XPC_FLAGS': '0x0', 15:03:21 INFO - 'XPC_SERVICE_NAME': '0', 15:03:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:03:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:03:21 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)) 15:03:21 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)) 15:03:21 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)) 15:03:21 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)) 15:03:21 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)) 15:03:21 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)) 15:03:21 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)) 15:03:21 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)) 15:03:21 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)) 15:03:21 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)) 15:03:21 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)) 15:03:21 INFO - Cleaning up... 15:03:21 INFO - Return code: 0 15:03:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:03:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:03:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:03:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:03:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:03:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:21 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 0x108d34a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d3a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff08ac80910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bd8d30>, '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 0x1089fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bcfb20>, '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.ksrh0O08c0/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.7komNO0YGo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 15:03:21 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 15:03:21 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 15:03:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ksrh0O08c0/Render', 15:03:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:03:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:03:21 INFO - 'HOME': '/Users/cltbld', 15:03:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:03:21 INFO - 'LOGNAME': 'cltbld', 15:03:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:03:21 INFO - 'MOZ_NO_REMOTE': '1', 15:03:21 INFO - 'NO_EM_RESTART': '1', 15:03:21 INFO - 'PAGER': '/bin/cat', 15:03:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:03:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:03:21 INFO - 'PWD': '/builds/slave/test', 15:03:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:03:21 INFO - 'SHELL': '/bin/bash', 15:03:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7komNO0YGo/Listeners', 15:03:21 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 15:03:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:03:21 INFO - 'USER': 'cltbld', 15:03:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:03:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:03:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:03:21 INFO - 'XPC_FLAGS': '0x0', 15:03:21 INFO - 'XPC_SERVICE_NAME': '0', 15:03:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:03:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:03:21 INFO - Downloading/unpacking jsonschema 15:03:21 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 15:03:21 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 15:03:21 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 15:03:21 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 15:03:21 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 15:03:21 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 15:03:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:03:24 INFO - Downloading/unpacking functools32 (from jsonschema) 15:03:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:03:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:03:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:03:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:03:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:03:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:03:24 INFO - Downloading functools32-3.2.3-2.tar.gz 15:03:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:03:24 INFO - warning: no files found matching '*.txt' 15:03:24 INFO - no previously-included directories found matching 'build' 15:03:24 INFO - no previously-included directories found matching 'dist' 15:03:24 INFO - no previously-included directories found matching '.git*' 15:03:24 INFO - Installing collected packages: jsonschema, functools32 15:03:24 INFO - Running setup.py install for functools32 15:03:24 INFO - warning: no files found matching '*.txt' 15:03:24 INFO - no previously-included directories found matching 'build' 15:03:24 INFO - no previously-included directories found matching 'dist' 15:03:24 INFO - no previously-included directories found matching '.git*' 15:03:24 INFO - Successfully installed jsonschema functools32 15:03:24 INFO - Cleaning up... 15:03:24 INFO - Return code: 0 15:03:24 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:03:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:03:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:03:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:03:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:03:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:25 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 0x108d34a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d3a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff08ac80910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bd8d30>, '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 0x1089fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bcfb20>, '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.ksrh0O08c0/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.7komNO0YGo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 15:03:25 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 15:03:25 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 15:03:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ksrh0O08c0/Render', 15:03:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:03:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:03:25 INFO - 'HOME': '/Users/cltbld', 15:03:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:03:25 INFO - 'LOGNAME': 'cltbld', 15:03:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:03:25 INFO - 'MOZ_NO_REMOTE': '1', 15:03:25 INFO - 'NO_EM_RESTART': '1', 15:03:25 INFO - 'PAGER': '/bin/cat', 15:03:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:03:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:03:25 INFO - 'PWD': '/builds/slave/test', 15:03:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:03:25 INFO - 'SHELL': '/bin/bash', 15:03:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7komNO0YGo/Listeners', 15:03:25 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 15:03:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:03:25 INFO - 'USER': 'cltbld', 15:03:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:03:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:03:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:03:25 INFO - 'XPC_FLAGS': '0x0', 15:03:25 INFO - 'XPC_SERVICE_NAME': '0', 15:03:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:03:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:03:25 INFO - Downloading/unpacking flake8 15:03:25 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 15:03:25 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 15:03:25 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 15:03:25 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 15:03:25 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 15:03:25 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 15:03:27 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:03:27 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:03:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:03:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:03:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:03:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:03:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:03:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:03:28 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:03:28 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:03:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:03:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:03:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:03:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:03:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:03:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:03:28 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:03:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:03:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:03:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:03:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:03:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:03:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:03:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:03:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:03:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:03:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:03:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:03:29 INFO - no previously-included directories found matching 'docs/_build' 15:03:29 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:03:29 INFO - Running setup.py install for pep8 15:03:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:03:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:03:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:03:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:03:29 INFO - no previously-included directories found matching 'docs/_build' 15:03:29 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:03:29 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:03:29 INFO - Cleaning up... 15:03:29 INFO - Return code: 0 15:03:29 INFO - Running post-action listener: _resource_record_post_action 15:03:29 INFO - Running post-action listener: _start_resource_monitoring 15:03:29 INFO - Starting resource monitoring. 15:03:29 INFO - ##### 15:03:29 INFO - ##### Finished create-virtualenv step (success) 15:03:29 INFO - ##### 15:03:29 INFO - ##### 15:03:29 INFO - ##### Running install step. 15:03:29 INFO - ##### 15:03:29 INFO - Running pre-action listener: _resource_record_pre_action 15:03:29 INFO - Running main action method: install 15:03:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:03:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:03:29 INFO - Reading from file tmpfile_stdout 15:03:29 INFO - Detecting whether we're running mozinstall >=1.0... 15:03:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:03:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:03:29 INFO - Reading from file tmpfile_stdout 15:03:29 INFO - Output received: 15:03:29 INFO - Usage: mozinstall [options] installer 15:03:29 INFO - Options: 15:03:29 INFO - -h, --help show this help message and exit 15:03:29 INFO - -d DEST, --destination=DEST 15:03:29 INFO - Directory to install application into. [default: 15:03:29 INFO - "/builds/slave/test"] 15:03:29 INFO - --app=APP Application being installed. [default: firefox] 15:03:29 INFO - mkdir: /builds/slave/test/build/application 15:03:29 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'] 15:03:29 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 15:04:04 INFO - Reading from file tmpfile_stdout 15:04:04 INFO - Output received: 15:04:04 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:04:04 INFO - Running post-action listener: _resource_record_post_action 15:04:04 INFO - ##### 15:04:04 INFO - ##### Finished install step (success) 15:04:04 INFO - ##### 15:04:04 INFO - ##### 15:04:04 INFO - ##### Running run-tests step. 15:04:04 INFO - ##### 15:04:04 INFO - Running pre-action listener: _resource_record_pre_action 15:04:04 INFO - Running main action method: run_tests 15:04:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:04:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:04:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:04:04 INFO - 2016-05-14 15:04:04.066 screenresolution[1644:8475] starting screenresolution argv=screenresolution get 15:04:04 INFO - 2016-05-14 15:04:04.069 screenresolution[1644:8475] Display 0: 1600x1200x32@60 15:04:04 INFO - 2016-05-14 15:04:04.074 screenresolution[1645:8478] starting screenresolution argv=screenresolution list 15:04:04 INFO - Available Modes on Display 0 15:04:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:04:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:04:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:04:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:04:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:04:04 INFO - Intel Iris: 15:04:04 INFO - Chipset Model: Intel Iris 15:04:04 INFO - Type: GPU 15:04:04 INFO - Bus: Built-In 15:04:04 INFO - VRAM (Dynamic, Max): 1536 MB 15:04:04 INFO - Vendor: Intel (0x8086) 15:04:04 INFO - Device ID: 0x0a2e 15:04:04 INFO - Revision ID: 0x0009 15:04:04 INFO - Displays: 15:04:04 INFO - Display: 15:04:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:04:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:04:04 INFO - Display Serial Number: 1600x1200 60 15:04:04 INFO - Main Display: Yes 15:04:04 INFO - Mirror: Off 15:04:04 INFO - Online: Yes 15:04:04 INFO - Rotation: Supported 15:04:04 INFO - Return code: 0 15:04:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:04:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:04:04 INFO - Python 2.7.3 15:04:04 INFO - Return code: 0 15:04:04 INFO - grabbing minidump binary from tooltool 15:04: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')]} 15:04:04 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 0x108bd8d30>, '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 0x1089fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bcfb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:04:04 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 15:04:04 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 15:04:04 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 15:04:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:04:04 INFO - Return code: 0 15:04:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:04:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:04:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:04:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:04:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:04:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:04:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:04:05 INFO - Return code: 0 15:04:05 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/Za5LwrsWRoCGj3_UbcTPBQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0033', '--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 15:04:05 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/Za5LwrsWRoCGj3_UbcTPBQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0033 --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 15:04:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ksrh0O08c0/Render', 15:04:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:04:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:04:05 INFO - 'HOME': '/Users/cltbld', 15:04:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:04:05 INFO - 'LOGNAME': 'cltbld', 15:04:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:04:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:04:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:04:05 INFO - 'MOZ_NO_REMOTE': '1', 15:04:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:04:05 INFO - 'NO_EM_RESTART': '1', 15:04:05 INFO - 'PAGER': '/bin/cat', 15:04:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:04:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:04:05 INFO - 'PWD': '/builds/slave/test', 15:04:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:04:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:04:05 INFO - 'SHELL': '/bin/bash', 15:04:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7komNO0YGo/Listeners', 15:04:05 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 15:04:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:04:05 INFO - 'USER': 'cltbld', 15:04:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:04:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:04:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:04:05 INFO - 'XPC_FLAGS': '0x0', 15:04:05 INFO - 'XPC_SERVICE_NAME': '0', 15:04:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:04:05 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/Za5LwrsWRoCGj3_UbcTPBQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0033', '--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 15:04:05 INFO - mozversion application_buildid: 20160514130834 15:04:05 INFO - mozversion application_changeset: 403912ca555eb65f814b18ecf38ad8e8e98569f5 15:04:05 INFO - mozversion application_display_name: Nightly 15:04:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:04:05 INFO - mozversion application_name: Firefox 15:04:05 INFO - mozversion application_remotingname: firefox 15:04:05 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 15:04:05 INFO - mozversion application_vendor: Mozilla 15:04:05 INFO - mozversion application_version: 49.0a1 15:04:05 INFO - mozversion platform_buildid: 20160514130834 15:04:05 INFO - mozversion platform_changeset: 403912ca555eb65f814b18ecf38ad8e8e98569f5 15:04:05 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 15:04:05 INFO - mozversion platform_version: 49.0a1 15:04:05 INFO - using testdate: 1463263445 15:04:05 INFO - actual date: 1463263445 15:04:05 INFO - starting webserver on 'localhost:49275' 15:04:05 INFO - SUITE-START | Running 2 tests 15:04:05 INFO - TEST-START | dromaeo_css 15:04:05 INFO - operating with platform_type : mac_ 15:04:05 INFO - Initialising browser for dromaeo_css test... 15:04:05 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmp6ZgtR3/profile http://localhost:49275/getInfo.html) 15:04:07 INFO - PROCESS | 1663 | JavaScript error: resource://app/components/nsBrowserGlue.js, line 1275: ReferenceError: E10SUINotification is not defined 15:04:08 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 15:04:08 INFO - PROCESS | 1663 | colorDepth:24 15:04:08 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 15:04:08 INFO - PROCESS | 1663 | __metrics 15:04:08 INFO - PROCESS | 1663 | 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" 15:04:08 ERROR - PROCESS | 1663 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:04:08 INFO - TEST-INFO | 1663: exit 0 15:04:08 INFO - Browser initialized. 15:04:08 INFO - Running cycle 1/1 for dromaeo_css test... 15:04:08 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmp6ZgtR3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:04:20 INFO - PROCESS | 1666 | RSS: Main: 239595520 15:04:20 INFO - PROCESS | 1666 | 15:05:11 INFO - PROCESS | 1666 | 1463263511977 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] 15:07:24 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 15:07:25 INFO - PROCESS | 1666 | RSS: Main: 352931840 15:07:25 INFO - PROCESS | 1666 | 15:10:28 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 15:10:29 INFO - PROCESS | 1666 | RSS: Main: 381173760 15:10:29 INFO - PROCESS | 1666 | 15:13:31 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 15:13:32 INFO - PROCESS | 1666 | RSS: Main: 371888128 15:13:32 INFO - PROCESS | 1666 | 15:16:34 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 15:16:35 INFO - PROCESS | 1666 | RSS: Main: 371040256 15:16:35 INFO - PROCESS | 1666 | 15:19:38 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 15:19:39 INFO - PROCESS | 1666 | RSS: Main: 343379968 15:19:39 INFO - PROCESS | 1666 | 15:22:42 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 15:22:42 INFO - PROCESS | 1666 | RSS: Main: 388788224 15:22:42 INFO - PROCESS | 1666 | 15:22:42 INFO - PROCESS | 1666 | __start_tp_report 15:22:42 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 15:22:42 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 15:22:42 INFO - PROCESS | 1666 | |i|pagename|runs| 15:22:42 INFO - PROCESS | 1666 | |0;dojo.html;3054.175372649385;3527.8236088195586;3533.858645654174;3551.6270791566876;3579.7136229101675;5772.134179873021;5780.8265752027455;5783.306003279612;5871.44221298977;5876.089206173045;5784.623999440033;5818.2145410369585;5844.795432159875;5847.0352391855395;5850.795222167225;5284.180951952453;5321.228421878825;5354.036273470773;5363.704996225206;5406.405295417501;4503.369528266046;5408.296921400212;5421.241026256327;5421.322334708161;5431.83704488864;5298.417174110845;5306.893862122755;5324.627276090675;5342.118550439173;5423.566114710833;5420.5934554908345;5431.103867861838;5447.45525447456;5448.264484294648;5531.446855314477;5198.194279886624;5266.131088370453;5308.946910530923;5332.973335133338;5356.678599284055;5659.943400565983;5684.8294551163535;5751.654900705971;5785.6528608283215;5789.478946894757;6323;6325.588836725598;6334.461570766491;6347.365263473662;6387.93612063878;6177.629342239435;6246.250449946036;6280.9685951569945;6306.400891915261;6308.905366419507;5972.044472884318;5989.431004054652;5990.400959903975;6005.549583781234;6013.548983826143;6392.456641185551;6394.424501794768;6411.711472983727;6415.550911436155;6429.485641148697;6109.419605137505;6158.952977993752;6188.504919606421;6245.812625621239;6278.6860656966965;6299.4330510253385;6306.990881458944;6320.5891617044745;6323.525735569759;6327.303996560375;6271.216723244555;6301.873962520724;6307.4954003679595;6324.367563243731;6351.96824015877;6190.628562286277;6239.594426362272;6256.843578910472;6350.396712312323;6367.681615919186;4947.2579113133315;4977.303177555145;4986.800527978913;4988.7505624718615;4990.126727822689;4446.288593825037;4497.820087196541;4504.391907092566;4511.278195488706;4526.32105184225;5872.11918212263;5873.530117590582;5883.17635531026;5909.6158749681135;5940.227770389822;6222.222222222223;6305.243370795534;6310.7475700971445;6314.273858506239;6322.462590679844;5697.857553561112;5756.5682573807135;5872.941270587325;5886.735096920649;5902.704864756745;4549.7042692224895;5405.945940540544;5441.673499590037;5484.506394424521;5494.862628434321;6413.743450261937;6414;6435;6451.48388128958;6466.4503517201565;6208.875822483616;6226.377362263737;6280.089387038763;6281.59169653971;6285.842853928505;6361.7137228823885;6473.2232132144445;6484.967575162283;6493.253275873335;6493.448056915215;6301.747930082745;6318.052292156213;6330.968345158429;6337.112804207322;6339.841503962253;6141.884937092248;6175.351588083367;6229.221347331583;6313.08460273267;6320.6839658016;6234.407731265523;6261.060840873905;6272.372762723691;6275.96862015687;6295.96852015737;6220;6246.406591373812;6246.562740608114;6272.18461599989;6281.214848143982;5968.46283834457;6031.396860313934;6085.847853803691;6094.108664720002;6172.969135154295;6262.655553944394;6266.122742816101;6273.905891411541;6280.842978925563;6297.96851015742;5132.5124113209185;6123.877522449437;6138.693065346804;6144.938550614615;6157.353477884937 15:22:42 INFO - PROCESS | 1666 | |1;ext.html;497.2516362873875;508.9007643509515;528.5507318779038;532.6005495878089;543.0279799159505;30553.388932221344;31485.685143148563;32412.02763917086;32414;32486.67513324866;44296.77851610738;45864;45955.77022114893;48229.27656085161;48525;9547.952260238706;9691.709248722515;9721.902780972207;9739.318247722662;9747.463889486075;9557.904420955805;9676.080772326617;9689;9692.224622030257;9704.563294651722;9689.370190937601;9728.805423891517;9739;9739.853902191437;9759.072888075658;9650.662226822064;9694.61221551144;9698.321117521777;9706.902930970671;9714;9618.567164477545;9650.131488166098;9669.323147379686;9670.90329096714;9673.467959262238;40003;40264.396034059515;40312.79843600793;40460.39309410361;40462.393064104064;3215.7266632336277;3222.162237818184;3250.0737289872272;3266.1181481000235;3457.6542345765474;28071;28083.85958070217;29666.406671866444;29731.959381421762;29734.70265297341;2686.4492778980457;2792.2042217967783;2817.6618805743237;2824.7598954088926;2846.8149570277865;444316;444343;444465;444718.7764061191;445231;29922.850385747934;36612;61072.38927610756;62350;62682.0597691035;37865.81067094702;37937.430938535945;37946.62053379486;37964.2407151861;37969.620303797165;15302.234888255543;15452.5364239073;15466.14936178521;15466.845331546765;15485;12929.288889111189;13029.543965961335;13034.869651303556;13037.608871733863;13050.477980880658;8317.792055198668;8343.45767525109;8354.206350396702;8418.158184181655;8429.4942303462;2910.89811856584;3410.0963244740165;3415.385230658456;3421.4012547804036;3452.671996160361;1000.5447521377756;1019.5819713917258;1071.121680222226;1096.9304927695432;1107.025817280804;29680.664370103397;29825.40349193004;30074.39851202964;30572.38855222883;30600.38799224003;1114.4873358255293;1124.7075760302216;1178.8290697848763;1210.4915935307192;1215.1251099208555;15471.613209669624;15561.922190389201;15602.141882196589;15799;15978.280977356048;713456;715564;717928;719016;723365;62100;62562;63418.68290658517;63607.68196158989;63793.72412551816;1467.6404280951235;1472.2785834940803;1473.7199932012884;1493.2384483913283;1505.3226048278318;27951.161465156078;29698.851505742332;29919.700802992564;30121.94573189928;30508;54816;55707;55880.44119558915;56011;56015.71992140013;510671;511103;511133.44433277595;602986;603506;27539.449211016068;27675.584866227426;27881;27974.3006424841;28006.159815205578;45544.089118218115;47820;48112.79718006938;48327;49225.52323430303;38753.01291999989;38922.6107738919;38939;39168.02079948024;40363.798181008904;38148.61851381451;38330.80834595809;38728.80635596804;38834.31074047961;39645.80177099096;37946;38244.66143684958;38775.61224387833;38828.80585597054;39065;32374;34141;34264;34285.657143428245;34528.82735586306;24688.382790430383;25172.496550069263;25176.50578380564;25381.746182538678;25681 15:22:42 INFO - PROCESS | 1666 | |2;jquery.html;362.7007718632129;364.0407526168545;367.28746232309317;394.3414497788693;414.17165668662676;12909.354532273384;15258.313375898097;20110.899445502753;21784.910754462293;22436.43908902274;18895.716564251514;21322.786772132273;21447.82036987969;22361.217357392496;22364.440888977784;5825.9417405825825;6007.309159446654;6050.42520960508;6058.9394106059035;6060.424259695322;5733.111367737986;5912.290525136989;5973.402659734035;5985.431384018511;5990.221271234734;5898.911516327258;5931.703414829263;5936.465718085371;5938.257717785277;5954.047352423613;5607.158926161068;5937.406259374071;5941.405859414046;5953.8809223815715;5959.225300710901;5668.404817494145;5707.115397113413;5882.735276912549;5902.026165682657;5906.173135760997;13430.261335626537;13610.455581776816;13622.455101795917;25614.10350637719;26451.867740661364;1873.915673794669;2012.8087831655969;2082;2105.147415296807;2284.1434462076722;10050.497475126287;10168.542415591317;10243.641472548425;10685.058110934999;15318.157501337422;1638.5330180898386;1737.9565510862203;1747.9563010924828;1825.1695478557224;1885.3684015854706;119879;122611;122975;124174;125668.37165814113;10506.317089389166;11356.977871991561;11716.355600442057;11759.941200293944;11807.173497855068;14106.083104598374;14921;14945.476908308157;14950.476733314283;15092.094474331576;7704.8844267336035;8488.40581159313;8596.484210947363;8764.474131552128;9000.729978100668;7217.458690598226;7570.16728159902;8697.478151310941;8707.47755134694;8713.172248636369;4374.343848422698;4375.649948004152;4558;4668.7665616719705;4708.9058218835435;1660.6927718371899;1881.5296175956012;1931.4688460673146;1943.3975467605087;1950.502621831424;836.3434703757521;894.9552522373855;925.5927391947522;972.4845831709206;1015.4567306490992;16162.59593510148;18926.24295028211;20270.391888243375;20450.693239601416;20522.38432847017;941.3034354577563;956.7082039977735;959.4627008875052;966.2318456826839;980.2696990741942;9791.118799308097;10109.78682558083;10834.349939003685;10847.132229421784;10874.619388321371;597214;602710;613425.9328703417;613478;614276;75342.49315013779;130772;133235;133997;141409.8788518173;1002.7292630989592;1008.8133695266399;1067.6316670748733;1084.9945750271356;1086.7500474890662;18770.343037993604;18993.43019709411;19078.046097695336;19133.8086619132;19402.320918767775;44706.76466176613;47893.5210647889;49339;49473;53839.19241211464;61235;67774.3222567768;70186.94719579109;71844;77347.61326193334;6992;7590.240975902587;7828.373730101692;7840.255175758294;8169.877451838346;10475;12110.878891211327;12422.813657794959;12535.122541422013;12578.496860125493;9155.86266205994;10539.57841686324;10642.62750803749;11077.556897724;11190.440477976232;9441.858372124561;10171.542280597243;10488.947555262175;10519.211059170746;10931.617393391192;9423.10480504351;10904.018638322588;10924.235303528676;10979.28634638746;11317.886821132013;7307.159676637255;7359.37445317154;7371.48399612022;7496.362809161064;7510.924890751241;9904.851427228741;10806;11059.944700276768;11124.05445537106;11151.776964460827 15:22:42 INFO - PROCESS | 1666 | |3;mootools.html;1427.685909099999;1435.6195608163835;1584.1999790106;1735.6702226576958;1819.9545011374714;8032.156623554531;8747.956260218698;9008.864867027;9009.594568244429;9111.088891110885;8699.39104262702;8955.238804701608;8956.373053886231;8987.191152796247;9005.054469280729;2183.683365911942;2417.528581926525;2458.1642241637796;2491.2650768023386;2493.9127130550432;2502.4995000999784;2534.2650631316897;2535.7210706822234;2536.1250368622887;2547.936301592466;2439.524292762912;2489.352768280251;2495.301315631626;2499.987500062506;2500.1374525788574;2154.935351939452;2231.6429371300515;2233.352327824929;2233.765454627257;2435.634654801785;2282.812937272609;2361.9527609447887;2416.7462416446197;2491.788198003172;2506.523760485502;8869.33479988997;8949.776255593662;8961.820763584692;8964.59659315326;8969.282457403391;5350.892982140335;5528.419515950848;5536.307961504812;5538.9169162462595;5544.6118771686;3000.6549246836676;3564.0733409313507;3592.5329707138167;3659.652333028358;3663.7618554793853;1031.262647207243;1045.3780000899453;1047.5024363427387;1051.3271506236015;1052.6999805055523;12095.092868034933;12110.515579376814;12149.08881833866;12177.391130443442;12185.268883866996;4863.392075990501;4880.731559764247;4998.200287953982;5003.724795136302;5018.096742586369;10203.693889183336;10242.795144097076;10251.692449226535;10254.333468324536;10262.486875656188;5549.472800083986;5549.556035517149;5555.8888822223325;5556.527695145958;5559.332880054339;4568.406107206109;4718.150732868116;4905.632077594195;5036.597072234213;5059.241113832954;4040.7690842583775;4094.546436015172;4124.835006599762;4171.5828417158045;4174.5616710244985;1327.8340207474066;1349.419749507721;1354.9864501355057;1355.986440135586;1367.856375080622;847.9406441549032;898.6180873128894;936.840737074699;959.6353385713384;975.0109776056764;5830.41695830422;5981.521478281814;6058.697065146725;6089.604175728564;6202.069689546604;446.37730366139056;447.70227798514276;456.51609294148307;476.02890104187765;959.8242153362215;3989.3218152914073;4001;4007.2586571484367;4055.1686904185162;4070;10292.845607315747;11139.888601114048;11200.327980321044;11205.71985700364;11220.495077721522;11065.004149626411;11097;11130.554777808959;11132.554697811996;11145.665630031112;7969.760907172794;8334.374921880883;8356.916430835736;8359.540225287668;8549;6043.36544662804;6078.179445774764;6134.846628834315;6193.938060619336;6271.247450305992;9920;10054.24593155531;10268.948655256676;10283.94858025735;10366.06705396518;10365.792684146125;10488.790224195625;10646.574137034431;10647.254692171473;10677.305975111592;7039.824004399931;7067.858642827217;7074.327938845801;7591.734289299848;7758.495697779852;8005.559694216874;8691.739247822576;8692.695755648521;8725.258353040063;8725.650973961225;7577.0907491101425;7607.619619019138;7666.19504951972;7841.137474877873;7852.6073696313815;7403.259674032553;7443.776686699408;7471.40228781707;7500.212477689764;7523.623818809147;7055.18865330473;7091.149062112373;7131.2155662876;7139.32176443237;7145;4786.234202527578;4791.1136439757365;4796.784144713566;4881.438634557071;5029.44676085623;4170.4995400552125;4920.852374428773;5003.949960500348;5026.723530205728;5234.084035293732 15:22:42 INFO - PROCESS | 1666 | |4;prototype.html;310.56055681211086;319.5781568329804;322.9854656540455;325.72638983254063;328.46460269760286;4666.859994200171;5469.453054694529;5545.805896793613;5556.972215138919;5606;4268.893277668059;5391.676499410039;5502.449755024496;5527.447255274471;5546.613346663335;3300.504924261356;4115.526714427834;4124.597636803486;4146.1914926589325;4180.47744031996;3733.663970242678;4149.46057012588;4173.353130264813;4187.309093999502;4365.257906155945;3528.6118526962014;4067.450894129299;4089.897752556195;4094.754314741125;4130.194612050651;3981.511555277951;4094.2016306820183;4156.108970418452;4156.937645935313;4180.143070670505;3599.3161299353037;3617.366960781853;3629.800360980145;3633.091727068233;3633.94549081764;4492.415985921842;4536.909261814745;5076.314697515862;5136.460671629462;5151.201563757623;4029.0531725044593;4504.234280172379;4542.318652202163;4737.289406589005;4754.429468463806;5204.713740744258;5419.184573167954;5640.520555752766;5667.829965101054;5712.22884910536;3634.018814919983;3724.938392558135;3961.6038396160443;3966.5522084439262;3988.6011398859882;4625.0056237908675;5334.5732341412595;5676.460736230051;6002.249718785152;6025.186599809013;3979.9602003980167;4004.6796256299426;4006.719529632956;4019.678425725935;4047.838086476567;3514.769830559284;4095.672346212296;4102.261592913304;4113.053997580513;4134.1731653668785;3970.563238043813;4056.79716014204;4695.783388378972;4699.130660827758;4719.174144524694;4803.95196048042;5534.584906132056;5711.457411545896;5720.68536230503;5758.798442054508;1368.753624347627;1377.9931100344634;1382.0947279531922;1396.4763213794827;1406.2406300597563;746.3022074360382;783.8432313537315;847.2798121596594;848.321342925657;893.2987604730256;4518.796654150571;4744.335792988984;4923.138450771101;4965;5341.62608617393;5381.730913454312;5519.558435325244;5583.832485025456;5939;5962.970185149134;4761.428628564595;5025.165814477689;5080.77136528857;5282.4717528246865;5357.19642053695;2797.6642802863785;3083.3370825272673;3099.6361600895534;3108;3112.486439737418;5065.645404821701;5344.545713614386;5414.864628384243;5978.671173085522;6013.7371152057685;5390.9191362129595;5805.82582522525;5838.7080645967535;6026.734385779036;6059.48494377974;5638.915416268759;6105.389461053859;6127.846803829851;6147.340218140987;6177.289611694623;4912.557869791736;5058.848234552969;5551.389347171727;5558.583106266965;5869.73800632866;5269.915815243217;5484.287042684426;5733.483986441241;5837.620554663933;5854.941450585439;5964.777220669839;6122.938770612415;6123.632582045091;6168.625079344574;6198.442140207223;3857.9614203858723;3949.9460280654475;3957.248122856763;3964.702647301406;4009;4478.7760611969925;5177.844664660066;5403.297961142228;5500.28496295479;5796.42035796417;4747.83382581608;5543.31809894861;5581.576697941999;5637.943620563742;5666;4475;5544.22380866687;5604.663720176803;5625.296837895263;5698.487136157766;4556.65825063115;5333.839984800462;5540.390557038642;5585.1901474286815;5873.530117590497;4838.612910967185;5282.075636763474;5305.628605997543;5621.353544342453;5781.103928891029;4831.492693267156;4993.5755460784785;5433.4566543345345;5602.758896441456;5687.630304030225 15:22:42 INFO - PROCESS | 1666 | |5;yui.html;614.5851550203612;686.6429456682522;708.9822754431138;717.4368121024996;755.5428965475887;15040.398384064638;15666.060036397796;15841.683166336681;15906.840931590681;15939;12569.371531423416;14798.704025919475;14866.851331486681;14909.552713418587;14914.105153690785;724.0008787872729;751.1324420294568;897.0356866368647;925.5464822237122;927.2350310993423;725.2493669052532;921;924.8936372317169;925.8333499970003;926.2034650200821;971.6793458158791;981.1513041219365;981.5975450065474;983.9360441571275;985.3841349156777;756.3873262657265;779.5050143159135;782.9843403131963;977.291463688827;977.8484334928062;577.419693208323;693.5977133262695;715.4777012780647;715.7852644206769;721.7221369772675;14460.855391446055;14529.346179421846;14535.927320363437;14553.563393098224;14559.199244041574;11914.344711040889;12412.81380779289;12486.4115529227;12715.682107947283;12740.745185096339;10863;11005.889941100566;11062.336259824437;11193.440327983568;11512.53586129475;3997.2605068062503;4021.718479706422;4103.199876024177;4179.331306990867;4206.642435392995;80800.59599702022;81644;83470.58264708698;83776.16223837745;84320.57839710885;9353.859692104625;9474.668386606574;9525.428474291426;9615.855762163574;10098.868657248735;20819.479513011993;20882.791172088084;20948.37154885356;20958.685619715718;20980.37058888205;7345.742898998509;7378.262173782578;7387.889181662279;7440.069991251094;7555.622218889143;3969.186316805048;4024.2353952748886;4085.611866872642;4099.1186894817165;4146.129312844337;3473.913152171165;3571.964280357215;3576.2311103112434;3577.83899724513;3582.3730847102174;623.6861244781819;639.6865535887474;640.1965533255734;654.7184710574403;664.2361284522838;481.78349667090174;509.3454910440067;527.101292296641;571.6055921414211;597.2176448852018;7952.443328966917;7954.681812727426;8056.436049476598;8146.959265203636;8212.922472116448;530.6338626347807;549.8542886135177;562.5668235458755;563.1496440374981;563.5913962377243;3781.489498917611;3821.980890095532;3846.538415390171;3957.050307926135;4035.152617950205;87343;91658.54170729237;93638.53180734052;95526.52236738772;95580.13259602223;82882.58558707168;83222.58388708018;84128.57935710282;84338;84906.5754671235;69026.65486672535;74383.62808185925;75015.24984750192;75050;75101.49797004029;7363.889541656772;7417.406607471282;7429.405647548291;7637.847243055219;7820.0224971878515;44817.327740083274;45797;46864.35974740868;47175.292370615156;48453.5154648449;57966.4203357961;58500.707496462244;62648.68675656593;63850.361496384445;64595;9375.27938094148;9681.031896810544;9804.068613481708;9900.801983960137;9942.403455792675;10398;10452.738681533023;10531.420771857622;10849.132069434268;10949.945250274017;9981.201503879818;9992.300538962203;10045.748856278651;10290.228232882415;10387.480625968823;10098.192144628265;10101.191904647758;10370.637027703995;10427.009434103747;10439.478026098817;9968.252381071303;10031;10294.330868493524;10342.793144136924;10392.960703929546;8350.62422191015;8363.665453381796;8676.522791246289;8762.868556971524;8881.200691937756;8393.748187554384;8469.280111190617;8515.446495977742;8607.031641891728;8635.740927772176 15:22:42 INFO - PROCESS | 1666 | __end_tp_report 15:22:42 INFO - PROCESS | 1666 | __start_cc_report 15:22:42 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,1361 15:22:42 INFO - PROCESS | 1666 | __end_cc_report 15:22:42 INFO - PROCESS | 1666 | __startTimestamp1463264562422__endTimestamp 15:22:42 INFO - PROCESS | 1666 | 15:22:42 INFO - PROCESS | 1666 | ------- Summary: start ------- 15:22:42 INFO - PROCESS | 1666 | Number of tests: 6 15:22:42 INFO - PROCESS | 1666 | 15:22:42 INFO - PROCESS | 1666 | [#0] dojo.html Cycles:165 Average:5845.54 Median:6138.69 stddev:655.48 (10.7%) stddev-sans-first:619.83 15:22:42 INFO - PROCESS | 1666 | Values: 3054.2 3527.8 3533.9 3551.6 3579.7 5772.1 5780.8 5783.3 5871.4 5876.1 5784.6 5818.2 5844.8 5847.0 5850.8 5284.2 5321.2 5354.0 5363.7 5406.4 4503.4 5408.3 5421.2 5421.3 5431.8 5298.4 5306.9 5324.6 5342.1 5423.6 5420.6 5431.1 5447.5 5448.3 5531.4 5198.2 5266.1 5308.9 5333.0 5356.7 5659.9 5684.8 5751.7 5785.7 5789.5 6323.0 6325.6 6334.5 6347.4 6387.9 6177.6 6246.3 6281.0 6306.4 6308.9 5972.0 5989.4 5990.4 6005.5 6013.5 6392.5 6394.4 6411.7 6415.6 6429.5 6109.4 6159.0 6188.5 6245.8 6278.7 6299.4 6307.0 6320.6 6323.5 6327.3 6271.2 6301.9 6307.5 6324.4 6352.0 6190.6 6239.6 6256.8 6350.4 6367.7 4947.3 4977.3 4986.8 4988.8 4990.1 4446.3 4497.8 4504.4 4511.3 4526.3 5872.1 5873.5 5883.2 5909.6 5940.2 6222.2 6305.2 6310.7 6314.3 6322.5 5697.9 5756.6 5872.9 5886.7 5902.7 4549.7 5405.9 5441.7 5484.5 5494.9 6413.7 6414.0 6435.0 6451.5 6466.5 6208.9 6226.4 6280.1 6281.6 6285.8 6361.7 6473.2 6485.0 6493.3 6493.4 6301.7 6318.1 6331.0 6337.1 6339.8 6141.9 6175.4 6229.2 6313.1 6320.7 6234.4 6261.1 6272.4 6276.0 6296.0 6220.0 6246.4 6246.6 6272.2 6281.2 5968.5 6031.4 6085.8 6094.1 6173.0 6262.7 6266.1 6273.9 6280.8 6298.0 5132.5 6123.9 6138.7 6144.9 6157.4 15:22:42 INFO - PROCESS | 1666 | 15:22:42 INFO - PROCESS | 1666 | [#1] ext.html Cycles:180 Average:69196.40 Median:38529.81 stddev:156086.52 (405.1%) stddev-sans-first:156439.16 15:22:42 INFO - PROCESS | 1666 | Values: 497.3 508.9 528.6 532.6 543.0 30553.4 31485.7 32412.0 32414.0 32486.7 44296.8 45864.0 45955.8 48229.3 48525.0 9548.0 9691.7 9721.9 9739.3 9747.5 9557.9 9676.1 9689.0 9692.2 9704.6 9689.4 9728.8 9739.0 9739.9 9759.1 9650.7 9694.6 9698.3 9706.9 9714.0 9618.6 9650.1 9669.3 9670.9 9673.5 40003.0 40264.4 40312.8 40460.4 40462.4 3215.7 3222.2 3250.1 3266.1 3457.7 28071.0 28083.9 29666.4 29732.0 29734.7 2686.4 2792.2 2817.7 2824.8 2846.8 444316.0 444343.0 444465.0 444718.8 445231.0 29922.9 36612.0 61072.4 62350.0 62682.1 37865.8 37937.4 37946.6 37964.2 37969.6 15302.2 15452.5 15466.1 15466.8 15485.0 12929.3 13029.5 13034.9 13037.6 13050.5 8317.8 8343.5 8354.2 8418.2 8429.5 2910.9 3410.1 3415.4 3421.4 3452.7 1000.5 1019.6 1071.1 1096.9 1107.0 29680.7 29825.4 30074.4 30572.4 30600.4 1114.5 1124.7 1178.8 1210.5 1215.1 15471.6 15561.9 15602.1 15799.0 15978.3 713456.0 715564.0 717928.0 719016.0 723365.0 62100.0 62562.0 63418.7 63607.7 63793.7 1467.6 1472.3 1473.7 1493.2 1505.3 27951.2 29698.9 29919.7 30121.9 30508.0 54816.0 55707.0 55880.4 56011.0 56015.7 510671.0 511103.0 511133.4 602986.0 603506.0 27539.4 27675.6 27881.0 27974.3 28006.2 45544.1 47820.0 48112.8 48327.0 49225.5 38753.0 38922.6 38939.0 39168.0 40363.8 38148.6 38330.8 38728.8 38834.3 39645.8 37946.0 38244.7 38775.6 38828.8 39065.0 32374.0 34141.0 34264.0 34285.7 34528.8 24688.4 25172.5 25176.5 25381.7 25681.0 15:22:42 INFO - PROCESS | 1666 | 15:22:42 INFO - PROCESS | 1666 | [#2] jquery.html Cycles:180 Average:34636.65 Median:22400.44 stddev:101457.80 (452.9%) stddev-sans-first:101709.78 15:22:42 INFO - PROCESS | 1666 | Values: 362.7 364.0 367.3 394.3 414.2 12909.4 15258.3 20110.9 21784.9 22436.4 18895.7 21322.8 21447.8 22361.2 22364.4 5825.9 6007.3 6050.4 6058.9 6060.4 5733.1 5912.3 5973.4 5985.4 5990.2 5898.9 5931.7 5936.5 5938.3 5954.0 5607.2 5937.4 5941.4 5953.9 5959.2 5668.4 5707.1 5882.7 5902.0 5906.2 13430.3 13610.5 13622.5 25614.1 26451.9 1873.9 2012.8 2082.0 2105.1 2284.1 10050.5 10168.5 10243.6 10685.1 15318.2 1638.5 1738.0 1748.0 1825.2 1885.4 119879.0 122611.0 122975.0 124174.0 125668.4 10506.3 11357.0 11716.4 11759.9 11807.2 14106.1 14921.0 14945.5 14950.5 15092.1 7704.9 8488.4 8596.5 8764.5 9000.7 7217.5 7570.2 8697.5 8707.5 8713.2 4374.3 4375.6 4558.0 4668.8 4708.9 1660.7 1881.5 1931.5 1943.4 1950.5 836.3 895.0 925.6 972.5 1015.5 16162.6 18926.2 20270.4 20450.7 20522.4 941.3 956.7 959.5 966.2 980.3 9791.1 10109.8 10834.3 10847.1 10874.6 597214.0 602710.0 613425.9 613478.0 614276.0 75342.5 130772.0 133235.0 133997.0 141409.9 1002.7 1008.8 1067.6 1085.0 1086.8 18770.3 18993.4 19078.0 19133.8 19402.3 44706.8 47893.5 49339.0 49473.0 53839.2 61235.0 67774.3 70186.9 71844.0 77347.6 6992.0 7590.2 7828.4 7840.3 8169.9 10475.0 12110.9 12422.8 12535.1 12578.5 9155.9 10539.6 10642.6 11077.6 11190.4 9441.9 10171.5 10488.9 10519.2 10931.6 9423.1 10904.0 10924.2 10979.3 11317.9 7307.2 7359.4 7371.5 7496.4 7510.9 9904.9 10806.0 11059.9 11124.1 11151.8 15:22:42 INFO - PROCESS | 1666 | 15:22:42 INFO - PROCESS | 1666 | [#3] mootools.html Cycles:180 Average:5843.47 Median:2631.13 stddev:3319.58 (126.2%) stddev-sans-first:3312.30 15:22:42 INFO - PROCESS | 1666 | Values: 1427.7 1435.6 1584.2 1735.7 1820.0 8032.2 8748.0 9008.9 9009.6 9111.1 8699.4 8955.2 8956.4 8987.2 9005.1 2183.7 2417.5 2458.2 2491.3 2493.9 2502.5 2534.3 2535.7 2536.1 2547.9 2439.5 2489.4 2495.3 2500.0 2500.1 2154.9 2231.6 2233.4 2233.8 2435.6 2282.8 2362.0 2416.7 2491.8 2506.5 8869.3 8949.8 8961.8 8964.6 8969.3 5350.9 5528.4 5536.3 5538.9 5544.6 3000.7 3564.1 3592.5 3659.7 3663.8 1031.3 1045.4 1047.5 1051.3 1052.7 12095.1 12110.5 12149.1 12177.4 12185.3 4863.4 4880.7 4998.2 5003.7 5018.1 10203.7 10242.8 10251.7 10254.3 10262.5 5549.5 5549.6 5555.9 5556.5 5559.3 4568.4 4718.2 4905.6 5036.6 5059.2 4040.8 4094.5 4124.8 4171.6 4174.6 1327.8 1349.4 1355.0 1356.0 1367.9 847.9 898.6 936.8 959.6 975.0 5830.4 5981.5 6058.7 6089.6 6202.1 446.4 447.7 456.5 476.0 959.8 3989.3 4001.0 4007.3 4055.2 4070.0 10292.8 11139.9 11200.3 11205.7 11220.5 11065.0 11097.0 11130.6 11132.6 11145.7 7969.8 8334.4 8356.9 8359.5 8549.0 6043.4 6078.2 6134.8 6193.9 6271.2 9920.0 10054.2 10268.9 10283.9 10366.1 10365.8 10488.8 10646.6 10647.3 10677.3 7039.8 7067.9 7074.3 7591.7 7758.5 8005.6 8691.7 8692.7 8725.3 8725.7 7577.1 7607.6 7666.2 7841.1 7852.6 7403.3 7443.8 7471.4 7500.2 7523.6 7055.2 7091.1 7131.2 7139.3 7145.0 4786.2 4791.1 4796.8 4881.4 5029.4 4170.5 4920.9 5003.9 5026.7 5234.1 15:22:42 INFO - PROCESS | 1666 | 15:22:42 INFO - PROCESS | 1666 | [#4] prototype.html Cycles:180 Average:4572.03 Median:5062.25 stddev:1390.87 (27.5%) stddev-sans-first:1357.49 15:22:42 INFO - PROCESS | 1666 | Values: 310.6 319.6 323.0 325.7 328.5 4666.9 5469.5 5545.8 5557.0 5606.0 4268.9 5391.7 5502.4 5527.4 5546.6 3300.5 4115.5 4124.6 4146.2 4180.5 3733.7 4149.5 4173.4 4187.3 4365.3 3528.6 4067.5 4089.9 4094.8 4130.2 3981.5 4094.2 4156.1 4156.9 4180.1 3599.3 3617.4 3629.8 3633.1 3633.9 4492.4 4536.9 5076.3 5136.5 5151.2 4029.1 4504.2 4542.3 4737.3 4754.4 5204.7 5419.2 5640.5 5667.8 5712.2 3634.0 3724.9 3961.6 3966.6 3988.6 4625.0 5334.6 5676.5 6002.2 6025.2 3980.0 4004.7 4006.7 4019.7 4047.8 3514.8 4095.7 4102.3 4113.1 4134.2 3970.6 4056.8 4695.8 4699.1 4719.2 4804.0 5534.6 5711.5 5720.7 5758.8 1368.8 1378.0 1382.1 1396.5 1406.2 746.3 783.8 847.3 848.3 893.3 4518.8 4744.3 4923.1 4965.0 5341.6 5381.7 5519.6 5583.8 5939.0 5963.0 4761.4 5025.2 5080.8 5282.5 5357.2 2797.7 3083.3 3099.6 3108.0 3112.5 5065.6 5344.5 5414.9 5978.7 6013.7 5390.9 5805.8 5838.7 6026.7 6059.5 5638.9 6105.4 6127.8 6147.3 6177.3 4912.6 5058.8 5551.4 5558.6 5869.7 5269.9 5484.3 5733.5 5837.6 5854.9 5964.8 6122.9 6123.6 6168.6 6198.4 3858.0 3949.9 3957.2 3964.7 4009.0 4478.8 5177.8 5403.3 5500.3 5796.4 4747.8 5543.3 5581.6 5637.9 5666.0 4475.0 5544.2 5604.7 5625.3 5698.5 4556.7 5333.8 5540.4 5585.2 5873.5 4838.6 5282.1 5305.6 5621.4 5781.1 4831.5 4993.6 5433.5 5602.8 5687.6 15:22:42 INFO - PROCESS | 1666 | 15:22:42 INFO - PROCESS | 1666 | [#5] yui.html Cycles:180 Average:18152.29 Median:639.94 stddev:26127.28 (4082.8%) stddev-sans-first:26167.39 15:22:42 INFO - PROCESS | 1666 | Values: 614.6 686.6 709.0 717.4 755.5 15040.4 15666.1 15841.7 15906.8 15939.0 12569.4 14798.7 14866.9 14909.6 14914.1 724.0 751.1 897.0 925.5 927.2 725.2 921.0 924.9 925.8 926.2 971.7 981.2 981.6 983.9 985.4 756.4 779.5 783.0 977.3 977.8 577.4 693.6 715.5 715.8 721.7 14460.9 14529.3 14535.9 14553.6 14559.2 11914.3 12412.8 12486.4 12715.7 12740.7 10863.0 11005.9 11062.3 11193.4 11512.5 3997.3 4021.7 4103.2 4179.3 4206.6 80800.6 81644.0 83470.6 83776.2 84320.6 9353.9 9474.7 9525.4 9615.9 10098.9 20819.5 20882.8 20948.4 20958.7 20980.4 7345.7 7378.3 7387.9 7440.1 7555.6 3969.2 4024.2 4085.6 4099.1 4146.1 3473.9 3572.0 3576.2 3577.8 3582.4 623.7 639.7 640.2 654.7 664.2 481.8 509.3 527.1 571.6 597.2 7952.4 7954.7 8056.4 8147.0 8212.9 530.6 549.9 562.6 563.1 563.6 3781.5 3822.0 3846.5 3957.1 4035.2 87343.0 91658.5 93638.5 95526.5 95580.1 82882.6 83222.6 84128.6 84338.0 84906.6 69026.7 74383.6 75015.2 75050.0 75101.5 7363.9 7417.4 7429.4 7637.8 7820.0 44817.3 45797.0 46864.4 47175.3 48453.5 57966.4 58500.7 62648.7 63850.4 64595.0 9375.3 9681.0 9804.1 9900.8 9942.4 10398.0 10452.7 10531.4 10849.1 10949.9 9981.2 9992.3 10045.7 10290.2 10387.5 10098.2 10101.2 10370.6 10427.0 10439.5 9968.3 10031.0 10294.3 10342.8 10393.0 8350.6 8363.7 8676.5 8762.9 8881.2 8393.7 8469.3 8515.4 8607.0 8635.7 15:22:42 INFO - PROCESS | 1666 | -------- Summary: end -------- 15:22:42 INFO - PROCESS | 1666 | 15:22:42 INFO - TEST-INFO | 1666: exit 0 15:22:43 INFO - TEST-OK | dromaeo_css | took 1117240ms 15:22:43 INFO - TEST-START | kraken 15:22:43 INFO - operating with platform_type : mac_ 15:22:43 INFO - Initialising browser for kraken test... 15:22:43 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpIqdH8B/profile http://localhost:49275/getInfo.html) 15:22:45 INFO - PROCESS | 1672 | JavaScript error: resource://app/components/nsBrowserGlue.js, line 1275: ReferenceError: E10SUINotification is not defined 15:22:45 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 15:22:45 INFO - PROCESS | 1672 | colorDepth:24 15:22:45 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 15:22:45 INFO - PROCESS | 1672 | __metrics 15:22:45 INFO - PROCESS | 1672 | 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" 15:22:45 ERROR - PROCESS | 1672 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:22:45 INFO - TEST-INFO | 1672: exit 0 15:22:45 INFO - Browser initialized. 15:22:45 INFO - Running cycle 1/1 for kraken test... 15:22:45 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpIqdH8B/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:22:57 INFO - PROCESS | 1674 | RSS: Main: 239497216 15:22:57 INFO - PROCESS | 1674 | 15:23:00 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 15:23:01 INFO - PROCESS | 1674 | RSS: Main: 287535104 15:23:01 INFO - PROCESS | 1674 | 15:23:04 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 15:23:05 INFO - PROCESS | 1674 | RSS: Main: 297721856 15:23:05 INFO - PROCESS | 1674 | 15:23:08 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 15:23:09 INFO - PROCESS | 1674 | RSS: Main: 384909312 15:23:09 INFO - PROCESS | 1674 | 15:23:11 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 15:23:12 INFO - PROCESS | 1674 | RSS: Main: 285331456 15:23:12 INFO - PROCESS | 1674 | 15:23:14 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:23:14 INFO - PROCESS | 1674 | RSS: Main: 290848768 15:23:14 INFO - PROCESS | 1674 | 15:23:17 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 15:23:18 INFO - PROCESS | 1674 | RSS: Main: 326414336 15:23:18 INFO - PROCESS | 1674 | 15:23:21 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 15:23:22 INFO - PROCESS | 1674 | RSS: Main: 329244672 15:23:22 INFO - PROCESS | 1674 | 15:23:25 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 15:23:26 INFO - PROCESS | 1674 | RSS: Main: 328568832 15:23:26 INFO - PROCESS | 1674 | 15:23:27 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:23:28 INFO - PROCESS | 1674 | RSS: Main: 316563456 15:23:28 INFO - PROCESS | 1674 | 15:23:30 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:23:31 INFO - PROCESS | 1674 | RSS: Main: 322822144 15:23:31 INFO - PROCESS | 1674 | 15:23:33 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:23:34 INFO - PROCESS | 1674 | RSS: Main: 325079040 15:23:34 INFO - PROCESS | 1674 | 15:23:37 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:23:37 INFO - PROCESS | 1674 | RSS: Main: 329756672 15:23:37 INFO - PROCESS | 1674 | 15:23:40 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:23:41 INFO - PROCESS | 1674 | RSS: Main: 327430144 15:23:41 INFO - PROCESS | 1674 | 15:23:43 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 15:23:43 INFO - PROCESS | 1674 | RSS: Main: 375021568 15:23:43 INFO - PROCESS | 1674 | 15:23:43 INFO - PROCESS | 1674 | __start_tp_report 15:23:43 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 15:23:43 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 15:23:43 INFO - PROCESS | 1674 | |i|pagename|runs| 15:23:43 INFO - PROCESS | 1674 | |0;ai-astar;112;105;112;111;111;112;112;117;159;165 15:23:43 INFO - PROCESS | 1674 | |1;audio-beat-detection;158;117;144;197;119;150;130;147;206;141 15:23:43 INFO - PROCESS | 1674 | |2;audio-dft;185;185;186;183;183;183;181;185;186;183 15:23:43 INFO - PROCESS | 1674 | |3;audio-fft;81;75;56;125;57;63;56;73;132;79 15:23:43 INFO - PROCESS | 1674 | |4;audio-oscillator;90;94;135;79;79;77;100;94;119;138 15:23:43 INFO - PROCESS | 1674 | |5;imaging-gaussian-blur;92;88;92;89;91;89;90;89;91;88 15:23:43 INFO - PROCESS | 1674 | |6;imaging-darkroom;134;147;96;94;96;96;103;149;149;96 15:23:43 INFO - PROCESS | 1674 | |7;imaging-desaturate;83;84;84;87;86;85;85;83;85;85 15:23:43 INFO - PROCESS | 1674 | |8;json-parse-financial;58;57;56;57;56;56;57;116;55;55 15:23:43 INFO - PROCESS | 1674 | |9;json-stringify-tinderbox;63;77;73;71;70;70;67;69;68;68 15:23:43 INFO - PROCESS | 1674 | |10;stanford-crypto-aes;85;84;99;104;72;96;98;103;78;86 15:23:43 INFO - PROCESS | 1674 | |11;stanford-crypto-ccm;137;135;134;134;130;138;135;139;236;123 15:23:43 INFO - PROCESS | 1674 | |12;stanford-crypto-pbkdf2;200;212;164;162;161;197;217;163;162;160 15:23:43 INFO - PROCESS | 1674 | |13;stanford-crypto-sha256-iterative;65;66;62;66;65;64;65;64;64;65 15:23:43 INFO - PROCESS | 1674 | __end_tp_report 15:23:43 INFO - PROCESS | 1674 | __start_cc_report 15:23:43 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,2308 15:23:43 INFO - PROCESS | 1674 | __end_cc_report 15:23:43 INFO - PROCESS | 1674 | __startTimestamp1463264623430__endTimestamp 15:23:43 INFO - PROCESS | 1674 | 15:23:43 INFO - PROCESS | 1674 | ------- Summary: start ------- 15:23:43 INFO - PROCESS | 1674 | Number of tests: 14 15:23:43 INFO - PROCESS | 1674 | 15:23:43 INFO - PROCESS | 1674 | [#0] ai-astar Cycles:10 Average:121.60 Median:112.00 stddev:21.53 (19.2%) stddev-sans-first:22.56 15:23:43 INFO - PROCESS | 1674 | Values: 112.0 105.0 112.0 111.0 111.0 112.0 112.0 117.0 159.0 165.0 15:23:43 INFO - PROCESS | 1674 | 15:23:43 INFO - PROCESS | 1674 | [#1] audio-beat-detection Cycles:10 Average:150.90 Median:148.50 stddev:29.77 (20.0%) stddev-sans-first:31.47 15:23:43 INFO - PROCESS | 1674 | Values: 158.0 117.0 144.0 197.0 119.0 150.0 130.0 147.0 206.0 141.0 15:23:43 INFO - PROCESS | 1674 | 15:23:43 INFO - PROCESS | 1674 | [#2] audio-dft Cycles:10 Average:184.00 Median:185.00 stddev:1.63 (0.9%) stddev-sans-first:1.69 15:23:43 INFO - PROCESS | 1674 | Values: 185.0 185.0 186.0 183.0 183.0 183.0 181.0 185.0 186.0 183.0 15:23:43 INFO - PROCESS | 1674 | 15:23:43 INFO - PROCESS | 1674 | [#3] audio-fft Cycles:10 Average:79.70 Median:68.00 stddev:27.43 (40.3%) stddev-sans-first:29.10 15:23:43 INFO - PROCESS | 1674 | Values: 81.0 75.0 56.0 125.0 57.0 63.0 56.0 73.0 132.0 79.0 15:23:43 INFO - PROCESS | 1674 | 15:23:43 INFO - PROCESS | 1674 | [#4] audio-oscillator Cycles:10 Average:100.50 Median:79.00 stddev:22.63 (28.7%) stddev-sans-first:23.69 15:23:43 INFO - PROCESS | 1674 | Values: 90.0 94.0 135.0 79.0 79.0 77.0 100.0 94.0 119.0 138.0 15:23:43 INFO - PROCESS | 1674 | 15:23:43 INFO - PROCESS | 1674 | [#5] imaging-gaussian-blur Cycles:10 Average:89.90 Median:90.50 stddev:1.52 (1.7%) stddev-sans-first:1.41 15:23:43 INFO - PROCESS | 1674 | Values: 92.0 88.0 92.0 89.0 91.0 89.0 90.0 89.0 91.0 88.0 15:23:43 INFO - PROCESS | 1674 | 15:23:43 INFO - PROCESS | 1674 | [#6] imaging-darkroom Cycles:10 Average:116.00 Median:95.00 stddev:25.20 (26.5%) stddev-sans-first:25.87 15:23:43 INFO - PROCESS | 1674 | Values: 134.0 147.0 96.0 94.0 96.0 96.0 103.0 149.0 149.0 96.0 15:23:43 INFO - PROCESS | 1674 | 15:23:43 INFO - PROCESS | 1674 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:85.00 stddev:1.25 (1.5%) stddev-sans-first:1.17 15:23:43 INFO - PROCESS | 1674 | Values: 83.0 84.0 84.0 87.0 86.0 85.0 85.0 83.0 85.0 85.0 15:23:43 INFO - PROCESS | 1674 | 15:23:43 INFO - PROCESS | 1674 | [#8] json-parse-financial Cycles:10 Average:62.30 Median:56.50 stddev:18.89 (33.4%) stddev-sans-first:19.97 15:23:43 INFO - PROCESS | 1674 | Values: 58.0 57.0 56.0 57.0 56.0 56.0 57.0 116.0 55.0 55.0 15:23:43 INFO - PROCESS | 1674 | 15:23:43 INFO - PROCESS | 1674 | [#9] json-stringify-tinderbox Cycles:10 Average:69.60 Median:70.00 stddev:3.72 (5.3%) stddev-sans-first:3.08 15:23:43 INFO - PROCESS | 1674 | Values: 63.0 77.0 73.0 71.0 70.0 70.0 67.0 69.0 68.0 68.0 15:23:43 INFO - PROCESS | 1674 | 15:23:43 INFO - PROCESS | 1674 | [#10] stanford-crypto-aes Cycles:10 Average:90.50 Median:85.50 stddev:11.00 (12.9%) stddev-sans-first:11.48 15:23:43 INFO - PROCESS | 1674 | Values: 85.0 84.0 99.0 104.0 72.0 96.0 98.0 103.0 78.0 86.0 15:23:43 INFO - PROCESS | 1674 | 15:23:43 INFO - PROCESS | 1674 | [#11] stanford-crypto-ccm Cycles:10 Average:144.10 Median:136.00 stddev:32.61 (24.0%) stddev-sans-first:34.49 15:23:43 INFO - PROCESS | 1674 | Values: 137.0 135.0 134.0 134.0 130.0 138.0 135.0 139.0 236.0 123.0 15:23:43 INFO - PROCESS | 1674 | 15:23:43 INFO - PROCESS | 1674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.80 Median:180.50 stddev:23.65 (13.1%) stddev-sans-first:23.93 15:23:43 INFO - PROCESS | 1674 | Values: 200.0 212.0 164.0 162.0 161.0 197.0 217.0 163.0 162.0 160.0 15:23:43 INFO - PROCESS | 1674 | 15:23:43 INFO - PROCESS | 1674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.60 Median:65.00 stddev:1.17 (1.8%) stddev-sans-first:1.24 15:23:43 INFO - PROCESS | 1674 | Values: 65.0 66.0 62.0 66.0 65.0 64.0 65.0 64.0 64.0 65.0 15:23:43 INFO - PROCESS | 1674 | -------- Summary: end -------- 15:23:43 INFO - PROCESS | 1674 | 15:23:44 INFO - TEST-INFO | 1674: exit 0 15:23:44 INFO - TEST-OK | kraken | took 61240ms 15:23:44 INFO - SUITE-END | took 1178s 15:23:44 INFO - Completed test suite (00:19:38) 15:23:44 INFO - javascript benchmark 15:23:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3054.175372649385, 3527.8236088195586, 3533.858645654174, 3551.6270791566876, 3579.7136229101675, 5772.134179873021, 5780.8265752027455, 5783.306003279612, 5871.44221298977, 5876.089206173045, 5784.623999440033, 5818.2145410369585, 5844.795432159875, 5847.0352391855395, 5850.795222167225, 5284.180951952453, 5321.228421878825, 5354.036273470773, 5363.704996225206, 5406.405295417501, 4503.369528266046, 5408.296921400212, 5421.241026256327, 5421.322334708161, 5431.83704488864, 5298.417174110845, 5306.893862122755, 5324.627276090675, 5342.118550439173, 5423.566114710833, 5420.5934554908345, 5431.103867861838, 5447.45525447456, 5448.264484294648, 5531.446855314477, 5198.194279886624, 5266.131088370453, 5308.946910530923, 5332.973335133338, 5356.678599284055, 5659.943400565983, 5684.8294551163535, 5751.654900705971, 5785.6528608283215, 5789.478946894757, 6323.0, 6325.588836725598, 6334.461570766491, 6347.365263473662, 6387.93612063878, 6177.629342239435, 6246.250449946036, 6280.9685951569945, 6306.400891915261, 6308.905366419507, 5972.044472884318, 5989.431004054652, 5990.400959903975, 6005.549583781234, 6013.548983826143, 6392.456641185551, 6394.424501794768, 6411.711472983727, 6415.550911436155, 6429.485641148697, 6109.419605137505, 6158.952977993752, 6188.504919606421, 6245.812625621239, 6278.6860656966965, 6299.4330510253385, 6306.990881458944, 6320.5891617044745, 6323.525735569759, 6327.303996560375, 6271.216723244555, 6301.873962520724, 6307.4954003679595, 6324.367563243731, 6351.96824015877, 6190.628562286277, 6239.594426362272, 6256.843578910472, 6350.396712312323, 6367.681615919186, 4947.2579113133315, 4977.303177555145, 4986.800527978913, 4988.7505624718615, 4990.126727822689, 4446.288593825037, 4497.820087196541, 4504.391907092566, 4511.278195488706, 4526.32105184225, 5872.11918212263, 5873.530117590582, 5883.17635531026, 5909.6158749681135, 5940.227770389822, 6222.222222222223, 6305.243370795534, 6310.7475700971445, 6314.273858506239, 6322.462590679844, 5697.857553561112, 5756.5682573807135, 5872.941270587325, 5886.735096920649, 5902.704864756745, 4549.7042692224895, 5405.945940540544, 5441.673499590037, 5484.506394424521, 5494.862628434321, 6413.743450261937, 6414.0, 6435.0, 6451.48388128958, 6466.4503517201565, 6208.875822483616, 6226.377362263737, 6280.089387038763, 6281.59169653971, 6285.842853928505, 6361.7137228823885, 6473.2232132144445, 6484.967575162283, 6493.253275873335, 6493.448056915215, 6301.747930082745, 6318.052292156213, 6330.968345158429, 6337.112804207322, 6339.841503962253, 6141.884937092248, 6175.351588083367, 6229.221347331583, 6313.08460273267, 6320.6839658016, 6234.407731265523, 6261.060840873905, 6272.372762723691, 6275.96862015687, 6295.96852015737, 6220.0, 6246.406591373812, 6246.562740608114, 6272.18461599989, 6281.214848143982, 5968.46283834457, 6031.396860313934, 6085.847853803691, 6094.108664720002, 6172.969135154295, 6262.655553944394, 6266.122742816101, 6273.905891411541, 6280.842978925563, 6297.96851015742, 5132.5124113209185, 6123.877522449437, 6138.693065346804, 6144.938550614615, 6157.353477884937], "value": 5803.677626981626, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.2516362873875, 508.9007643509515, 528.5507318779038, 532.6005495878089, 543.0279799159505, 30553.388932221344, 31485.685143148563, 32412.02763917086, 32414.0, 32486.67513324866, 44296.77851610738, 45864.0, 45955.77022114893, 48229.27656085161, 48525.0, 9547.952260238706, 9691.709248722515, 9721.902780972207, 9739.318247722662, 9747.463889486075, 9557.904420955805, 9676.080772326617, 9689.0, 9692.224622030257, 9704.563294651722, 9689.370190937601, 9728.805423891517, 9739.0, 9739.853902191437, 9759.072888075658, 9650.662226822064, 9694.61221551144, 9698.321117521777, 9706.902930970671, 9714.0, 9618.567164477545, 9650.131488166098, 9669.323147379686, 9670.90329096714, 9673.467959262238, 40003.0, 40264.396034059515, 40312.79843600793, 40460.39309410361, 40462.393064104064, 3215.7266632336277, 3222.162237818184, 3250.0737289872272, 3266.1181481000235, 3457.6542345765474, 28071.0, 28083.85958070217, 29666.406671866444, 29731.959381421762, 29734.70265297341, 2686.4492778980457, 2792.2042217967783, 2817.6618805743237, 2824.7598954088926, 2846.8149570277865, 444316.0, 444343.0, 444465.0, 444718.7764061191, 445231.0, 29922.850385747934, 36612.0, 61072.38927610756, 62350.0, 62682.0597691035, 37865.81067094702, 37937.430938535945, 37946.62053379486, 37964.2407151861, 37969.620303797165, 15302.234888255543, 15452.5364239073, 15466.14936178521, 15466.845331546765, 15485.0, 12929.288889111189, 13029.543965961335, 13034.869651303556, 13037.608871733863, 13050.477980880658, 8317.792055198668, 8343.45767525109, 8354.206350396702, 8418.158184181655, 8429.4942303462, 2910.89811856584, 3410.0963244740165, 3415.385230658456, 3421.4012547804036, 3452.671996160361, 1000.5447521377756, 1019.5819713917258, 1071.121680222226, 1096.9304927695432, 1107.025817280804, 29680.664370103397, 29825.40349193004, 30074.39851202964, 30572.38855222883, 30600.38799224003, 1114.4873358255293, 1124.7075760302216, 1178.8290697848763, 1210.4915935307192, 1215.1251099208555, 15471.613209669624, 15561.922190389201, 15602.141882196589, 15799.0, 15978.280977356048, 713456.0, 715564.0, 717928.0, 719016.0, 723365.0, 62100.0, 62562.0, 63418.68290658517, 63607.68196158989, 63793.72412551816, 1467.6404280951235, 1472.2785834940803, 1473.7199932012884, 1493.2384483913283, 1505.3226048278318, 27951.161465156078, 29698.851505742332, 29919.700802992564, 30121.94573189928, 30508.0, 54816.0, 55707.0, 55880.44119558915, 56011.0, 56015.71992140013, 510671.0, 511103.0, 511133.44433277595, 602986.0, 603506.0, 27539.449211016068, 27675.584866227426, 27881.0, 27974.3006424841, 28006.159815205578, 45544.089118218115, 47820.0, 48112.79718006938, 48327.0, 49225.52323430303, 38753.01291999989, 38922.6107738919, 38939.0, 39168.02079948024, 40363.798181008904, 38148.61851381451, 38330.80834595809, 38728.80635596804, 38834.31074047961, 39645.80177099096, 37946.0, 38244.66143684958, 38775.61224387833, 38828.80585597054, 39065.0, 32374.0, 34141.0, 34264.0, 34285.657143428245, 34528.82735586306, 24688.382790430383, 25172.496550069263, 25176.50578380564, 25381.746182538678, 25681.0], "value": 18843.253345242083, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.7007718632129, 364.0407526168545, 367.28746232309317, 394.3414497788693, 414.17165668662676, 12909.354532273384, 15258.313375898097, 20110.899445502753, 21784.910754462293, 22436.43908902274, 18895.716564251514, 21322.786772132273, 21447.82036987969, 22361.217357392496, 22364.440888977784, 5825.9417405825825, 6007.309159446654, 6050.42520960508, 6058.9394106059035, 6060.424259695322, 5733.111367737986, 5912.290525136989, 5973.402659734035, 5985.431384018511, 5990.221271234734, 5898.911516327258, 5931.703414829263, 5936.465718085371, 5938.257717785277, 5954.047352423613, 5607.158926161068, 5937.406259374071, 5941.405859414046, 5953.8809223815715, 5959.225300710901, 5668.404817494145, 5707.115397113413, 5882.735276912549, 5902.026165682657, 5906.173135760997, 13430.261335626537, 13610.455581776816, 13622.455101795917, 25614.10350637719, 26451.867740661364, 1873.915673794669, 2012.8087831655969, 2082.0, 2105.147415296807, 2284.1434462076722, 10050.497475126287, 10168.542415591317, 10243.641472548425, 10685.058110934999, 15318.157501337422, 1638.5330180898386, 1737.9565510862203, 1747.9563010924828, 1825.1695478557224, 1885.3684015854706, 119879.0, 122611.0, 122975.0, 124174.0, 125668.37165814113, 10506.317089389166, 11356.977871991561, 11716.355600442057, 11759.941200293944, 11807.173497855068, 14106.083104598374, 14921.0, 14945.476908308157, 14950.476733314283, 15092.094474331576, 7704.8844267336035, 8488.40581159313, 8596.484210947363, 8764.474131552128, 9000.729978100668, 7217.458690598226, 7570.16728159902, 8697.478151310941, 8707.47755134694, 8713.172248636369, 4374.343848422698, 4375.649948004152, 4558.0, 4668.7665616719705, 4708.9058218835435, 1660.6927718371899, 1881.5296175956012, 1931.4688460673146, 1943.3975467605087, 1950.502621831424, 836.3434703757521, 894.9552522373855, 925.5927391947522, 972.4845831709206, 1015.4567306490992, 16162.59593510148, 18926.24295028211, 20270.391888243375, 20450.693239601416, 20522.38432847017, 941.3034354577563, 956.7082039977735, 959.4627008875052, 966.2318456826839, 980.2696990741942, 9791.118799308097, 10109.78682558083, 10834.349939003685, 10847.132229421784, 10874.619388321371, 597214.0, 602710.0, 613425.9328703417, 613478.0, 614276.0, 75342.49315013779, 130772.0, 133235.0, 133997.0, 141409.8788518173, 1002.7292630989592, 1008.8133695266399, 1067.6316670748733, 1084.9945750271356, 1086.7500474890662, 18770.343037993604, 18993.43019709411, 19078.046097695336, 19133.8086619132, 19402.320918767775, 44706.76466176613, 47893.5210647889, 49339.0, 49473.0, 53839.19241211464, 61235.0, 67774.3222567768, 70186.94719579109, 71844.0, 77347.61326193334, 6992.0, 7590.240975902587, 7828.373730101692, 7840.255175758294, 8169.877451838346, 10475.0, 12110.878891211327, 12422.813657794959, 12535.122541422013, 12578.496860125493, 9155.86266205994, 10539.57841686324, 10642.62750803749, 11077.556897724, 11190.440477976232, 9441.858372124561, 10171.542280597243, 10488.947555262175, 10519.211059170746, 10931.617393391192, 9423.10480504351, 10904.018638322588, 10924.235303528676, 10979.28634638746, 11317.886821132013, 7307.159676637255, 7359.37445317154, 7371.48399612022, 7496.362809161064, 7510.924890751241, 9904.851427228741, 10806.0, 11059.944700276768, 11124.05445537106, 11151.776964460827], "value": 9327.029378611116, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1427.685909099999, 1435.6195608163835, 1584.1999790106, 1735.6702226576958, 1819.9545011374714, 8032.156623554531, 8747.956260218698, 9008.864867027, 9009.594568244429, 9111.088891110885, 8699.39104262702, 8955.238804701608, 8956.373053886231, 8987.191152796247, 9005.054469280729, 2183.683365911942, 2417.528581926525, 2458.1642241637796, 2491.2650768023386, 2493.9127130550432, 2502.4995000999784, 2534.2650631316897, 2535.7210706822234, 2536.1250368622887, 2547.936301592466, 2439.524292762912, 2489.352768280251, 2495.301315631626, 2499.987500062506, 2500.1374525788574, 2154.935351939452, 2231.6429371300515, 2233.352327824929, 2233.765454627257, 2435.634654801785, 2282.812937272609, 2361.9527609447887, 2416.7462416446197, 2491.788198003172, 2506.523760485502, 8869.33479988997, 8949.776255593662, 8961.820763584692, 8964.59659315326, 8969.282457403391, 5350.892982140335, 5528.419515950848, 5536.307961504812, 5538.9169162462595, 5544.6118771686, 3000.6549246836676, 3564.0733409313507, 3592.5329707138167, 3659.652333028358, 3663.7618554793853, 1031.262647207243, 1045.3780000899453, 1047.5024363427387, 1051.3271506236015, 1052.6999805055523, 12095.092868034933, 12110.515579376814, 12149.08881833866, 12177.391130443442, 12185.268883866996, 4863.392075990501, 4880.731559764247, 4998.200287953982, 5003.724795136302, 5018.096742586369, 10203.693889183336, 10242.795144097076, 10251.692449226535, 10254.333468324536, 10262.486875656188, 5549.472800083986, 5549.556035517149, 5555.8888822223325, 5556.527695145958, 5559.332880054339, 4568.406107206109, 4718.150732868116, 4905.632077594195, 5036.597072234213, 5059.241113832954, 4040.7690842583775, 4094.546436015172, 4124.835006599762, 4171.5828417158045, 4174.5616710244985, 1327.8340207474066, 1349.419749507721, 1354.9864501355057, 1355.986440135586, 1367.856375080622, 847.9406441549032, 898.6180873128894, 936.840737074699, 959.6353385713384, 975.0109776056764, 5830.41695830422, 5981.521478281814, 6058.697065146725, 6089.604175728564, 6202.069689546604, 446.37730366139056, 447.70227798514276, 456.51609294148307, 476.02890104187765, 959.8242153362215, 3989.3218152914073, 4001.0, 4007.2586571484367, 4055.1686904185162, 4070.0, 10292.845607315747, 11139.888601114048, 11200.327980321044, 11205.71985700364, 11220.495077721522, 11065.004149626411, 11097.0, 11130.554777808959, 11132.554697811996, 11145.665630031112, 7969.760907172794, 8334.374921880883, 8356.916430835736, 8359.540225287668, 8549.0, 6043.36544662804, 6078.179445774764, 6134.846628834315, 6193.938060619336, 6271.247450305992, 9920.0, 10054.24593155531, 10268.948655256676, 10283.94858025735, 10366.06705396518, 10365.792684146125, 10488.790224195625, 10646.574137034431, 10647.254692171473, 10677.305975111592, 7039.824004399931, 7067.858642827217, 7074.327938845801, 7591.734289299848, 7758.495697779852, 8005.559694216874, 8691.739247822576, 8692.695755648521, 8725.258353040063, 8725.650973961225, 7577.0907491101425, 7607.619619019138, 7666.19504951972, 7841.137474877873, 7852.6073696313815, 7403.259674032553, 7443.776686699408, 7471.40228781707, 7500.212477689764, 7523.623818809147, 7055.18865330473, 7091.149062112373, 7131.2155662876, 7139.32176443237, 7145.0, 4786.234202527578, 4791.1136439757365, 4796.784144713566, 4881.438634557071, 5029.44676085623, 4170.4995400552125, 4920.852374428773, 5003.949960500348, 5026.723530205728, 5234.084035293732], "value": 4625.208466852885, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.56055681211086, 319.5781568329804, 322.9854656540455, 325.72638983254063, 328.46460269760286, 4666.859994200171, 5469.453054694529, 5545.805896793613, 5556.972215138919, 5606.0, 4268.893277668059, 5391.676499410039, 5502.449755024496, 5527.447255274471, 5546.613346663335, 3300.504924261356, 4115.526714427834, 4124.597636803486, 4146.1914926589325, 4180.47744031996, 3733.663970242678, 4149.46057012588, 4173.353130264813, 4187.309093999502, 4365.257906155945, 3528.6118526962014, 4067.450894129299, 4089.897752556195, 4094.754314741125, 4130.194612050651, 3981.511555277951, 4094.2016306820183, 4156.108970418452, 4156.937645935313, 4180.143070670505, 3599.3161299353037, 3617.366960781853, 3629.800360980145, 3633.091727068233, 3633.94549081764, 4492.415985921842, 4536.909261814745, 5076.314697515862, 5136.460671629462, 5151.201563757623, 4029.0531725044593, 4504.234280172379, 4542.318652202163, 4737.289406589005, 4754.429468463806, 5204.713740744258, 5419.184573167954, 5640.520555752766, 5667.829965101054, 5712.22884910536, 3634.018814919983, 3724.938392558135, 3961.6038396160443, 3966.5522084439262, 3988.6011398859882, 4625.0056237908675, 5334.5732341412595, 5676.460736230051, 6002.249718785152, 6025.186599809013, 3979.9602003980167, 4004.6796256299426, 4006.719529632956, 4019.678425725935, 4047.838086476567, 3514.769830559284, 4095.672346212296, 4102.261592913304, 4113.053997580513, 4134.1731653668785, 3970.563238043813, 4056.79716014204, 4695.783388378972, 4699.130660827758, 4719.174144524694, 4803.95196048042, 5534.584906132056, 5711.457411545896, 5720.68536230503, 5758.798442054508, 1368.753624347627, 1377.9931100344634, 1382.0947279531922, 1396.4763213794827, 1406.2406300597563, 746.3022074360382, 783.8432313537315, 847.2798121596594, 848.321342925657, 893.2987604730256, 4518.796654150571, 4744.335792988984, 4923.138450771101, 4965.0, 5341.62608617393, 5381.730913454312, 5519.558435325244, 5583.832485025456, 5939.0, 5962.970185149134, 4761.428628564595, 5025.165814477689, 5080.77136528857, 5282.4717528246865, 5357.19642053695, 2797.6642802863785, 3083.3370825272673, 3099.6361600895534, 3108.0, 3112.486439737418, 5065.645404821701, 5344.545713614386, 5414.864628384243, 5978.671173085522, 6013.7371152057685, 5390.9191362129595, 5805.82582522525, 5838.7080645967535, 6026.734385779036, 6059.48494377974, 5638.915416268759, 6105.389461053859, 6127.846803829851, 6147.340218140987, 6177.289611694623, 4912.557869791736, 5058.848234552969, 5551.389347171727, 5558.583106266965, 5869.73800632866, 5269.915815243217, 5484.287042684426, 5733.483986441241, 5837.620554663933, 5854.941450585439, 5964.777220669839, 6122.938770612415, 6123.632582045091, 6168.625079344574, 6198.442140207223, 3857.9614203858723, 3949.9460280654475, 3957.248122856763, 3964.702647301406, 4009.0, 4478.7760611969925, 5177.844664660066, 5403.297961142228, 5500.28496295479, 5796.42035796417, 4747.83382581608, 5543.31809894861, 5581.576697941999, 5637.943620563742, 5666.0, 4475.0, 5544.22380866687, 5604.663720176803, 5625.296837895263, 5698.487136157766, 4556.65825063115, 5333.839984800462, 5540.390557038642, 5585.1901474286815, 5873.530117590497, 4838.612910967185, 5282.075636763474, 5305.628605997543, 5621.353544342453, 5781.103928891029, 4831.492693267156, 4993.5755460784785, 5433.4566543345345, 5602.758896441456, 5687.630304030225], "value": 4130.351868341684, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [614.5851550203612, 686.6429456682522, 708.9822754431138, 717.4368121024996, 755.5428965475887, 15040.398384064638, 15666.060036397796, 15841.683166336681, 15906.840931590681, 15939.0, 12569.371531423416, 14798.704025919475, 14866.851331486681, 14909.552713418587, 14914.105153690785, 724.0008787872729, 751.1324420294568, 897.0356866368647, 925.5464822237122, 927.2350310993423, 725.2493669052532, 921.0, 924.8936372317169, 925.8333499970003, 926.2034650200821, 971.6793458158791, 981.1513041219365, 981.5975450065474, 983.9360441571275, 985.3841349156777, 756.3873262657265, 779.5050143159135, 782.9843403131963, 977.291463688827, 977.8484334928062, 577.419693208323, 693.5977133262695, 715.4777012780647, 715.7852644206769, 721.7221369772675, 14460.855391446055, 14529.346179421846, 14535.927320363437, 14553.563393098224, 14559.199244041574, 11914.344711040889, 12412.81380779289, 12486.4115529227, 12715.682107947283, 12740.745185096339, 10863.0, 11005.889941100566, 11062.336259824437, 11193.440327983568, 11512.53586129475, 3997.2605068062503, 4021.718479706422, 4103.199876024177, 4179.331306990867, 4206.642435392995, 80800.59599702022, 81644.0, 83470.58264708698, 83776.16223837745, 84320.57839710885, 9353.859692104625, 9474.668386606574, 9525.428474291426, 9615.855762163574, 10098.868657248735, 20819.479513011993, 20882.791172088084, 20948.37154885356, 20958.685619715718, 20980.37058888205, 7345.742898998509, 7378.262173782578, 7387.889181662279, 7440.069991251094, 7555.622218889143, 3969.186316805048, 4024.2353952748886, 4085.611866872642, 4099.1186894817165, 4146.129312844337, 3473.913152171165, 3571.964280357215, 3576.2311103112434, 3577.83899724513, 3582.3730847102174, 623.6861244781819, 639.6865535887474, 640.1965533255734, 654.7184710574403, 664.2361284522838, 481.78349667090174, 509.3454910440067, 527.101292296641, 571.6055921414211, 597.2176448852018, 7952.443328966917, 7954.681812727426, 8056.436049476598, 8146.959265203636, 8212.922472116448, 530.6338626347807, 549.8542886135177, 562.5668235458755, 563.1496440374981, 563.5913962377243, 3781.489498917611, 3821.980890095532, 3846.538415390171, 3957.050307926135, 4035.152617950205, 87343.0, 91658.54170729237, 93638.53180734052, 95526.52236738772, 95580.13259602223, 82882.58558707168, 83222.58388708018, 84128.57935710282, 84338.0, 84906.5754671235, 69026.65486672535, 74383.62808185925, 75015.24984750192, 75050.0, 75101.49797004029, 7363.889541656772, 7417.406607471282, 7429.405647548291, 7637.847243055219, 7820.0224971878515, 44817.327740083274, 45797.0, 46864.35974740868, 47175.292370615156, 48453.5154648449, 57966.4203357961, 58500.707496462244, 62648.68675656593, 63850.361496384445, 64595.0, 9375.27938094148, 9681.031896810544, 9804.068613481708, 9900.801983960137, 9942.403455792675, 10398.0, 10452.738681533023, 10531.420771857622, 10849.132069434268, 10949.945250274017, 9981.201503879818, 9992.300538962203, 10045.748856278651, 10290.228232882415, 10387.480625968823, 10098.192144628265, 10101.191904647758, 10370.637027703995, 10427.009434103747, 10439.478026098817, 9968.252381071303, 10031.0, 10294.330868493524, 10342.793144136924, 10392.960703929546, 8350.62422191015, 8363.665453381796, 8676.522791246289, 8762.868556971524, 8881.200691937756, 8393.748187554384, 8469.280111190617, 8515.446495977742, 8607.031641891728, 8635.740927772176], "value": 6719.204676096625, "unit": "score"}], "name": "dromaeo_css", "value": 7126.017169093417, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 105.0, 112.0, 111.0, 111.0, 112.0, 112.0, 117.0, 159.0, 165.0], "value": 121.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 117.0, 144.0, 197.0, 119.0, 150.0, 130.0, 147.0, 206.0, 141.0], "value": 150.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 186.0, 183.0, 183.0, 183.0, 181.0, 185.0, 186.0, 183.0], "value": 184.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 75.0, 56.0, 125.0, 57.0, 63.0, 56.0, 73.0, 132.0, 79.0], "value": 79.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 94.0, 135.0, 79.0, 79.0, 77.0, 100.0, 94.0, 119.0, 138.0], "value": 100.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 88.0, 92.0, 89.0, 91.0, 89.0, 90.0, 89.0, 91.0, 88.0], "value": 89.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 147.0, 96.0, 94.0, 96.0, 96.0, 103.0, 149.0, 149.0, 96.0], "value": 116.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 84.0, 87.0, 86.0, 85.0, 85.0, 83.0, 85.0, 85.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 56.0, 57.0, 56.0, 56.0, 57.0, 116.0, 55.0, 55.0], "value": 62.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 77.0, 73.0, 71.0, 70.0, 70.0, 67.0, 69.0, 68.0, 68.0], "value": 69.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 99.0, 104.0, 72.0, 96.0, 98.0, 103.0, 78.0, 86.0], "value": 90.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 135.0, 134.0, 134.0, 130.0, 138.0, 135.0, 139.0, 236.0, 123.0], "value": 144.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 212.0, 164.0, 162.0, 161.0, 197.0, 217.0, 163.0, 162.0, 160.0], "value": 179.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 62.0, 66.0, 65.0, 64.0, 65.0, 64.0, 64.0, 65.0], "value": 64.6, "unit": "score"}], "name": "kraken", "value": 1538.1999999999998, "alertThreshold": 2.0}]} 15:23:44 INFO - Return code: 0 15:23:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 15:23:44 ERROR - # TBPL SUCCESS # 15:23:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:23:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:23:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:23:44 INFO - 2016-05-14 15:23:44.770 screenresolution[1678:10499] starting screenresolution argv=screenresolution get 15:23:44 INFO - 2016-05-14 15:23:44.772 screenresolution[1678:10499] Display 0: 1600x1200x32@60 15:23:44 INFO - 2016-05-14 15:23:44.778 screenresolution[1679:10502] starting screenresolution argv=screenresolution list 15:23:44 INFO - Available Modes on Display 0 15:23:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:23:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:23:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:23:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:23:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:23:45 INFO - Intel Iris: 15:23:45 INFO - Chipset Model: Intel Iris 15:23:45 INFO - Type: GPU 15:23:45 INFO - Bus: Built-In 15:23:45 INFO - VRAM (Dynamic, Max): 1536 MB 15:23:45 INFO - Vendor: Intel (0x8086) 15:23:45 INFO - Device ID: 0x0a2e 15:23:45 INFO - Revision ID: 0x0009 15:23:45 INFO - Displays: 15:23:45 INFO - Display: 15:23:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:23:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:23:45 INFO - Display Serial Number: 1600x1200 60 15:23:45 INFO - Main Display: Yes 15:23:45 INFO - Mirror: Off 15:23:45 INFO - Online: Yes 15:23:45 INFO - Rotation: Supported 15:23:45 INFO - Return code: 0 15:23:45 INFO - Running post-action listener: _resource_record_post_action 15:23:45 INFO - ##### 15:23:45 INFO - ##### Finished run-tests step (success) 15:23:45 INFO - ##### 15:23:45 INFO - Running post-run listener: _resource_record_post_run 15:23:45 INFO - Total resource usage - Wall time: 1215s; CPU: 54.0%; Read bytes: 32640512; Write bytes: 695988736; Read time: 324; Write time: 2054 15:23:45 INFO - TinderboxPrint: CPU usage
53.7% 15:23:45 INFO - TinderboxPrint: I/O read bytes / time
32,640,512 / 324 15:23:45 INFO - TinderboxPrint: I/O write bytes / time
695,988,736 / 2,054 15:23:45 INFO - TinderboxPrint: CPU idle
2,251.6 (46.3%) 15:23:45 INFO - TinderboxPrint: CPU system
969.3 (19.9%) 15:23:45 INFO - TinderboxPrint: CPU user
1,640.8 (33.7%) 15:23:45 INFO - TinderboxPrint: Swap in / out
423,800,832 / 0 15:23:45 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 386667008; Write bytes: 378058240; Read time: 29463; Write time: 696 15:23:45 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 30866944; Write bytes: 297096704; Read time: 306; Write time: 1305 15:23:45 INFO - Running post-run listener: _upload_blobber_files 15:23:45 INFO - Blob upload gear active. 15:23:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:23:45 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 15:23:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:23:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:23:45 INFO - (blobuploader) - INFO - Open directory for files ... 15:23:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:23:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:23:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:23:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:23:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:23:47 INFO - (blobuploader) - INFO - Done attempting. 15:23:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:23:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:23:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:23:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:23:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:23:47 INFO - (blobuploader) - INFO - Done attempting. 15:23:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:23:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:23:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:23:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:23:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:23:50 INFO - (blobuploader) - INFO - Done attempting. 15:23:50 INFO - (blobuploader) - INFO - Iteration through files over. 15:23:50 INFO - Return code: 0 15:23:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:23:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:23:50 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2da1a2c7278552079995fd00aa0199193daf48ec4ba188cef9d2fccf103c0ddf0579ec4bad64b37d858af160557675021b476a14af637338f5fbdfbdd022e14c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aa9629c73e0d90cda959f3c63e646e66e73ebab509e3ca77e32182b71879b7f93b6249aa2bd18b8b764be2a46821479f496aa5a08fe60b4d4c7aa3158e1541f8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/175a5d7d1cfb6b877ef06bd8eacfdc747b3f1dcffa8fe219d98a556b892f551f62bafef4accfcc109d01f6c7d692c83ef194efb258a9ef8a7d2dc2606a063c07"} 15:23:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:23:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:23:50 INFO - Contents: 15:23:50 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2da1a2c7278552079995fd00aa0199193daf48ec4ba188cef9d2fccf103c0ddf0579ec4bad64b37d858af160557675021b476a14af637338f5fbdfbdd022e14c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aa9629c73e0d90cda959f3c63e646e66e73ebab509e3ca77e32182b71879b7f93b6249aa2bd18b8b764be2a46821479f496aa5a08fe60b4d4c7aa3158e1541f8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/175a5d7d1cfb6b877ef06bd8eacfdc747b3f1dcffa8fe219d98a556b892f551f62bafef4accfcc109d01f6c7d692c83ef194efb258a9ef8a7d2dc2606a063c07"} 15:23:50 INFO - Running post-run listener: copy_logs_to_upload_dir 15:23:50 INFO - Copying logs to upload dir... 15:23:50 INFO - mkdir: /builds/slave/test/build/upload/logs 15:23:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1288.506135 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 28 secs) (at 2016-05-14 15:23:50.386476) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-14 15:23:50.389558) ========= 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.ksrh0O08c0/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.7komNO0YGo/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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/2da1a2c7278552079995fd00aa0199193daf48ec4ba188cef9d2fccf103c0ddf0579ec4bad64b37d858af160557675021b476a14af637338f5fbdfbdd022e14c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aa9629c73e0d90cda959f3c63e646e66e73ebab509e3ca77e32182b71879b7f93b6249aa2bd18b8b764be2a46821479f496aa5a08fe60b4d4c7aa3158e1541f8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/175a5d7d1cfb6b877ef06bd8eacfdc747b3f1dcffa8fe219d98a556b892f551f62bafef4accfcc109d01f6c7d692c83ef194efb258a9ef8a7d2dc2606a063c07"} build_url:https://queue.taskcluster.net/v1/task/Za5LwrsWRoCGj3_UbcTPBQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010885 build_url: 'https://queue.taskcluster.net/v1/task/Za5LwrsWRoCGj3_UbcTPBQ/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/2da1a2c7278552079995fd00aa0199193daf48ec4ba188cef9d2fccf103c0ddf0579ec4bad64b37d858af160557675021b476a14af637338f5fbdfbdd022e14c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aa9629c73e0d90cda959f3c63e646e66e73ebab509e3ca77e32182b71879b7f93b6249aa2bd18b8b764be2a46821479f496aa5a08fe60b4d4c7aa3158e1541f8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/175a5d7d1cfb6b877ef06bd8eacfdc747b3f1dcffa8fe219d98a556b892f551f62bafef4accfcc109d01f6c7d692c83ef194efb258a9ef8a7d2dc2606a063c07"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-14 15:23:50.417155) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 15:23:50.417475) ========= 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.ksrh0O08c0/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.7komNO0YGo/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005014 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 15:23:50.465732) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-14 15:23:50.466022) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-14 15:23:50.466324) ========= ========= Total master_lag: 0.18 =========