builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0220 starttime: 1463171638.54 results: success (0) buildid: 20160513122849 builduid: 186f3523e5a745a8ab44d820f92fc8f2 revision: b1ff014a973c1a1d5218cf0be7034f49f2dd537c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 13:33:58.539221) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 13:33:58.539649) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 13:33:58.539915) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 13:33:58.623485) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 13:33:58.623785) ========= 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.3K4LCjtvoO/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.LvwqFJqsRB/Listeners TMPDIR=/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-13 13:33:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 36.4M=0s 2016-05-13 13:33:58 (36.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.147900 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 13:33:58.790911) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 13:33:58.791321) ========= 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.3K4LCjtvoO/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.LvwqFJqsRB/Listeners TMPDIR=/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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.018966 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 13:33:58.844994) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 13:33:58.845713) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev b1ff014a973c1a1d5218cf0be7034f49f2dd537c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev b1ff014a973c1a1d5218cf0be7034f49f2dd537c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3K4LCjtvoO/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.LvwqFJqsRB/Listeners TMPDIR=/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-13 13:33:58,931 truncating revision to first 12 chars 2016-05-13 13:33:58,931 Setting DEBUG logging. 2016-05-13 13:33:58,931 attempt 1/10 2016-05-13 13:33:58,931 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/b1ff014a973c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-13 13:33:59,575 unpacking tar archive at: fx-team-b1ff014a973c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.902246 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 13:33:59.766315) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 13:33:59.766626) ========= script_repo_revision: b1ff014a973c1a1d5218cf0be7034f49f2dd537c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 13:33:59.766992) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 13:33:59.767249) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 13:33:59.783883) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2016-05-13 13:33:59.784306) ========= /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.3K4LCjtvoO/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.LvwqFJqsRB/Listeners TMPDIR=/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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 13:33:59 INFO - MultiFileLogger online at 20160513 13:33:59 in /builds/slave/test 13:33:59 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 13:33:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:33:59 INFO - {'append_to_log': False, 13:33:59 INFO - 'base_work_dir': '/builds/slave/test', 13:33:59 INFO - 'blob_upload_branch': 'Fx-Team', 13:33:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:33:59 INFO - 'branch': 'Fx-Team', 13:33:59 INFO - 'buildbot_json_path': 'buildprops.json', 13:33:59 INFO - 'config_files': ('talos/mac_config.py',), 13:33:59 INFO - 'default_actions': ('clobber', 13:33:59 INFO - 'read-buildbot-config', 13:33:59 INFO - 'download-and-extract', 13:33:59 INFO - 'populate-webroot', 13:33:59 INFO - 'create-virtualenv', 13:33:59 INFO - 'install', 13:33:59 INFO - 'run-tests'), 13:33:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:33:59 INFO - 'download_minidump_stackwalk': True, 13:33:59 INFO - 'download_symbols': 'ondemand', 13:33:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:33:59 INFO - 'tooltool.py': '/tools/tooltool.py', 13:33:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:33:59 INFO - '/tools/misc-python/virtualenv.py')}, 13:33:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:33:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:33:59 INFO - 'installer_path': 'installer.exe', 13:33:59 INFO - 'log_level': 'info', 13:33:59 INFO - 'log_name': 'talos', 13:33:59 INFO - 'log_to_console': True, 13:33:59 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:33:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:33:59 INFO - 'opt_config_files': (), 13:33:59 INFO - 'pip_index': False, 13:33:59 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:33:59 INFO - 'cmd': ('bash', 13:33:59 INFO - '-c', 13:33:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:33:59 INFO - 'enabled': True, 13:33:59 INFO - 'halt_on_failure': False, 13:33:59 INFO - 'name': 'check_screen_resolution'},), 13:33:59 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:33:59 INFO - 'cmd': ('bash', 13:33:59 INFO - '-c', 13:33:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:33:59 INFO - 'enabled': True, 13:33:59 INFO - 'halt_on_failure': False, 13:33:59 INFO - 'name': 'check_screen_resolution'},), 13:33:59 INFO - 'run_cmd_checks_enabled': True, 13:33:59 INFO - 'sps_profile': False, 13:33:59 INFO - 'sps_profile_interval': 0, 13:33:59 INFO - 'suite': 'dromaeojs', 13:33:59 INFO - 'system_bits': '32', 13:33:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:33:59 INFO - 'title': 't-yosemite-r7-0220', 13:33:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:33:59 INFO - 'use_talos_json': True, 13:33:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:33:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:33:59 INFO - 'work_dir': 'build'} 13:33:59 INFO - ##### 13:33:59 INFO - ##### Running clobber step. 13:33:59 INFO - ##### 13:33:59 INFO - Running pre-action listener: _resource_record_pre_action 13:33:59 INFO - Running main action method: clobber 13:33:59 INFO - rmtree: /builds/slave/test/build 13:33:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:34:01 INFO - Running post-action listener: _resource_record_post_action 13:34:01 INFO - ##### 13:34:01 INFO - ##### Finished clobber step (success) 13:34:01 INFO - ##### 13:34:01 INFO - ##### 13:34:01 INFO - ##### Running read-buildbot-config step. 13:34:01 INFO - ##### 13:34:01 INFO - Running pre-action listener: _resource_record_pre_action 13:34:01 INFO - Running main action method: read_buildbot_config 13:34:01 INFO - Using buildbot properties: 13:34:01 INFO - { 13:34:01 INFO - "project": "", 13:34:01 INFO - "product": "firefox", 13:34:01 INFO - "script_repo_revision": "production", 13:34:01 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 13:34:01 INFO - "repository": "", 13:34:01 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 13:34:01 INFO - "buildid": "20160513122849", 13:34:01 INFO - "pgo_build": "False", 13:34:01 INFO - "basedir": "/builds/slave/test", 13:34:01 INFO - "buildnumber": 9, 13:34:01 INFO - "slavename": "t-yosemite-r7-0220", 13:34:01 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 13:34:01 INFO - "platform": "yosemite_r7", 13:34:01 INFO - "scheduler": "tests-fx-team-macosx64-talos", 13:34:01 INFO - "branch": "fx-team", 13:34:01 INFO - "repo_path": "integration/fx-team", 13:34:01 INFO - "revision": "b1ff014a973c1a1d5218cf0be7034f49f2dd537c", 13:34:01 INFO - "stage_platform": "macosx64", 13:34:01 INFO - "builduid": "186f3523e5a745a8ab44d820f92fc8f2", 13:34:01 INFO - "slavebuilddir": "test" 13:34:01 INFO - } 13:34:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 13:34:01 INFO - Running post-action listener: _resource_record_post_action 13:34:01 INFO - ##### 13:34:01 INFO - ##### Finished read-buildbot-config step (success) 13:34:01 INFO - ##### 13:34:01 INFO - ##### 13:34:01 INFO - ##### Running download-and-extract step. 13:34:01 INFO - ##### 13:34:01 INFO - Running pre-action listener: _resource_record_pre_action 13:34:01 INFO - Running main action method: download_and_extract 13:34:01 INFO - mkdir: /builds/slave/test/build/tests 13:34:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:34:01 INFO - https://queue.taskcluster.net/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 13:34:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 13:34:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 13:34:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 13:34:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 13:34:02 INFO - Downloaded 1199 bytes. 13:34:02 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 13:34:02 INFO - Using the following test package requirements: 13:34:02 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 13:34:02 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:34:02 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 13:34:02 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:34:02 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 13:34:02 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:34:02 INFO - u'jsshell-mac.zip'], 13:34:02 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:34:02 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 13:34:02 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 13:34:02 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:34:02 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 13:34:02 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:34:02 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 13:34:02 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:34:02 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 13:34:02 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:34:02 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 13:34:02 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:34:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:34:02 INFO - https://queue.taskcluster.net/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:34:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:34:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:34:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:34:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:34:04 INFO - Downloaded 18153778 bytes. 13:34:04 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'] 13:34:04 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 13:34:07 INFO - Return code: 0 13:34:07 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 13:34:07 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')]} 13:34:07 INFO - https://queue.taskcluster.net/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:34:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:34:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:34:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:34:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:34:13 INFO - Downloaded 18153778 bytes. 13:34:13 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'] 13:34:13 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 13:34:16 INFO - Return code: 0 13:34:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:34:16 INFO - https://queue.taskcluster.net/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 13:34:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 13:34:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 13:34:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 13:34:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 13:34:26 INFO - Downloaded 10840239 bytes. 13:34:26 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'] 13:34:26 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 13:34:26 INFO - Return code: 0 13:34: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')]} 13:34:26 INFO - https://queue.taskcluster.net/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 13:34:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:34:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:34:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 13:34:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 13:34:29 INFO - Downloaded 110072128 bytes. 13:34:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:34:29 INFO - mkdir: /builds/slave/test/properties 13:34:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:34:29 INFO - Writing to file /builds/slave/test/properties/build_url 13:34:29 INFO - Contents: 13:34:29 INFO - build_url:https://queue.taskcluster.net/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:34:30 INFO - Running post-action listener: _resource_record_post_action 13:34:30 INFO - Running post-action listener: set_extra_try_arguments 13:34:30 INFO - ##### 13:34:30 INFO - ##### Finished download-and-extract step (success) 13:34:30 INFO - ##### 13:34:30 INFO - ##### 13:34:30 INFO - ##### Running populate-webroot step. 13:34:30 INFO - ##### 13:34:30 INFO - Running pre-action listener: _resource_record_pre_action 13:34:30 INFO - Running main action method: populate_webroot 13:34:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:34:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:34:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:34:30 INFO - '--tppagecycles', 13:34:30 INFO - '7'], 13:34:30 INFO - 'tests': ['tsvgm']}}, 13:34:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:34:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:34:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:34:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:34:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:34:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:34:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:34:30 INFO - 'talos_options': ['--disable-e10s'], 13:34:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:34:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:34:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:34:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:34:30 INFO - 'talos_options': ['--disable-e10s'], 13:34:30 INFO - 'tests': ['damp', 'tps']}, 13:34:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:34:30 INFO - 'tests': ['damp', 'tps']}, 13:34:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:34:30 INFO - 'tests': ['dromaeo_dom']}, 13:34:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:34:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:34:30 INFO - 'tests': ['a11yr', 13:34:30 INFO - 'ts_paint', 13:34:30 INFO - 'tpaint', 13:34:30 INFO - 'sessionrestore', 13:34:30 INFO - 'sessionrestore_no_auto_restore', 13:34:30 INFO - 'tabpaint']}, 13:34:30 INFO - 'other-e10s': {'tests': ['a11yr', 13:34:30 INFO - 'ts_paint', 13:34:30 INFO - 'tpaint', 13:34:30 INFO - 'sessionrestore', 13:34:30 INFO - 'sessionrestore_no_auto_restore', 13:34:30 INFO - 'tabpaint']}, 13:34:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:34:30 INFO - 'ts_paint', 13:34:30 INFO - 'tpaint', 13:34:30 INFO - 'sessionrestore', 13:34:30 INFO - 'sessionrestore_no_auto_restore', 13:34:30 INFO - 'tabpaint']}, 13:34:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:34:30 INFO - 'ts_paint', 13:34:30 INFO - 'tpaint', 13:34:30 INFO - 'sessionrestore', 13:34:30 INFO - 'sessionrestore_no_auto_restore', 13:34:30 INFO - 'tabpaint']}, 13:34:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:34:30 INFO - 'tests': ['a11yr', 13:34:30 INFO - 'ts_paint', 13:34:30 INFO - 'tpaint', 13:34:30 INFO - 'sessionrestore', 13:34:30 INFO - 'sessionrestore_no_auto_restore', 13:34:30 INFO - 'tabpaint']}, 13:34:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:34:30 INFO - 'tests': ['a11yr', 13:34:30 INFO - 'ts_paint', 13:34:30 INFO - 'tpaint', 13:34:30 INFO - 'sessionrestore', 13:34:30 INFO - 'sessionrestore_no_auto_restore', 13:34:30 INFO - 'tabpaint']}, 13:34:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:34:30 INFO - 'tests': ['tsvgx', 13:34:30 INFO - 'tsvgr_opacity', 13:34:30 INFO - 'tart', 13:34:30 INFO - 'tscrollx', 13:34:30 INFO - 'cart']}, 13:34:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:34:30 INFO - 'tsvgr_opacity', 13:34:30 INFO - 'tart', 13:34:30 INFO - 'tscrollx', 13:34:30 INFO - 'cart']}, 13:34:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:34:30 INFO - 'talos_options': ['--disable-e10s'], 13:34:30 INFO - 'tests': ['tp5o']}, 13:34:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:34:30 INFO - 'tests': ['tp5o']}, 13:34:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:34:30 INFO - 'talos_options': ['--disable-e10s', 13:34:30 INFO - '--xperf_path', 13:34:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:34:30 INFO - 'tests': ['tp5n']}, 13:34:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:34:30 INFO - 'talos_options': ['--xperf_path', 13:34:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:34:30 INFO - 'tests': ['tp5n']}}, 13:34:30 INFO - 'talos.zip': {'path': '', 13:34:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:34:30 INFO - Running post-action listener: _resource_record_post_action 13:34:30 INFO - ##### 13:34:30 INFO - ##### Finished populate-webroot step (success) 13:34:30 INFO - ##### 13:34:30 INFO - ##### 13:34:30 INFO - ##### Running create-virtualenv step. 13:34:30 INFO - ##### 13:34:30 INFO - Running pre-action listener: _resource_record_pre_action 13:34:30 INFO - Running main action method: create_virtualenv 13:34:30 INFO - Creating virtualenv /builds/slave/test/build/venv 13:34:30 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 13:34:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:34:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:34:30 INFO - Using real prefix '/tools/python27' 13:34:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:34:31 INFO - Installing distribute.............................................................................................................................................................................................done. 13:34:34 INFO - Installing pip.................done. 13:34:34 INFO - Return code: 0 13:34:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:34:34 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')]} 13:34:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:34:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:34 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')]} 13:34:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:34:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:34 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 0x10674ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106752570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd563ca2e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1065f0d30>, '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 0x106414db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1065e7b20>, '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.3K4LCjtvoO/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.LvwqFJqsRB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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 13:34:34 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 13:34:34 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 13:34:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3K4LCjtvoO/Render', 13:34:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:34:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:34:34 INFO - 'HOME': '/Users/cltbld', 13:34:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:34:34 INFO - 'LOGNAME': 'cltbld', 13:34:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:34:34 INFO - 'MOZ_NO_REMOTE': '1', 13:34:34 INFO - 'NO_EM_RESTART': '1', 13:34:34 INFO - 'PAGER': '/bin/cat', 13:34:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:34:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:34:34 INFO - 'PWD': '/builds/slave/test', 13:34:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:34:34 INFO - 'SHELL': '/bin/bash', 13:34:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LvwqFJqsRB/Listeners', 13:34:34 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 13:34:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:34:34 INFO - 'USER': 'cltbld', 13:34:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:34:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:34:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:34:34 INFO - 'XPC_FLAGS': '0x0', 13:34:34 INFO - 'XPC_SERVICE_NAME': '0', 13:34:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:34:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:34:34 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 13:34:34 INFO - Cleaning up... 13:34:34 INFO - Return code: 0 13:34:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:34:34 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')]} 13:34:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:34:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:34 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')]} 13:34:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:34:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:34 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 0x10674ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106752570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd563ca2e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1065f0d30>, '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 0x106414db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1065e7b20>, '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.3K4LCjtvoO/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.LvwqFJqsRB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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 13:34:34 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 13:34:34 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 13:34:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3K4LCjtvoO/Render', 13:34:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:34:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:34:34 INFO - 'HOME': '/Users/cltbld', 13:34:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:34:34 INFO - 'LOGNAME': 'cltbld', 13:34:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:34:34 INFO - 'MOZ_NO_REMOTE': '1', 13:34:34 INFO - 'NO_EM_RESTART': '1', 13:34:34 INFO - 'PAGER': '/bin/cat', 13:34:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:34:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:34:34 INFO - 'PWD': '/builds/slave/test', 13:34:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:34:34 INFO - 'SHELL': '/bin/bash', 13:34:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LvwqFJqsRB/Listeners', 13:34:34 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 13:34:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:34:34 INFO - 'USER': 'cltbld', 13:34:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:34:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:34:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:34:34 INFO - 'XPC_FLAGS': '0x0', 13:34:34 INFO - 'XPC_SERVICE_NAME': '0', 13:34:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:34:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:34:34 INFO - Downloading/unpacking psutil>=3.1.1 13:34:34 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 13:34:34 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 13:34:34 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 13:34:34 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 13:34:34 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 13:34:34 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 13:34:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:34:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:34:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:34:38 INFO - Installing collected packages: psutil 13:34:38 INFO - Running setup.py install for psutil 13:34:38 INFO - building 'psutil._psutil_osx' extension 13:34:38 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 13:34:38 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 13:34:38 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 13:34:38 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 13:34:38 INFO - building 'psutil._psutil_posix' extension 13:34:38 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 13:34:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:34:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:34:38 INFO - ^ 13:34:38 INFO - 1 warning generated. 13:34:38 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 13:34:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:34:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:34:38 INFO - Successfully installed psutil 13:34:38 INFO - Cleaning up... 13:34:38 INFO - Return code: 0 13:34:38 INFO - Installing mozsystemmonitor==0.1 into virtualenv /builds/slave/test/build/venv 13:34:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:34:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:34:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:34:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:34:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10674ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106752570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd563ca2e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1065f0d30>, '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 0x106414db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1065e7b20>, '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.3K4LCjtvoO/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.LvwqFJqsRB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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 13:34:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.1'] in /builds/slave/test/build 13:34:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.1 13:34:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3K4LCjtvoO/Render', 13:34:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:34:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:34:38 INFO - 'HOME': '/Users/cltbld', 13:34:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:34:38 INFO - 'LOGNAME': 'cltbld', 13:34:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:34:38 INFO - 'MOZ_NO_REMOTE': '1', 13:34:38 INFO - 'NO_EM_RESTART': '1', 13:34:38 INFO - 'PAGER': '/bin/cat', 13:34:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:34:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:34:38 INFO - 'PWD': '/builds/slave/test', 13:34:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:34:38 INFO - 'SHELL': '/bin/bash', 13:34:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LvwqFJqsRB/Listeners', 13:34:38 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 13:34:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:34:38 INFO - 'USER': 'cltbld', 13:34:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:34:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:34:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:34:38 INFO - 'XPC_FLAGS': '0x0', 13:34:38 INFO - 'XPC_SERVICE_NAME': '0', 13:34:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:34:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:34:39 INFO - Downloading/unpacking mozsystemmonitor==0.1 13:34:39 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 13:34:39 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 13:34:39 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 13:34:39 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 13:34:39 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 13:34:39 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 13:34:41 INFO - Downloading mozsystemmonitor-0.1.tar.gz 13:34:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:34:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.1) 13:34:41 INFO - Installing collected packages: mozsystemmonitor 13:34:41 INFO - Running setup.py install for mozsystemmonitor 13:34:42 INFO - Successfully installed mozsystemmonitor 13:34:42 INFO - Cleaning up... 13:34:42 INFO - Return code: 0 13:34:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:34: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')]} 13:34:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:34:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34: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')]} 13:34:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:34:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:42 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 0x10674ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106752570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd563ca2e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1065f0d30>, '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 0x106414db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1065e7b20>, '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.3K4LCjtvoO/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.LvwqFJqsRB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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 13:34:42 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 13:34:42 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 13:34:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3K4LCjtvoO/Render', 13:34:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:34:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:34:42 INFO - 'HOME': '/Users/cltbld', 13:34:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:34:42 INFO - 'LOGNAME': 'cltbld', 13:34:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:34:42 INFO - 'MOZ_NO_REMOTE': '1', 13:34:42 INFO - 'NO_EM_RESTART': '1', 13:34:42 INFO - 'PAGER': '/bin/cat', 13:34:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:34:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:34:42 INFO - 'PWD': '/builds/slave/test', 13:34:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:34:42 INFO - 'SHELL': '/bin/bash', 13:34:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LvwqFJqsRB/Listeners', 13:34:42 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 13:34:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:34:42 INFO - 'USER': 'cltbld', 13:34:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:34:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:34:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:34:42 INFO - 'XPC_FLAGS': '0x0', 13:34:42 INFO - 'XPC_SERVICE_NAME': '0', 13:34:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:34:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:34:42 INFO - Downloading/unpacking blobuploader==1.2.4 13:34:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:34:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:34:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:34:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:34:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:34:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:34:44 INFO - Downloading blobuploader-1.2.4.tar.gz 13:34:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:34:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:34:44 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 13:34:44 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 13:34:44 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 13:34:44 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 13:34:44 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 13:34:44 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 13:34:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:34:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:34:45 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 13:34:45 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 13:34:45 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 13:34:45 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 13:34:45 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 13:34:45 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 13:34:45 INFO - Downloading docopt-0.6.1.tar.gz 13:34:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:34:46 INFO - Installing collected packages: blobuploader, requests, docopt 13:34:46 INFO - Running setup.py install for blobuploader 13:34:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:34:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:34:46 INFO - Running setup.py install for requests 13:34:46 INFO - Running setup.py install for docopt 13:34:46 INFO - Successfully installed blobuploader requests docopt 13:34:46 INFO - Cleaning up... 13:34:46 INFO - Return code: 0 13:34:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:34:46 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')]} 13:34:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:34:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:46 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')]} 13:34:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:34:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:46 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 0x10674ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106752570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd563ca2e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1065f0d30>, '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 0x106414db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1065e7b20>, '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.3K4LCjtvoO/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.LvwqFJqsRB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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 13:34:46 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 13:34:46 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 13:34:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3K4LCjtvoO/Render', 13:34:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:34:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:34:46 INFO - 'HOME': '/Users/cltbld', 13:34:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:34:46 INFO - 'LOGNAME': 'cltbld', 13:34:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:34:46 INFO - 'MOZ_NO_REMOTE': '1', 13:34:46 INFO - 'NO_EM_RESTART': '1', 13:34:46 INFO - 'PAGER': '/bin/cat', 13:34:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:34:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:34:46 INFO - 'PWD': '/builds/slave/test', 13:34:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:34:46 INFO - 'SHELL': '/bin/bash', 13:34:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LvwqFJqsRB/Listeners', 13:34:46 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 13:34:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:34:46 INFO - 'USER': 'cltbld', 13:34:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:34:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:34:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:34:46 INFO - 'XPC_FLAGS': '0x0', 13:34:46 INFO - 'XPC_SERVICE_NAME': '0', 13:34:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:34:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:34:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:34:47 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-6xkyrU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:34:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:34:47 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-b8S5j_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:34:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:34:47 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-njdUma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:34:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:34:47 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-WUIwYw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:34:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:34:47 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-DbYijn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:34:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:34:47 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-LLXL25-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:34:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:34:47 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-qI0kop-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:34:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:34:47 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-e2OIhi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:34:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:34:48 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-Y5bxKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:34:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:34:48 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-ceJS7b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:34:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:34:48 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-Egrnas-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:34:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:34:48 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-wSfQ6z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:34:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:34:48 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-uOIX9p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:34:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:34:48 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-e7pC4A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:34:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:34:48 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-lc2pNQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:34:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:34:48 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-KXURgw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:34:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:34:48 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-zvowyk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:34:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:34:48 INFO - Running setup.py install for manifestparser 13:34:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:34:49 INFO - Running setup.py install for mozcrash 13:34:49 INFO - Running setup.py install for mozdebug 13:34:49 INFO - Running setup.py install for mozdevice 13:34:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:34:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:34:49 INFO - Running setup.py install for mozfile 13:34:49 INFO - Running setup.py install for mozhttpd 13:34:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:34:49 INFO - Running setup.py install for mozinfo 13:34:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:34:49 INFO - Running setup.py install for mozInstall 13:34:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:34:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:34:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:34:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:34:50 INFO - Running setup.py install for mozleak 13:34:50 INFO - Running setup.py install for mozlog 13:34:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:34:50 INFO - Running setup.py install for moznetwork 13:34:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:34:50 INFO - Running setup.py install for mozprocess 13:34:50 INFO - Running setup.py install for mozprofile 13:34:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:34:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:34:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:34:50 INFO - Running setup.py install for mozrunner 13:34:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:34:51 INFO - Running setup.py install for mozscreenshot 13:34:51 INFO - Running setup.py install for moztest 13:34:51 INFO - Running setup.py install for mozversion 13:34:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:34:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:34:51 INFO - Cleaning up... 13:34:51 INFO - Return code: 0 13:34:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:34:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:34:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:34:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:34:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:34:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:51 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 0x10674ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106752570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd563ca2e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1065f0d30>, '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 0x106414db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1065e7b20>, '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.3K4LCjtvoO/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.LvwqFJqsRB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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 13:34:51 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 13:34:51 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 13:34:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3K4LCjtvoO/Render', 13:34:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:34:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:34:51 INFO - 'HOME': '/Users/cltbld', 13:34:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:34:51 INFO - 'LOGNAME': 'cltbld', 13:34:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:34:51 INFO - 'MOZ_NO_REMOTE': '1', 13:34:51 INFO - 'NO_EM_RESTART': '1', 13:34:51 INFO - 'PAGER': '/bin/cat', 13:34:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:34:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:34:51 INFO - 'PWD': '/builds/slave/test', 13:34:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:34:51 INFO - 'SHELL': '/bin/bash', 13:34:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LvwqFJqsRB/Listeners', 13:34:51 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 13:34:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:34:51 INFO - 'USER': 'cltbld', 13:34:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:34:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:34:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:34:51 INFO - 'XPC_FLAGS': '0x0', 13:34:51 INFO - 'XPC_SERVICE_NAME': '0', 13:34:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:34:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:34:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:34:51 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-wButYM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:34:51 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)) 13:34:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:34:51 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-Of8CcS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:34:52 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)) 13:34:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:34:52 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-D6Q2KC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:34:52 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)) 13:34:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:34:52 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-4uxwQl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:34:52 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)) 13:34:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:34:52 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-oNzVH_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:34:52 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)) 13:34:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:34:52 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-9JVjNB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:34:52 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)) 13:34:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:34:52 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-KujMXp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:34:52 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)) 13:34:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:34:52 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-y5lJFB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:34:52 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)) 13:34:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:34:52 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-ENiNb3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:34:52 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)) 13:34:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:34:52 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-EIQgvh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:34:52 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)) 13:34:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:34:52 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-6cL87H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:34:52 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)) 13:34:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:34:52 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-pVaATf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:34:53 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)) 13:34:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:34:53 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-2tBd44-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:34:53 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)) 13:34:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:34:53 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-mJk6H2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:34:53 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)) 13:34:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:34:53 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-8iGYTX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:34:53 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)) 13:34:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:34:53 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-3mMTMu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:34:53 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)) 13:34:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:34:53 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-T5TLTY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:34:53 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)) 13:34:53 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)) 13:34:53 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)) 13:34:53 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)) 13:34:53 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)) 13:34:53 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)) 13:34:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:34:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:34:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:34:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:34:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:34:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:34:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:34:55 INFO - Downloading blessings-1.6.tar.gz 13:34:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:34:56 INFO - Installing collected packages: blessings 13:34:56 INFO - Running setup.py install for blessings 13:34:56 INFO - Successfully installed blessings 13:34:56 INFO - Cleaning up... 13:34:56 INFO - Return code: 0 13:34:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:34:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:34:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:34:56 INFO - Reading from file tmpfile_stdout 13:34:56 INFO - Current package versions: 13:34:56 INFO - blessings == 1.6 13:34:56 INFO - blobuploader == 1.2.4 13:34:56 INFO - docopt == 0.6.1 13:34:56 INFO - manifestparser == 1.1 13:34:56 INFO - mozInstall == 1.12 13:34:56 INFO - mozcrash == 0.17 13:34:56 INFO - mozdebug == 0.1 13:34:56 INFO - mozdevice == 0.48 13:34:56 INFO - mozfile == 1.2 13:34:56 INFO - mozhttpd == 0.7 13:34:56 INFO - mozinfo == 0.9 13:34:56 INFO - mozleak == 0.1 13:34:56 INFO - mozlog == 3.2 13:34:56 INFO - moznetwork == 0.27 13:34:56 INFO - mozprocess == 0.22 13:34:56 INFO - mozprofile == 0.28 13:34:56 INFO - mozrunner == 6.11 13:34:56 INFO - mozscreenshot == 0.1 13:34:56 INFO - mozsystemmonitor == 0.1 13:34:56 INFO - moztest == 0.7 13:34:56 INFO - mozversion == 1.4 13:34:56 INFO - psutil == 3.1.1 13:34:56 INFO - requests == 1.2.3 13:34:56 INFO - wsgiref == 0.1.2 13:34:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:34:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:34:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:34:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:34:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:34:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:56 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 0x10674ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106752570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd563ca2e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1065f0d30>, '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 0x106414db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1065e7b20>, '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.3K4LCjtvoO/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.LvwqFJqsRB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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 13:34:56 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 13:34:56 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 13:34:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3K4LCjtvoO/Render', 13:34:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:34:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:34:56 INFO - 'HOME': '/Users/cltbld', 13:34:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:34:56 INFO - 'LOGNAME': 'cltbld', 13:34:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:34:56 INFO - 'MOZ_NO_REMOTE': '1', 13:34:56 INFO - 'NO_EM_RESTART': '1', 13:34:56 INFO - 'PAGER': '/bin/cat', 13:34:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:34:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:34:56 INFO - 'PWD': '/builds/slave/test', 13:34:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:34:56 INFO - 'SHELL': '/bin/bash', 13:34:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LvwqFJqsRB/Listeners', 13:34:56 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 13:34:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:34:56 INFO - 'USER': 'cltbld', 13:34:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:34:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:34:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:34:56 INFO - 'XPC_FLAGS': '0x0', 13:34:56 INFO - 'XPC_SERVICE_NAME': '0', 13:34:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:34:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:34:56 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)) 13:34:56 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)) 13:34:56 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)) 13:34:56 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)) 13:34:56 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)) 13:34:56 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)) 13:34:56 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)) 13:34:56 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)) 13:34:56 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)) 13:34:56 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)) 13:34:56 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)) 13:34:56 INFO - Cleaning up... 13:34:56 INFO - Return code: 0 13:34:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:34:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:34:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:34:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:34:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:34:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10674ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106752570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd563ca2e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1065f0d30>, '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 0x106414db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1065e7b20>, '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.3K4LCjtvoO/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.LvwqFJqsRB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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 13:34:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 13:34:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 13:34:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3K4LCjtvoO/Render', 13:34:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:34:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:34:56 INFO - 'HOME': '/Users/cltbld', 13:34:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:34:56 INFO - 'LOGNAME': 'cltbld', 13:34:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:34:56 INFO - 'MOZ_NO_REMOTE': '1', 13:34:56 INFO - 'NO_EM_RESTART': '1', 13:34:56 INFO - 'PAGER': '/bin/cat', 13:34:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:34:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:34:56 INFO - 'PWD': '/builds/slave/test', 13:34:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:34:56 INFO - 'SHELL': '/bin/bash', 13:34:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LvwqFJqsRB/Listeners', 13:34:56 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 13:34:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:34:56 INFO - 'USER': 'cltbld', 13:34:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:34:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:34:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:34:56 INFO - 'XPC_FLAGS': '0x0', 13:34:56 INFO - 'XPC_SERVICE_NAME': '0', 13:34:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:34:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:34:57 INFO - Downloading/unpacking jsonschema 13:34:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:34:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:34:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:34:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:34:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:34:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:34:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:34:59 INFO - Downloading/unpacking functools32 (from jsonschema) 13:34: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 13:34: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 13:34: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 13:34: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 13:34: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 13:34: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 13:34:59 INFO - Downloading functools32-3.2.3-2.tar.gz 13:34:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:35:00 INFO - warning: no files found matching '*.txt' 13:35:00 INFO - no previously-included directories found matching 'build' 13:35:00 INFO - no previously-included directories found matching 'dist' 13:35:00 INFO - no previously-included directories found matching '.git*' 13:35:00 INFO - Installing collected packages: jsonschema, functools32 13:35:00 INFO - Running setup.py install for functools32 13:35:00 INFO - warning: no files found matching '*.txt' 13:35:00 INFO - no previously-included directories found matching 'build' 13:35:00 INFO - no previously-included directories found matching 'dist' 13:35:00 INFO - no previously-included directories found matching '.git*' 13:35:00 INFO - Successfully installed jsonschema functools32 13:35:00 INFO - Cleaning up... 13:35:00 INFO - Return code: 0 13:35:00 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 13:35:00 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')]} 13:35:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:35:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:35:00 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')]} 13:35:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:35:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:35:00 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 0x10674ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106752570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd563ca2e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1065f0d30>, '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 0x106414db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1065e7b20>, '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.3K4LCjtvoO/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.LvwqFJqsRB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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 13:35:00 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 13:35:00 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 13:35:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3K4LCjtvoO/Render', 13:35:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:35:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:35:00 INFO - 'HOME': '/Users/cltbld', 13:35:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:35:00 INFO - 'LOGNAME': 'cltbld', 13:35:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:35:00 INFO - 'MOZ_NO_REMOTE': '1', 13:35:00 INFO - 'NO_EM_RESTART': '1', 13:35:00 INFO - 'PAGER': '/bin/cat', 13:35:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:35:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:35:00 INFO - 'PWD': '/builds/slave/test', 13:35:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:35:00 INFO - 'SHELL': '/bin/bash', 13:35:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LvwqFJqsRB/Listeners', 13:35:00 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 13:35:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:35:00 INFO - 'USER': 'cltbld', 13:35:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:35:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:35:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:35:00 INFO - 'XPC_FLAGS': '0x0', 13:35:00 INFO - 'XPC_SERVICE_NAME': '0', 13:35:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:35:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:35:00 INFO - Downloading/unpacking flake8 13:35:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:35:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:35:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:35:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:35:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:35:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:35:03 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 13:35:03 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 13:35: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 13:35: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 13:35: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 13:35: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 13:35: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 13:35: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 13:35:03 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 13:35:03 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 13:35: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 13:35: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 13:35: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 13:35: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 13:35: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 13:35: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 13:35:03 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 13:35: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 13:35: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 13:35: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 13:35: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 13:35: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 13:35: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 13:35:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 13:35:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:35:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:35:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:35:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:35:04 INFO - no previously-included directories found matching 'docs/_build' 13:35:04 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 13:35:04 INFO - Running setup.py install for pep8 13:35:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:35:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:35:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:35:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:35:04 INFO - no previously-included directories found matching 'docs/_build' 13:35:04 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 13:35:04 INFO - Successfully installed flake8 mccabe pyflakes pep8 13:35:04 INFO - Cleaning up... 13:35:04 INFO - Return code: 0 13:35:04 INFO - Running post-action listener: _resource_record_post_action 13:35:04 INFO - Running post-action listener: _start_resource_monitoring 13:35:04 INFO - Starting resource monitoring. 13:35:04 INFO - ##### 13:35:04 INFO - ##### Finished create-virtualenv step (success) 13:35:04 INFO - ##### 13:35:04 INFO - ##### 13:35:04 INFO - ##### Running install step. 13:35:04 INFO - ##### 13:35:04 INFO - Running pre-action listener: _resource_record_pre_action 13:35:04 INFO - Running main action method: install 13:35:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:35:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:35:05 INFO - Reading from file tmpfile_stdout 13:35:05 INFO - Detecting whether we're running mozinstall >=1.0... 13:35:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:35:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:35:05 INFO - Reading from file tmpfile_stdout 13:35:05 INFO - Output received: 13:35:05 INFO - Usage: mozinstall [options] installer 13:35:05 INFO - Options: 13:35:05 INFO - -h, --help show this help message and exit 13:35:05 INFO - -d DEST, --destination=DEST 13:35:05 INFO - Directory to install application into. [default: 13:35:05 INFO - "/builds/slave/test"] 13:35:05 INFO - --app=APP Application being installed. [default: firefox] 13:35:05 INFO - mkdir: /builds/slave/test/build/application 13:35:05 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'] 13:35:05 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 13:35:38 INFO - Reading from file tmpfile_stdout 13:35:38 INFO - Output received: 13:35:38 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 13:35:38 INFO - Running post-action listener: _resource_record_post_action 13:35:38 INFO - ##### 13:35:38 INFO - ##### Finished install step (success) 13:35:38 INFO - ##### 13:35:38 INFO - ##### 13:35:38 INFO - ##### Running run-tests step. 13:35:38 INFO - ##### 13:35:38 INFO - Running pre-action listener: _resource_record_pre_action 13:35:38 INFO - Running main action method: run_tests 13:35:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:35:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:35:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:35:38 INFO - 2016-05-13 13:35:38.782 screenresolution[1919:14072] starting screenresolution argv=screenresolution get 13:35:38 INFO - 2016-05-13 13:35:38.785 screenresolution[1919:14072] Display 0: 1600x1200x32@60 13:35:38 INFO - 2016-05-13 13:35:38.792 screenresolution[1920:14074] starting screenresolution argv=screenresolution list 13:35:38 INFO - Available Modes on Display 0 13:35:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:35:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:35:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:35:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:35:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:35:39 INFO - Intel Iris: 13:35:39 INFO - Chipset Model: Intel Iris 13:35:39 INFO - Type: GPU 13:35:39 INFO - Bus: Built-In 13:35:39 INFO - VRAM (Dynamic, Max): 1536 MB 13:35:39 INFO - Vendor: Intel (0x8086) 13:35:39 INFO - Device ID: 0x0a2e 13:35:39 INFO - Revision ID: 0x0009 13:35:39 INFO - Displays: 13:35:39 INFO - Display: 13:35:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:35:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:35:39 INFO - Display Serial Number: 1600x1200 60 13:35:39 INFO - Main Display: Yes 13:35:39 INFO - Mirror: Off 13:35:39 INFO - Online: Yes 13:35:39 INFO - Rotation: Supported 13:35:39 INFO - Return code: 0 13:35:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:35:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:35:39 INFO - Python 2.7.3 13:35:39 INFO - Return code: 0 13:35:39 INFO - grabbing minidump binary from tooltool 13:35:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:35:39 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 0x1065f0d30>, '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 0x106414db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1065e7b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:35:39 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 13:35:39 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 13:35:39 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 13:35:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:35:39 INFO - Return code: 0 13:35:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:35:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:35:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:35:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:35:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:35:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 13:35:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 13:35:40 INFO - Return code: 0 13:35:40 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/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0220', '--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 13:35:40 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/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0220 --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 13:35:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3K4LCjtvoO/Render', 13:35:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:35:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:35:40 INFO - 'HOME': '/Users/cltbld', 13:35:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:35:40 INFO - 'LOGNAME': 'cltbld', 13:35:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:35:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:35:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:35:40 INFO - 'MOZ_NO_REMOTE': '1', 13:35:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:35:40 INFO - 'NO_EM_RESTART': '1', 13:35:40 INFO - 'PAGER': '/bin/cat', 13:35:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:35:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:35:40 INFO - 'PWD': '/builds/slave/test', 13:35:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:35:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:35:40 INFO - 'SHELL': '/bin/bash', 13:35:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LvwqFJqsRB/Listeners', 13:35:40 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 13:35:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:35:40 INFO - 'USER': 'cltbld', 13:35:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:35:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:35:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:35:40 INFO - 'XPC_FLAGS': '0x0', 13:35:40 INFO - 'XPC_SERVICE_NAME': '0', 13:35:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:35:40 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/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0220', '--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 13:35:40 INFO - mozversion application_buildid: 20160513122849 13:35:40 INFO - mozversion application_changeset: b1ff014a973c1a1d5218cf0be7034f49f2dd537c 13:35:40 INFO - mozversion application_display_name: Nightly 13:35:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:35:40 INFO - mozversion application_name: Firefox 13:35:40 INFO - mozversion application_remotingname: firefox 13:35:40 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 13:35:40 INFO - mozversion application_vendor: Mozilla 13:35:40 INFO - mozversion application_version: 49.0a1 13:35:40 INFO - mozversion platform_buildid: 20160513122849 13:35:40 INFO - mozversion platform_changeset: b1ff014a973c1a1d5218cf0be7034f49f2dd537c 13:35:40 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 13:35:40 INFO - mozversion platform_version: 49.0a1 13:35:40 INFO - using testdate: 1463171740 13:35:40 INFO - actual date: 1463171740 13:35:40 INFO - starting webserver on 'localhost:50146' 13:35:40 INFO - SUITE-START | Running 2 tests 13:35:40 INFO - TEST-START | dromaeo_css 13:35:40 INFO - operating with platform_type : mac_ 13:35:40 INFO - Initialising browser for dromaeo_css test... 13:35:40 INFO - TEST-INFO | started process 1938 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/tmp4RRC5I/profile http://localhost:50146/getInfo.html) 13:35:42 INFO - PROCESS | 1938 | __metrics Screen width/height:1600/1200 13:35:42 INFO - PROCESS | 1938 | colorDepth:24 13:35:42 INFO - PROCESS | 1938 | Browser inner width/height: 1024/689 13:35:42 INFO - PROCESS | 1938 | __metrics 13:35:42 INFO - PROCESS | 1938 | 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" 13:35:42 ERROR - PROCESS | 1938 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 13:35:43 INFO - TEST-INFO | 1938: exit 0 13:35:43 INFO - Browser initialized. 13:35:43 INFO - Running cycle 1/1 for dromaeo_css test... 13:35:43 INFO - TEST-INFO | started process 1940 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/tmp4RRC5I/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:35:55 INFO - PROCESS | 1940 | RSS: Main: 238972928 13:35:55 INFO - PROCESS | 1940 | 13:36:46 INFO - PROCESS | 1940 | 1463171806483 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] 13:38:57 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:50146/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50146/tests/dromaeo/cssquery-ext.html) 13:38:58 INFO - PROCESS | 1940 | RSS: Main: 372576256 13:38:58 INFO - PROCESS | 1940 | 13:42:01 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:50146/tests/dromaeo/cssquery-ext.html (next: http://localhost:50146/tests/dromaeo/cssquery-jquery.html) 13:42:02 INFO - PROCESS | 1940 | RSS: Main: 370360320 13:42:02 INFO - PROCESS | 1940 | 13:45:03 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:50146/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50146/tests/dromaeo/cssquery-mootools.html) 13:45:04 INFO - PROCESS | 1940 | RSS: Main: 372801536 13:45:04 INFO - PROCESS | 1940 | 13:48:06 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:50146/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50146/tests/dromaeo/cssquery-prototype.html) 13:48:07 INFO - PROCESS | 1940 | RSS: Main: 369438720 13:48:07 INFO - PROCESS | 1940 | 13:51:10 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:50146/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50146/tests/dromaeo/cssquery-yui.html) 13:51:11 INFO - PROCESS | 1940 | RSS: Main: 333815808 13:51:11 INFO - PROCESS | 1940 | 13:54:14 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:50146/tests/dromaeo/cssquery-yui.html (next: http://localhost:50146/tests/dromaeo/cssquery-dojo.html) 13:54:14 INFO - PROCESS | 1940 | RSS: Main: 389066752 13:54:14 INFO - PROCESS | 1940 | 13:54:14 INFO - PROCESS | 1940 | __start_tp_report 13:54:14 INFO - PROCESS | 1940 | _x_x_mozilla_page_load 13:54:14 INFO - PROCESS | 1940 | _x_x_mozilla_page_load_details 13:54:14 INFO - PROCESS | 1940 | |i|pagename|runs| 13:54:14 INFO - PROCESS | 1940 | |0;dojo.html;3406.097384193189;3421.2644281479484;3430.6397828228037;3450.654934506548;3490.2670439207764;5617.410171931944;5668.971655141729;5706.258186435757;5756.654600723954;5782.681952492611;4443.755593442352;5672.801451949183;5771.134329850514;5782;5796.62321949073;5073;5111.539961403473;5156.742162891859;5183.507566781169;5255.054090263751;5243.449437809032;5267.841964741045;5289.206619007141;5302.204669299616;5337;5101.15830887903;5135.666181698178;5137.999999999981;5140.742962851842;5146.459621739701;5157.4584668610005;5190.688558686491;5195.454477279907;5200.401953775288;5207.114790485626;5091.490850914916;5103.004914041763;5111.284420181178;5138.30632864566;5153.201253805629;4667.649926255511;5611.607187496877;5679.971600142014;5731.996900542388;5734.913976290359;6142.846428839315;6168.136460895478;6273.8745225095245;6275.497960163176;6284.497240220771;6265.185525881652;6272.341404152544;6273.12176295319;6309.779157729503;6319.715612797435;5837.445442682938;5847.707614619337;5850.531957443394;5852.356240813507;5914.497267732248;6134.7121296814075;6213.316535181127;6251.531135164881;6273.937260627426;6280.937190628035;6202.44178023971;6210.347913469111;6213.2233470816145;6223.626582405071;6227.626342419469;6291.370862913764;6310.873782524323;6317.96841015792;6324.367563243639;6326.145970293951;6091.847703807441;6103.034636161374;6107.46191531438;6125.388069147376;6216.968915155395;6158.261008678897;6187.443130118311;6222.782202622977;6235.282942461584;6237.095621135;4897.975510122498;4902.240152776325;4906.852794416173;4913.754312284443;4940.184869496565;4441.977790111029;4444.866654000385;4457.286834106528;4483.887902802391;4528.592426681615;5580.441955804387;5664.6034777566;5670.8015219467125;5751.913721294184;5769.249997500382;6215.875682486325;6279.183706118175;6289.119523266746;6295.811125666238;6298.02380631003;5775.971120144456;5786.07422812348;5787.594868359174;5791.797287095016;5808.94191058084;5341.679499230058;5390.622656414092;5408.810691625774;5413.323334583177;5413.350397952271;6233.4389904909685;6251.4061164189325;6275.560710750295;6280.874382512233;6314.715837787218;6292.842678933063;6301.905471417841;6323.557350985387;6329.430351268408;6329.810105696836;6295.087212354091;6327.525435592441;6327.557071004985;6339.524535659752;6353.428191462791;6075.453209211192;6114.66235542843;6146.6170111724505;6192.380761923771;6267.561270711189;6273.404026617464;6330.841728956813;6334.714937827716;6351.904721429275;6361.109444677656;6005.7297421617;6011.519078473799;6152.969235153795;6159.983602705683;6165.907511387422;6012.218411606463;6051.667158306335;6089.964705999902;6158.230221222348;6159.045347971071;4946.3817022872145;5931.851703707442;5943.791967281124;5954.434328738763;5994.940050599438;5892.823215303548;6049.637021778707;6135.509159267337;6140.223762386189;6142.78500252489;5922.111683247547;6026.487748541423;6046.697665116814;6047.425494578008;6047.606905551125 13:54:14 INFO - PROCESS | 1940 | |1;ext.html;492.9778159982803;513.4762542206952;518.2951186386515;522.9372475302964;539.2612121393691;30529.236769080755;31270.530942035886;41997.580024199786;42594.57405425945;42680.57319426805;36528.63471365286;44163.77918110405;44201;44230;44284.892877677994;9616.18262447691;9789;9791.804163916717;9796.167325777318;9807.558659860288;9543.618255269781;9652;9683.709488715349;9685.176759975375;9688.224942004623;9690.224782017456;9694;9696.515174241295;9732.95133524335;9782.804343913118;9639.759006024871;9653.855192172123;9670.9516452418;9674.419534827934;9715.36850104741;8941.6870409536;9117.22503587196;9534.28492863038;9639.132478076937;9645.035496450299;40303.19393612141;40571.391429128584;40613.59386406127;40633.79683101595;40704;2737.397772490049;3008.8796448142048;3162.414953233659;3462.9307413851834;3469.8959031229106;24120.396990075387;28779.712202877912;28943.855280723674;29604.55593166104;30175.547366789517;2406.3743426709007;2617;2736.261209473451;2741.7258274172623;2783.178962206154;382123;384042;384578;384767.0761646202;385250;34910.476342854876;35777.1055723609;59324.44053356812;60544.69727651333;60771;36869.446958295644;36899.26201475955;36965;37048.591841530215;37061.44407833885;14946.925265373604;15136.54590362291;15140.848591514165;15141.621459463602;15142.697146057237;10953.56185752561;12708.809367859489;12850.10049296559;12859.357032148542;12867.806982895265;7861.449698521049;8001.359891208689;8025.5585942773705;8081.555514446762;8095.392845536609;3450.9827450863086;3454.481827725811;3474.5309383233443;3474.947875781865;3503.597086335053;876.3164731509432;998.1017084623896;1016.7712264740522;1037.4034929915329;1109.290054365207;25967.870160649454;27948.02181923623;29386.55920161199;29462.705372945995;30367.69632303693;923.819855128257;927.5547737086237;946.7822400847843;1132.1112926352773;1165.0272022861006;15418.229088545751;15448.613784655474;15552.766708499381;15777.322214039757;15935.442259520862;643373.6144512097;644619;646352;647902;649405;60142.69928650329;60701.69649151726;60764;60782.39217607856;60854;1280.9743805123846;1300.6618279247275;1339.6115126613172;1484.9382691375718;1521.4674863797804;28576;28676.13971580856;30418.543721843747;30439.239019024702;30974.38051239008;49871.75064124656;50375.49624503855;50377.74811126068;50458.49541504538;50559.74720126376;414824;415326;417765;418368;419360;26618.334541636614;27197.72802271952;28156.577651335658;28172.43655126927;28872.855635721688;45534.54465455303;46272.8431789208;46539.53460465487;47349.28976065293;47652.04695905993;36777.448338275484;36904.81547592245;38185.80907095447;38971.83084507469;39254.60745392624;31170;37990.24019519539;38150.809245954704;38160.80919595384;38492.23015539617;37295.06762330855;37679.62320376761;38285.6171438282;38461.615383845805;38798.6120138795;33153.66846331506;33937.66062339345;34007.82996085004;34022.65977340294;34050.489242660886;24398.02407903664;25182.925920590704;25555.744442555337;25662.973481060548;26087.08695195659 13:54:14 INFO - PROCESS | 1940 | |2;jquery.html;389.7427697719505;395.0755232755352;397.0906623831426;401.19560281635336;424.87253823852836;19969;20025.699614505793;20450.89774551125;20480;21303.147874085018;19517.707234391495;19926;21819.454513637167;22054.33836984893;22075;5959.165716799641;6226.2839773426185;6242.8439289017915;6253.530985176108;6256.343083976185;6036.396360363972;6131.14164017038;6186.505079593644;6238.376162383748;6245.875082498347;5928.644281343111;6107.572469927107;6149.661768602725;6163.106349579309;6165.969170154143;5264.947350526484;6041.6375017499095;6128.11142384354;6152.692365381713;6152.84617884552;5227.60792940531;5355.651882627617;5384.9730751346;5392.838214853561;5393.002294575516;23785.16751913692;25969.350766230807;26098.608520872203;26762.063327783628;26918.46163076747;2167.5014746608213;2189.2447105748493;2362.244081893795;2679.5042917060405;2950.5574163875376;14966.251687415695;15043.473478428203;15174.317155727907;15555.066695998275;15892.52322430329;1631;1777.9022153781536;1805.5125116218678;1827.5339788353886;1835.8714889957707;113162;117352;117579.99999999914;117922;119632;9750.414975101517;11010.009099181112;11168.497417616065;11266.66200014001;11455.656330310105;14459.349329280207;14523.564293071013;14533.128012319296;14549.854501455062;14566.85433145655;7389.187189409161;8605.784855378666;8820.735377938543;8910.242629376575;8966.103389660982;8839.425437346552;8868.15752503511;8886;8954.462732236087;8961.59672814725;6813.557118787263;6906.792796216121;6912.654367281616;6917.446604271646;6943.062686537252;1868.9065546722609;1914.4639500939759;1916.1185854506805;1929.199382256348;1930.5752734398409;953.6566835939057;955.2739917662626;964.9517524123765;1002.5638847101534;1007.0835539659022;15421.9228903857;17757.378491752985;21047.473813154793;21104.366868993955;21174.941252937293;985.3201291109254;985.7979114281555;993.4734590666958;1026.1124127629546;1112.4159816096615;10900.50947707355;11383.975442210241;11410.600628977945;11428.2000259981;11448.885511144948;532712.3457530906;561745;578321;582865;620341;107082.46458767656;120631.79368206381;123990;124383;125631.3718431402;1004.9899501004896;1016.5311125423692;1028.5422986770964;1029.8712660917386;1064.3879769132934;18713.438596842116;19043.619127617647;19147.712784308525;19197.424077277705;19227.711584326527;41806;48032.5196748028;48351.03297934092;49119.754401227765;49505.50494495009;69492.65253673699;70183.29816701972;70310.29689703038;71551.28448715447;72072.55854882978;8115.269625733712;8159.673613055411;8192.67229310821;8385.077641459557;8421.07368189511;12589.811152832897;12730.554430594884;13055.738885222432;13342.332883355599;13553.051286409856;11041.613543525938;11226.158038147334;11336.886631133913;11369.943150284196;11413.657590272305;11080.61217857371;11188.10495160369;11317.490712917774;11420.885791142315;11490.88509114898;11108.944455277995;11394.316341019565;11741.823872642088;11825.52697892109;11877.346745928711;7301.977723118662;7844.333231675371;8117.675292988214;8192.467489613156;8550.444221125606;10639.468026598484;11286.49210785533;11584.07327413788;11642.825357619473;11661.008814250883 13:54:14 INFO - PROCESS | 1940 | |3;mootools.html;1448.7102579484106;1478.4381934864753;1638.3364737281397;1870.859685523586;2020.5756791073863;8029.558374289411;8136.186381361861;8488.830223395536;8490.27832634226;8493.78765530862;8234.094249632552;8883.60023798929;9043.186113249807;9054.320925930566;9055.275577953764;2420.3465064432635;2457.631355296711;2459.3359792855917;2460.7170175429787;2461.3723500507344;2395.221523089661;2453.987730061356;2457.213691618683;2458.1642241637837;2458.2502336787275;2411.3383573688916;2431.659567660529;2456.0175929628112;2458.385403649088;2462.630605409194;2402.9519409611885;2405.0139442828445;2416.758324167578;2420.201333559921;2438.317271164077;2386.880655967212;2405.8195635327247;2410.9758902411104;2415.178839194682;2416.2630397728685;8971;9061.13919177677;9066.410683305565;9070.18368346852;9070.319726020509;4416.40378548895;5289.629725919187;5497.862553436156;5499.560035197174;5500.862478438031;3498.545189125423;3536.469529570559;3597.874074407383;3656.5063716398213;3682.797446140461;1029.665358758399;1057.8994995475493;1066.109798318405;1068.8984546468073;1073.5222825842425;12215.877841221563;12292.262464252173;12315.261084334968;12315.445804938889;12329.876701233052;4616.122936642095;4688.249880019249;4748.572628463455;4753.168195565762;4777.163996300634;9378.202852757591;9487.573059212353;9497.715068547956;9500;9503.714888553355;5393.541548968303;5406.378266499324;5406.567474602022;5407.161889907071;5412;4356.891077723082;4867.196912509467;4973.701577905337;5073.315102461194;5081.212412076134;3780.432935059763;3857.9421308680394;3890.4438224709775;3925.3130702127582;3954.8728612345344;1114.3091283404342;1199.772043311768;1208.323338930202;1213.150794443893;1224.6938265433641;704.7744721689113;874.2001069021901;902.873597696323;964.2334344196381;1005.5424781724298;5976.043832986701;6029.728662210211;6077.574569780073;6099.481544068715;6134.846628834225;886.2024178239636;953.4327075390132;954.6944977607238;996.6013594562233;1012.291396022787;3957.821898014596;4012.25773231953;4021.3967904814513;4074.572169922175;4091.3453847384276;10985;11186.216964812547;11334.546618135182;11339.376334301693;11353;11130.944345278222;11232.382218977871;11239.04468120219;11239.550417983353;11246.943765281121;7977.920220797834;8363.749087527262;8482.575871206414;8491.150884911583;8497.575121243914;5164.406093299319;6009.429104235091;6027.879442411215;6056.485198758155;6135.478484328882;9997.650082247086;10115.59537618487;10312.381257124596;10362.5336859843;10377.740556486147;10448;10451.634192803216;10477.266591338534;10494.422806745702;10504.527296271533;7682.193369696101;7729.381649468141;7746.57393843344;7847.882281765664;7850.214978502333;8445.577721114043;8486.236238738544;8577.185167409336;8578.485290882567;8581;7387.8153046174275;7594.354479869273;7609.885851712118;7698.807529811799;7759.146493885555;6392.968035159794;7355.742549010759;7414.666340014604;7567.9243207569425;7608.771736847903;7166.175889772743;7175.569465831858;7257.092863392076;7264.309890560388;7269.055022846996;4870.78081486341;5015.04714104319;5110.5911527077005;5137.640365174402;5149.304843846033;4909.533594308535;5093.541581257705;5179.559737422211;5301.23132145829;5373.274607928036 13:54:14 INFO - PROCESS | 1940 | |4;prototype.html;243.2398753894081;291.0700312501557;304.39577438784016;306.45909968904874;308.9536569514573;4769.809207631691;4849.321095046696;5222.4777522247805;5236.779380680882;5244.737763111843;4234.661227101832;4252.89367765806;4776.307435421862;4902.803887844482;5207;3597.1366871950804;3701.3337599232127;4001.439438618941;4004.5194576650842;4017.437558741764;3431.5538979932576;3498.9300213995707;3651.9269614607692;3671.5594128704593;4021.767918805062;3014.562888381194;3508.877189298375;3801.9429708554394;3934.4262295082053;4000.0399904023116;3256.576645036154;3957.1096503286817;3983.442318075486;3985.7480523504128;3991.600839916014;2810.775137988956;2866.3264132928743;3462.774919630241;3555.9111022224392;3582.8745993890348;4335.978320108379;5006.07387633289;5007.119482401132;5029.7736601853;5033.84898453047;4005.5193376794678;4157.792110394468;4593.242115051013;4598.931016034762;4655.4878963314;5385.946140538584;5386.841829006782;5386.973065134688;5416.241726158341;5424.674519528841;3078.3843231353817;3820.3572463840537;3868.207017561393;3876.7673939563715;3884.941725874114;4832.806687732486;5086.415062267813;5470.343558772933;5558.333000039981;5746.511546518509;3372.359251742161;3886.747361421555;3932.5477570079233;3935.409688546684;3989.1822176453757;3136.8745250190195;3323.7174840138377;3373.8819141330428;3973.562908080167;4010.5387880393546;3531.841067151984;3962.722609417313;4229.048464095554;4414.293713005904;4546.272596384629;4698.88252793676;5130.486951304914;5312.282841816383;5558.499735023867;5568.7215639217875;1314.8224989626278;1334.8865346445662;1341.376260039067;1341.5505805555053;1342.161149281699;688.1157712339619;777.8755399136111;809.4333966223667;819.9586525113192;820.5568992743852;3903.33643280643;4309.332053531708;4336.1978034063795;4366.995591014084;4371.409859668904;4369.563043695669;4457.946306779866;4595.931061034087;4755.358026666356;4829.420469543677;3827.2919509890758;3993.781896521589;4220.324748040299;4270.957290427056;4276.422682937826;2482.4414506736202;2688.6639170103736;2689.341111427713;2997.685243049448;2999.6051835896415;5425.321834770653;5477.808276710296;5750.079987202028;5935.970320148458;6007.339192688801;4537.682362234678;4929.531694489017;4939.3084968104495;5020.665390593392;5837.357890632027;5874.295084589877;5892.024527613614;5894.879972805154;5909.349971503131;5929.391067930097;4638.605718513964;4775.188218002879;5585.497305242548;5624.268845050117;5661.915071274017;5131.8717032073;5280.867978300574;5591.769810641652;5604.467575580314;5613.614213959133;5204.271402003798;5834.504067154339;5875.883582119383;5889.911651325319;5908.164081240942;3679.889603311905;3760.981195094007;3770.227103443842;3831.6168383162576;3833.6166383362556;5280.841574752763;5321.2816269804625;5535.169724541351;5567.105105345278;5628.380878103323;4831.792051987003;4875.536824001714;5293.3118694571;5429.294191755204;5452.182172673972;5385.973070134624;5438.401775804738;5468.26178465902;5471.112466199155;5482.945170548243;4547.067141693656;4949.133901567139;4953.306537084739;5087.769266515716;5362.276092727588;4574.908501830011;5411.1883217516115;5423.810166644149;5439;5467.617266791445;4570.428696412949;5337.372101509077;5345.1714984177515;5354.866128346666;5405.621606487508 13:54:14 INFO - PROCESS | 1940 | |5;yui.html;641.550914359948;749.3780162465155;756.2362014365489;758.9114984032063;770.6801677303919;14929.402823887045;15304.770428443582;15940.920295398535;15976;16005.119718415483;14681.119132852036;14750.631234219123;14760.6309842254;14765.18791466469;14770.261486925638;896.7085697148436;923.7483210250115;925.6528801699362;926.4117285523707;927.1979737527024;734.7097896330973;760.4714723267332;909.495230147268;943.5848226780195;944.9102335278137;995.8307087795092;997.7256254530026;1001.1990407673868;1002.1581871228167;1002.3384566186319;762.2453770766956;992.072412294507;994.602159136344;995.0000249749029;995.3082607587729;582.935877053528;583.0438081546267;584.8713283077716;705.4990956420947;729.9708011679527;14087.225202613852;14116.435342586285;14117.364718587689;14128.71742565143;14141.505047323295;12469.812952805625;12561.497540098386;12649.177803442839;12770.878926404675;12923.483060677561;10786.08318292946;10833.620823271227;10878.673639790819;11222.719432014264;11414.372209528472;3386.8475918583476;3569.8572057117685;3709.4992176056157;3962.5641179470235;4322.762248076355;51345;84566;85753.57123214344;85999.14000859974;86087;9306.66164175702;9339.392939458914;9390.62437502506;9402.341836071546;9741.65904193364;17178.914105429394;17234.82765172357;20481.79518204829;20496.97515124238;20523.692144617842;7429.962850185714;7439.367653749492;7503.662335194929;7556.357709594746;7627.733029343946;4043.534993475788;4090.9293470456946;4129.938050929239;4132.504099508078;4146.3365861462025;3479.7042251408407;3556.91107722309;3560.7863528187872;3561.30554541867;3563.8396272167815;595.2470125291424;632.5824955529329;656.9802905912735;659.5383231737803;673.4948788408693;498.09346988480615;543.3506959183737;546.2652732075352;564.3650892745661;570.497961793619;7846.9215307847335;7935.722249721232;8022.598870056474;8134.877976830352;8415.663373465115;486.83403249217923;489.4493694593582;560.3024234827666;576.3746335226326;597.3130899465649;3698.4082546792383;3752.3620984432714;3819.923601527954;3828.349180639298;3984.920301593952;96172.51913740387;96281.03718962721;98474.53813654715;99875.50062249642;101608;80299;82884.17115828885;83544;84773;84793.15206847976;73514;73526.63236683782;73548;73897.89153162707;75082;7146.606936618536;7310.59791711461;7332.0834895638045;7537.170911199654;7761.805954851174;42031.528896488475;42271.7886410566;42323.365149522164;46605.76697116493;46719.29921051119;66595.66702166459;67355.66322168554;67465.650686985;67487.66256168688;67585.66207169129;11082.944585277022;11095.288774301784;11272.66182014541;11315.377654229096;11549.6535103947;11781.705457363634;11822.763544729229;11982.760344793229;12091.15361924692;13127.93436032852;11402.486888090221;11534.307941523535;11802.350870702194;12016.33910134951;12091.39543022863;9715.36850104741;11234.3259404436;11660.425842511491;11892.643220703392;12311.138220324492;11421.828672570084;11551.249168804;11669.891360320757;11787.469563869476;11867.287962722265;9441.48071856027;9618.326717129734;9627;9631.807363852824;9857.753556160866;9400.918894326966;9682.370645907993;9778.55996480146;9899.950500247453;9929.602815887283 13:54:14 INFO - PROCESS | 1940 | __end_tp_report 13:54:14 INFO - PROCESS | 1940 | __start_cc_report 13:54:14 INFO - PROCESS | 1940 | _x_x_mozilla_cycle_collect,1434 13:54:14 INFO - PROCESS | 1940 | __end_cc_report 13:54:14 INFO - PROCESS | 1940 | __startTimestamp1463172854430__endTimestamp 13:54:14 INFO - PROCESS | 1940 | 13:54:14 INFO - PROCESS | 1940 | ------- Summary: start ------- 13:54:14 INFO - PROCESS | 1940 | Number of tests: 6 13:54:14 INFO - PROCESS | 1940 | 13:54:14 INFO - PROCESS | 1940 | [#0] dojo.html Cycles:165 Average:5741.18 Median:6005.73 stddev:654.27 (10.9%) stddev-sans-first:630.11 13:54:14 INFO - PROCESS | 1940 | Values: 3406.1 3421.3 3430.6 3450.7 3490.3 5617.4 5669.0 5706.3 5756.7 5782.7 4443.8 5672.8 5771.1 5782.0 5796.6 5073.0 5111.5 5156.7 5183.5 5255.1 5243.4 5267.8 5289.2 5302.2 5337.0 5101.2 5135.7 5138.0 5140.7 5146.5 5157.5 5190.7 5195.5 5200.4 5207.1 5091.5 5103.0 5111.3 5138.3 5153.2 4667.6 5611.6 5680.0 5732.0 5734.9 6142.8 6168.1 6273.9 6275.5 6284.5 6265.2 6272.3 6273.1 6309.8 6319.7 5837.4 5847.7 5850.5 5852.4 5914.5 6134.7 6213.3 6251.5 6273.9 6280.9 6202.4 6210.3 6213.2 6223.6 6227.6 6291.4 6310.9 6318.0 6324.4 6326.1 6091.8 6103.0 6107.5 6125.4 6217.0 6158.3 6187.4 6222.8 6235.3 6237.1 4898.0 4902.2 4906.9 4913.8 4940.2 4442.0 4444.9 4457.3 4483.9 4528.6 5580.4 5664.6 5670.8 5751.9 5769.2 6215.9 6279.2 6289.1 6295.8 6298.0 5776.0 5786.1 5787.6 5791.8 5808.9 5341.7 5390.6 5408.8 5413.3 5413.4 6233.4 6251.4 6275.6 6280.9 6314.7 6292.8 6301.9 6323.6 6329.4 6329.8 6295.1 6327.5 6327.6 6339.5 6353.4 6075.5 6114.7 6146.6 6192.4 6267.6 6273.4 6330.8 6334.7 6351.9 6361.1 6005.7 6011.5 6153.0 6160.0 6165.9 6012.2 6051.7 6090.0 6158.2 6159.0 4946.4 5931.9 5943.8 5954.4 5994.9 5892.8 6049.6 6135.5 6140.2 6142.8 5922.1 6026.5 6046.7 6047.4 6047.6 13:54:14 INFO - PROCESS | 1940 | 13:54:14 INFO - PROCESS | 1940 | [#1] ext.html Cycles:180 Average:61467.93 Median:211163.81 stddev:132783.34 (62.9%) stddev-sans-first:133076.91 13:54:14 INFO - PROCESS | 1940 | Values: 493.0 513.5 518.3 522.9 539.3 30529.2 31270.5 41997.6 42594.6 42680.6 36528.6 44163.8 44201.0 44230.0 44284.9 9616.2 9789.0 9791.8 9796.2 9807.6 9543.6 9652.0 9683.7 9685.2 9688.2 9690.2 9694.0 9696.5 9733.0 9782.8 9639.8 9653.9 9671.0 9674.4 9715.4 8941.7 9117.2 9534.3 9639.1 9645.0 40303.2 40571.4 40613.6 40633.8 40704.0 2737.4 3008.9 3162.4 3462.9 3469.9 24120.4 28779.7 28943.9 29604.6 30175.5 2406.4 2617.0 2736.3 2741.7 2783.2 382123.0 384042.0 384578.0 384767.1 385250.0 34910.5 35777.1 59324.4 60544.7 60771.0 36869.4 36899.3 36965.0 37048.6 37061.4 14946.9 15136.5 15140.8 15141.6 15142.7 10953.6 12708.8 12850.1 12859.4 12867.8 7861.4 8001.4 8025.6 8081.6 8095.4 3451.0 3454.5 3474.5 3474.9 3503.6 876.3 998.1 1016.8 1037.4 1109.3 25967.9 27948.0 29386.6 29462.7 30367.7 923.8 927.6 946.8 1132.1 1165.0 15418.2 15448.6 15552.8 15777.3 15935.4 643373.6 644619.0 646352.0 647902.0 649405.0 60142.7 60701.7 60764.0 60782.4 60854.0 1281.0 1300.7 1339.6 1484.9 1521.5 28576.0 28676.1 30418.5 30439.2 30974.4 49871.8 50375.5 50377.7 50458.5 50559.7 414824.0 415326.0 417765.0 418368.0 419360.0 26618.3 27197.7 28156.6 28172.4 28872.9 45534.5 46272.8 46539.5 47349.3 47652.0 36777.4 36904.8 38185.8 38971.8 39254.6 31170.0 37990.2 38150.8 38160.8 38492.2 37295.1 37679.6 38285.6 38461.6 38798.6 33153.7 33937.7 34007.8 34022.7 34050.5 24398.0 25182.9 25555.7 25663.0 26087.1 13:54:14 INFO - PROCESS | 1940 | 13:54:14 INFO - PROCESS | 1940 | [#2] jquery.html Cycles:180 Average:34088.12 Median:11993.48 stddev:95941.69 (799.9%) stddev-sans-first:96177.47 13:54:14 INFO - PROCESS | 1940 | Values: 389.7 395.1 397.1 401.2 424.9 19969.0 20025.7 20450.9 20480.0 21303.1 19517.7 19926.0 21819.5 22054.3 22075.0 5959.2 6226.3 6242.8 6253.5 6256.3 6036.4 6131.1 6186.5 6238.4 6245.9 5928.6 6107.6 6149.7 6163.1 6166.0 5264.9 6041.6 6128.1 6152.7 6152.8 5227.6 5355.7 5385.0 5392.8 5393.0 23785.2 25969.4 26098.6 26762.1 26918.5 2167.5 2189.2 2362.2 2679.5 2950.6 14966.3 15043.5 15174.3 15555.1 15892.5 1631.0 1777.9 1805.5 1827.5 1835.9 113162.0 117352.0 117580.0 117922.0 119632.0 9750.4 11010.0 11168.5 11266.7 11455.7 14459.3 14523.6 14533.1 14549.9 14566.9 7389.2 8605.8 8820.7 8910.2 8966.1 8839.4 8868.2 8886.0 8954.5 8961.6 6813.6 6906.8 6912.7 6917.4 6943.1 1868.9 1914.5 1916.1 1929.2 1930.6 953.7 955.3 965.0 1002.6 1007.1 15421.9 17757.4 21047.5 21104.4 21174.9 985.3 985.8 993.5 1026.1 1112.4 10900.5 11384.0 11410.6 11428.2 11448.9 532712.3 561745.0 578321.0 582865.0 620341.0 107082.5 120631.8 123990.0 124383.0 125631.4 1005.0 1016.5 1028.5 1029.9 1064.4 18713.4 19043.6 19147.7 19197.4 19227.7 41806.0 48032.5 48351.0 49119.8 49505.5 69492.7 70183.3 70310.3 71551.3 72072.6 8115.3 8159.7 8192.7 8385.1 8421.1 12589.8 12730.6 13055.7 13342.3 13553.1 11041.6 11226.2 11336.9 11369.9 11413.7 11080.6 11188.1 11317.5 11420.9 11490.9 11108.9 11394.3 11741.8 11825.5 11877.3 7302.0 7844.3 8117.7 8192.5 8550.4 10639.5 11286.5 11584.1 11642.8 11661.0 13:54:14 INFO - PROCESS | 1940 | 13:54:14 INFO - PROCESS | 1940 | [#3] mootools.html Cycles:180 Average:5827.88 Median:4941.62 stddev:3295.10 (66.7%) stddev-sans-first:3287.91 13:54:14 INFO - PROCESS | 1940 | Values: 1448.7 1478.4 1638.3 1870.9 2020.6 8029.6 8136.2 8488.8 8490.3 8493.8 8234.1 8883.6 9043.2 9054.3 9055.3 2420.3 2457.6 2459.3 2460.7 2461.4 2395.2 2454.0 2457.2 2458.2 2458.3 2411.3 2431.7 2456.0 2458.4 2462.6 2403.0 2405.0 2416.8 2420.2 2438.3 2386.9 2405.8 2411.0 2415.2 2416.3 8971.0 9061.1 9066.4 9070.2 9070.3 4416.4 5289.6 5497.9 5499.6 5500.9 3498.5 3536.5 3597.9 3656.5 3682.8 1029.7 1057.9 1066.1 1068.9 1073.5 12215.9 12292.3 12315.3 12315.4 12329.9 4616.1 4688.2 4748.6 4753.2 4777.2 9378.2 9487.6 9497.7 9500.0 9503.7 5393.5 5406.4 5406.6 5407.2 5412.0 4356.9 4867.2 4973.7 5073.3 5081.2 3780.4 3857.9 3890.4 3925.3 3954.9 1114.3 1199.8 1208.3 1213.2 1224.7 704.8 874.2 902.9 964.2 1005.5 5976.0 6029.7 6077.6 6099.5 6134.8 886.2 953.4 954.7 996.6 1012.3 3957.8 4012.3 4021.4 4074.6 4091.3 10985.0 11186.2 11334.5 11339.4 11353.0 11130.9 11232.4 11239.0 11239.6 11246.9 7977.9 8363.7 8482.6 8491.2 8497.6 5164.4 6009.4 6027.9 6056.5 6135.5 9997.7 10115.6 10312.4 10362.5 10377.7 10448.0 10451.6 10477.3 10494.4 10504.5 7682.2 7729.4 7746.6 7847.9 7850.2 8445.6 8486.2 8577.2 8578.5 8581.0 7387.8 7594.4 7609.9 7698.8 7759.1 6393.0 7355.7 7414.7 7567.9 7608.8 7166.2 7175.6 7257.1 7264.3 7269.1 4870.8 5015.0 5110.6 5137.6 5149.3 4909.5 5093.5 5179.6 5301.2 5373.3 13:54:14 INFO - PROCESS | 1940 | 13:54:14 INFO - PROCESS | 1940 | [#4] prototype.html Cycles:180 Average:4308.22 Median:4677.19 stddev:1350.22 (28.9%) stddev-sans-first:1319.08 13:54:14 INFO - PROCESS | 1940 | Values: 243.2 291.1 304.4 306.5 309.0 4769.8 4849.3 5222.5 5236.8 5244.7 4234.7 4252.9 4776.3 4902.8 5207.0 3597.1 3701.3 4001.4 4004.5 4017.4 3431.6 3498.9 3651.9 3671.6 4021.8 3014.6 3508.9 3801.9 3934.4 4000.0 3256.6 3957.1 3983.4 3985.7 3991.6 2810.8 2866.3 3462.8 3555.9 3582.9 4336.0 5006.1 5007.1 5029.8 5033.8 4005.5 4157.8 4593.2 4598.9 4655.5 5385.9 5386.8 5387.0 5416.2 5424.7 3078.4 3820.4 3868.2 3876.8 3884.9 4832.8 5086.4 5470.3 5558.3 5746.5 3372.4 3886.7 3932.5 3935.4 3989.2 3136.9 3323.7 3373.9 3973.6 4010.5 3531.8 3962.7 4229.0 4414.3 4546.3 4698.9 5130.5 5312.3 5558.5 5568.7 1314.8 1334.9 1341.4 1341.6 1342.2 688.1 777.9 809.4 820.0 820.6 3903.3 4309.3 4336.2 4367.0 4371.4 4369.6 4457.9 4595.9 4755.4 4829.4 3827.3 3993.8 4220.3 4271.0 4276.4 2482.4 2688.7 2689.3 2997.7 2999.6 5425.3 5477.8 5750.1 5936.0 6007.3 4537.7 4929.5 4939.3 5020.7 5837.4 5874.3 5892.0 5894.9 5909.3 5929.4 4638.6 4775.2 5585.5 5624.3 5661.9 5131.9 5280.9 5591.8 5604.5 5613.6 5204.3 5834.5 5875.9 5889.9 5908.2 3679.9 3761.0 3770.2 3831.6 3833.6 5280.8 5321.3 5535.2 5567.1 5628.4 4831.8 4875.5 5293.3 5429.3 5452.2 5386.0 5438.4 5468.3 5471.1 5482.9 4547.1 4949.1 4953.3 5087.8 5362.3 4574.9 5411.2 5423.8 5439.0 5467.6 4570.4 5337.4 5345.2 5354.9 5405.6 13:54:14 INFO - PROCESS | 1940 | 13:54:14 INFO - PROCESS | 1940 | [#5] yui.html Cycles:180 Average:18477.47 Median:567.43 stddev:26520.96 (4673.9%) stddev-sans-first:26561.55 13:54:14 INFO - PROCESS | 1940 | Values: 641.6 749.4 756.2 758.9 770.7 14929.4 15304.8 15940.9 15976.0 16005.1 14681.1 14750.6 14760.6 14765.2 14770.3 896.7 923.7 925.7 926.4 927.2 734.7 760.5 909.5 943.6 944.9 995.8 997.7 1001.2 1002.2 1002.3 762.2 992.1 994.6 995.0 995.3 582.9 583.0 584.9 705.5 730.0 14087.2 14116.4 14117.4 14128.7 14141.5 12469.8 12561.5 12649.2 12770.9 12923.5 10786.1 10833.6 10878.7 11222.7 11414.4 3386.8 3569.9 3709.5 3962.6 4322.8 51345.0 84566.0 85753.6 85999.1 86087.0 9306.7 9339.4 9390.6 9402.3 9741.7 17178.9 17234.8 20481.8 20497.0 20523.7 7430.0 7439.4 7503.7 7556.4 7627.7 4043.5 4090.9 4129.9 4132.5 4146.3 3479.7 3556.9 3560.8 3561.3 3563.8 595.2 632.6 657.0 659.5 673.5 498.1 543.4 546.3 564.4 570.5 7846.9 7935.7 8022.6 8134.9 8415.7 486.8 489.4 560.3 576.4 597.3 3698.4 3752.4 3819.9 3828.3 3984.9 96172.5 96281.0 98474.5 99875.5 101608.0 80299.0 82884.2 83544.0 84773.0 84793.2 73514.0 73526.6 73548.0 73897.9 75082.0 7146.6 7310.6 7332.1 7537.2 7761.8 42031.5 42271.8 42323.4 46605.8 46719.3 66595.7 67355.7 67465.7 67487.7 67585.7 11082.9 11095.3 11272.7 11315.4 11549.7 11781.7 11822.8 11982.8 12091.2 13127.9 11402.5 11534.3 11802.4 12016.3 12091.4 9715.4 11234.3 11660.4 11892.6 12311.1 11421.8 11551.2 11669.9 11787.5 11867.3 9441.5 9618.3 9627.0 9631.8 9857.8 9400.9 9682.4 9778.6 9900.0 9929.6 13:54:14 INFO - PROCESS | 1940 | -------- Summary: end -------- 13:54:14 INFO - PROCESS | 1940 | 13:54:14 INFO - TEST-INFO | 1940: exit 0 13:54:15 INFO - TEST-OK | dromaeo_css | took 1114536ms 13:54:15 INFO - TEST-START | kraken 13:54:15 INFO - operating with platform_type : mac_ 13:54:15 INFO - Initialising browser for kraken test... 13:54:15 INFO - TEST-INFO | started process 1944 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/tmpiwc0bo/profile http://localhost:50146/getInfo.html) 13:54:17 INFO - PROCESS | 1944 | __metrics Screen width/height:1600/1200 13:54:17 INFO - PROCESS | 1944 | colorDepth:24 13:54:17 INFO - PROCESS | 1944 | Browser inner width/height: 1024/689 13:54:17 INFO - PROCESS | 1944 | __metrics 13:54:17 INFO - PROCESS | 1944 | 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" 13:54:17 ERROR - PROCESS | 1944 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 13:54:17 INFO - TEST-INFO | 1944: exit 0 13:54:17 INFO - Browser initialized. 13:54:17 INFO - Running cycle 1/1 for kraken test... 13:54:17 INFO - TEST-INFO | started process 1946 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/tmpiwc0bo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:54:29 INFO - PROCESS | 1946 | RSS: Main: 239185920 13:54:29 INFO - PROCESS | 1946 | 13:54:32 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50146/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50146/tests/kraken/driver.html?testName=audio-beat-detection) 13:54:33 INFO - PROCESS | 1946 | RSS: Main: 287043584 13:54:33 INFO - PROCESS | 1946 | 13:54:36 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50146/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50146/tests/kraken/driver.html?testName=audio-dft) 13:54:36 INFO - PROCESS | 1946 | RSS: Main: 297873408 13:54:36 INFO - PROCESS | 1946 | 13:54:40 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50146/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50146/tests/kraken/driver.html?testName=audio-fft) 13:54:40 INFO - PROCESS | 1946 | RSS: Main: 387858432 13:54:40 INFO - PROCESS | 1946 | 13:54:42 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50146/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50146/tests/kraken/driver.html?testName=audio-oscillator) 13:54:43 INFO - PROCESS | 1946 | RSS: Main: 287301632 13:54:43 INFO - PROCESS | 1946 | 13:54:45 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50146/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50146/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:54:46 INFO - PROCESS | 1946 | RSS: Main: 291340288 13:54:46 INFO - PROCESS | 1946 | 13:54:49 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50146/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50146/tests/kraken/driver.html?testName=imaging-darkroom) 13:54:50 INFO - PROCESS | 1946 | RSS: Main: 327856128 13:54:50 INFO - PROCESS | 1946 | 13:54:53 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50146/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50146/tests/kraken/driver.html?testName=imaging-desaturate) 13:54:53 INFO - PROCESS | 1946 | RSS: Main: 330125312 13:54:53 INFO - PROCESS | 1946 | 13:54:56 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50146/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50146/tests/kraken/driver.html?testName=json-parse-financial) 13:54:57 INFO - PROCESS | 1946 | RSS: Main: 327471104 13:54:57 INFO - PROCESS | 1946 | 13:54:59 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50146/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50146/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:54:59 INFO - PROCESS | 1946 | RSS: Main: 316416000 13:54:59 INFO - PROCESS | 1946 | 13:55:01 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50146/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50146/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:55:02 INFO - PROCESS | 1946 | RSS: Main: 323223552 13:55:02 INFO - PROCESS | 1946 | 13:55:04 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50146/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50146/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:55:05 INFO - PROCESS | 1946 | RSS: Main: 325013504 13:55:05 INFO - PROCESS | 1946 | 13:55:08 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50146/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50146/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:55:08 INFO - PROCESS | 1946 | RSS: Main: 329748480 13:55:08 INFO - PROCESS | 1946 | 13:55:11 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50146/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50146/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:55:12 INFO - PROCESS | 1946 | RSS: Main: 327430144 13:55:12 INFO - PROCESS | 1946 | 13:55:14 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:50146/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50146/tests/kraken/driver.html?testName=ai-astar) 13:55:14 INFO - PROCESS | 1946 | RSS: Main: 373837824 13:55:14 INFO - PROCESS | 1946 | 13:55:14 INFO - PROCESS | 1946 | __start_tp_report 13:55:14 INFO - PROCESS | 1946 | _x_x_mozilla_page_load 13:55:14 INFO - PROCESS | 1946 | _x_x_mozilla_page_load_details 13:55:14 INFO - PROCESS | 1946 | |i|pagename|runs| 13:55:14 INFO - PROCESS | 1946 | |0;ai-astar;112;106;111;112;111;112;111;111;111;112 13:55:14 INFO - PROCESS | 1946 | |1;audio-beat-detection;176;137;165;197;121;142;134;120;168;118 13:55:14 INFO - PROCESS | 1946 | |2;audio-dft;189;185;181;185;185;184;185;180;181;182 13:55:14 INFO - PROCESS | 1946 | |3;audio-fft;73;76;57;123;56;60;57;60;110;55 13:55:14 INFO - PROCESS | 1946 | |4;audio-oscillator;93;122;154;78;79;78;101;80;83;101 13:55:14 INFO - PROCESS | 1946 | |5;imaging-gaussian-blur;87;83;87;83;90;84;74;112;128;120 13:55:14 INFO - PROCESS | 1946 | |6;imaging-darkroom;95;92;93;92;93;92;93;93;92;93 13:55:14 INFO - PROCESS | 1946 | |7;imaging-desaturate;85;84;83;84;85;83;84;84;103;130 13:55:14 INFO - PROCESS | 1946 | |8;json-parse-financial;57;57;57;55;55;56;56;116;58;55 13:55:14 INFO - PROCESS | 1946 | |9;json-stringify-tinderbox;62;76;76;74;72;67;68;69;67;68 13:55:14 INFO - PROCESS | 1946 | |10;stanford-crypto-aes;86;85;82;84;85;82;85;84;85;85 13:55:14 INFO - PROCESS | 1946 | |11;stanford-crypto-ccm;138;139;139;137;146;141;135;136;219;139 13:55:14 INFO - PROCESS | 1946 | |12;stanford-crypto-pbkdf2;162;161;161;163;162;170;210;209;191;161 13:55:14 INFO - PROCESS | 1946 | |13;stanford-crypto-sha256-iterative;65;66;66;66;76;78;80;81;74;65 13:55:14 INFO - PROCESS | 1946 | __end_tp_report 13:55:14 INFO - PROCESS | 1946 | __start_cc_report 13:55:14 INFO - PROCESS | 1946 | _x_x_mozilla_cycle_collect,2212 13:55:14 INFO - PROCESS | 1946 | __end_cc_report 13:55:14 INFO - PROCESS | 1946 | __startTimestamp1463172914564__endTimestamp 13:55:14 INFO - PROCESS | 1946 | 13:55:14 INFO - PROCESS | 1946 | ------- Summary: start ------- 13:55:14 INFO - PROCESS | 1946 | Number of tests: 14 13:55:14 INFO - PROCESS | 1946 | 13:55:14 INFO - PROCESS | 1946 | [#0] ai-astar Cycles:10 Average:110.90 Median:111.50 stddev:1.79 (1.6%) stddev-sans-first:1.86 13:55:14 INFO - PROCESS | 1946 | Values: 112.0 106.0 111.0 112.0 111.0 112.0 111.0 111.0 111.0 112.0 13:55:14 INFO - PROCESS | 1946 | 13:55:14 INFO - PROCESS | 1946 | [#1] audio-beat-detection Cycles:10 Average:147.80 Median:153.50 stddev:27.16 (17.7%) stddev-sans-first:26.82 13:55:14 INFO - PROCESS | 1946 | Values: 176.0 137.0 165.0 197.0 121.0 142.0 134.0 120.0 168.0 118.0 13:55:14 INFO - PROCESS | 1946 | 13:55:14 INFO - PROCESS | 1946 | [#2] audio-dft Cycles:10 Average:183.70 Median:185.00 stddev:2.71 (1.5%) stddev-sans-first:2.09 13:55:14 INFO - PROCESS | 1946 | Values: 189.0 185.0 181.0 185.0 185.0 184.0 185.0 180.0 181.0 182.0 13:55:14 INFO - PROCESS | 1946 | 13:55:14 INFO - PROCESS | 1946 | [#3] audio-fft Cycles:10 Average:72.70 Median:58.50 stddev:24.36 (41.6%) stddev-sans-first:25.84 13:55:14 INFO - PROCESS | 1946 | Values: 73.0 76.0 57.0 123.0 56.0 60.0 57.0 60.0 110.0 55.0 13:55:14 INFO - PROCESS | 1946 | 13:55:14 INFO - PROCESS | 1946 | [#4] audio-oscillator Cycles:10 Average:96.90 Median:78.50 stddev:24.61 (31.4%) stddev-sans-first:26.07 13:55:14 INFO - PROCESS | 1946 | Values: 93.0 122.0 154.0 78.0 79.0 78.0 101.0 80.0 83.0 101.0 13:55:14 INFO - PROCESS | 1946 | 13:55:14 INFO - PROCESS | 1946 | [#5] imaging-gaussian-blur Cycles:10 Average:94.80 Median:83.50 stddev:18.27 (21.9%) stddev-sans-first:19.16 13:55:14 INFO - PROCESS | 1946 | Values: 87.0 83.0 87.0 83.0 90.0 84.0 74.0 112.0 128.0 120.0 13:55:14 INFO - PROCESS | 1946 | 13:55:14 INFO - PROCESS | 1946 | [#6] imaging-darkroom Cycles:10 Average:92.80 Median:93.00 stddev:0.92 (1.0%) stddev-sans-first:0.53 13:55:14 INFO - PROCESS | 1946 | Values: 95.0 92.0 93.0 92.0 93.0 92.0 93.0 93.0 92.0 93.0 13:55:14 INFO - PROCESS | 1946 | 13:55:14 INFO - PROCESS | 1946 | [#7] imaging-desaturate Cycles:10 Average:90.50 Median:84.00 stddev:15.12 (18.0%) stddev-sans-first:15.91 13:55:14 INFO - PROCESS | 1946 | Values: 85.0 84.0 83.0 84.0 85.0 83.0 84.0 84.0 103.0 130.0 13:55:14 INFO - PROCESS | 1946 | 13:55:14 INFO - PROCESS | 1946 | [#8] json-parse-financial Cycles:10 Average:62.20 Median:56.50 stddev:18.93 (33.5%) stddev-sans-first:19.99 13:55:14 INFO - PROCESS | 1946 | Values: 57.0 57.0 57.0 55.0 55.0 56.0 56.0 116.0 58.0 55.0 13:55:14 INFO - PROCESS | 1946 | 13:55:14 INFO - PROCESS | 1946 | [#9] json-stringify-tinderbox Cycles:10 Average:69.90 Median:70.50 stddev:4.51 (6.4%) stddev-sans-first:3.77 13:55:14 INFO - PROCESS | 1946 | Values: 62.0 76.0 76.0 74.0 72.0 67.0 68.0 69.0 67.0 68.0 13:55:14 INFO - PROCESS | 1946 | 13:55:14 INFO - PROCESS | 1946 | [#10] stanford-crypto-aes Cycles:10 Average:84.30 Median:85.00 stddev:1.34 (1.6%) stddev-sans-first:1.27 13:55:14 INFO - PROCESS | 1946 | Values: 86.0 85.0 82.0 84.0 85.0 82.0 85.0 84.0 85.0 85.0 13:55:14 INFO - PROCESS | 1946 | 13:55:14 INFO - PROCESS | 1946 | [#11] stanford-crypto-ccm Cycles:10 Average:146.90 Median:139.00 stddev:25.51 (18.4%) stddev-sans-first:26.86 13:55:14 INFO - PROCESS | 1946 | Values: 138.0 139.0 139.0 137.0 146.0 141.0 135.0 136.0 219.0 139.0 13:55:14 INFO - PROCESS | 1946 | 13:55:14 INFO - PROCESS | 1946 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.00 Median:166.50 stddev:20.36 (12.2%) stddev-sans-first:21.05 13:55:14 INFO - PROCESS | 1946 | Values: 162.0 161.0 161.0 163.0 162.0 170.0 210.0 209.0 191.0 161.0 13:55:14 INFO - PROCESS | 1946 | 13:55:14 INFO - PROCESS | 1946 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.70 Median:75.00 stddev:6.72 (9.0%) stddev-sans-first:6.67 13:55:14 INFO - PROCESS | 1946 | Values: 65.0 66.0 66.0 66.0 76.0 78.0 80.0 81.0 74.0 65.0 13:55:14 INFO - PROCESS | 1946 | -------- Summary: end -------- 13:55:14 INFO - PROCESS | 1946 | 13:55:15 INFO - TEST-INFO | 1946: exit 0 13:55:15 INFO - TEST-OK | kraken | took 60387ms 13:55:15 INFO - SUITE-END | took 1174s 13:55:15 INFO - Completed test suite (00:19:35) 13:55:15 INFO - javascript benchmark 13:55:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3406.097384193189, 3421.2644281479484, 3430.6397828228037, 3450.654934506548, 3490.2670439207764, 5617.410171931944, 5668.971655141729, 5706.258186435757, 5756.654600723954, 5782.681952492611, 4443.755593442352, 5672.801451949183, 5771.134329850514, 5782.0, 5796.62321949073, 5073.0, 5111.539961403473, 5156.742162891859, 5183.507566781169, 5255.054090263751, 5243.449437809032, 5267.841964741045, 5289.206619007141, 5302.204669299616, 5337.0, 5101.15830887903, 5135.666181698178, 5137.999999999981, 5140.742962851842, 5146.459621739701, 5157.4584668610005, 5190.688558686491, 5195.454477279907, 5200.401953775288, 5207.114790485626, 5091.490850914916, 5103.004914041763, 5111.284420181178, 5138.30632864566, 5153.201253805629, 4667.649926255511, 5611.607187496877, 5679.971600142014, 5731.996900542388, 5734.913976290359, 6142.846428839315, 6168.136460895478, 6273.8745225095245, 6275.497960163176, 6284.497240220771, 6265.185525881652, 6272.341404152544, 6273.12176295319, 6309.779157729503, 6319.715612797435, 5837.445442682938, 5847.707614619337, 5850.531957443394, 5852.356240813507, 5914.497267732248, 6134.7121296814075, 6213.316535181127, 6251.531135164881, 6273.937260627426, 6280.937190628035, 6202.44178023971, 6210.347913469111, 6213.2233470816145, 6223.626582405071, 6227.626342419469, 6291.370862913764, 6310.873782524323, 6317.96841015792, 6324.367563243639, 6326.145970293951, 6091.847703807441, 6103.034636161374, 6107.46191531438, 6125.388069147376, 6216.968915155395, 6158.261008678897, 6187.443130118311, 6222.782202622977, 6235.282942461584, 6237.095621135, 4897.975510122498, 4902.240152776325, 4906.852794416173, 4913.754312284443, 4940.184869496565, 4441.977790111029, 4444.866654000385, 4457.286834106528, 4483.887902802391, 4528.592426681615, 5580.441955804387, 5664.6034777566, 5670.8015219467125, 5751.913721294184, 5769.249997500382, 6215.875682486325, 6279.183706118175, 6289.119523266746, 6295.811125666238, 6298.02380631003, 5775.971120144456, 5786.07422812348, 5787.594868359174, 5791.797287095016, 5808.94191058084, 5341.679499230058, 5390.622656414092, 5408.810691625774, 5413.323334583177, 5413.350397952271, 6233.4389904909685, 6251.4061164189325, 6275.560710750295, 6280.874382512233, 6314.715837787218, 6292.842678933063, 6301.905471417841, 6323.557350985387, 6329.430351268408, 6329.810105696836, 6295.087212354091, 6327.525435592441, 6327.557071004985, 6339.524535659752, 6353.428191462791, 6075.453209211192, 6114.66235542843, 6146.6170111724505, 6192.380761923771, 6267.561270711189, 6273.404026617464, 6330.841728956813, 6334.714937827716, 6351.904721429275, 6361.109444677656, 6005.7297421617, 6011.519078473799, 6152.969235153795, 6159.983602705683, 6165.907511387422, 6012.218411606463, 6051.667158306335, 6089.964705999902, 6158.230221222348, 6159.045347971071, 4946.3817022872145, 5931.851703707442, 5943.791967281124, 5954.434328738763, 5994.940050599438, 5892.823215303548, 6049.637021778707, 6135.509159267337, 6140.223762386189, 6142.78500252489, 5922.111683247547, 6026.487748541423, 6046.697665116814, 6047.425494578008, 6047.606905551125], "value": 5699.924684215615, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [492.9778159982803, 513.4762542206952, 518.2951186386515, 522.9372475302964, 539.2612121393691, 30529.236769080755, 31270.530942035886, 41997.580024199786, 42594.57405425945, 42680.57319426805, 36528.63471365286, 44163.77918110405, 44201.0, 44230.0, 44284.892877677994, 9616.18262447691, 9789.0, 9791.804163916717, 9796.167325777318, 9807.558659860288, 9543.618255269781, 9652.0, 9683.709488715349, 9685.176759975375, 9688.224942004623, 9690.224782017456, 9694.0, 9696.515174241295, 9732.95133524335, 9782.804343913118, 9639.759006024871, 9653.855192172123, 9670.9516452418, 9674.419534827934, 9715.36850104741, 8941.6870409536, 9117.22503587196, 9534.28492863038, 9639.132478076937, 9645.035496450299, 40303.19393612141, 40571.391429128584, 40613.59386406127, 40633.79683101595, 40704.0, 2737.397772490049, 3008.8796448142048, 3162.414953233659, 3462.9307413851834, 3469.8959031229106, 24120.396990075387, 28779.712202877912, 28943.855280723674, 29604.55593166104, 30175.547366789517, 2406.3743426709007, 2617.0, 2736.261209473451, 2741.7258274172623, 2783.178962206154, 382123.0, 384042.0, 384578.0, 384767.0761646202, 385250.0, 34910.476342854876, 35777.1055723609, 59324.44053356812, 60544.69727651333, 60771.0, 36869.446958295644, 36899.26201475955, 36965.0, 37048.591841530215, 37061.44407833885, 14946.925265373604, 15136.54590362291, 15140.848591514165, 15141.621459463602, 15142.697146057237, 10953.56185752561, 12708.809367859489, 12850.10049296559, 12859.357032148542, 12867.806982895265, 7861.449698521049, 8001.359891208689, 8025.5585942773705, 8081.555514446762, 8095.392845536609, 3450.9827450863086, 3454.481827725811, 3474.5309383233443, 3474.947875781865, 3503.597086335053, 876.3164731509432, 998.1017084623896, 1016.7712264740522, 1037.4034929915329, 1109.290054365207, 25967.870160649454, 27948.02181923623, 29386.55920161199, 29462.705372945995, 30367.69632303693, 923.819855128257, 927.5547737086237, 946.7822400847843, 1132.1112926352773, 1165.0272022861006, 15418.229088545751, 15448.613784655474, 15552.766708499381, 15777.322214039757, 15935.442259520862, 643373.6144512097, 644619.0, 646352.0, 647902.0, 649405.0, 60142.69928650329, 60701.69649151726, 60764.0, 60782.39217607856, 60854.0, 1280.9743805123846, 1300.6618279247275, 1339.6115126613172, 1484.9382691375718, 1521.4674863797804, 28576.0, 28676.13971580856, 30418.543721843747, 30439.239019024702, 30974.38051239008, 49871.75064124656, 50375.49624503855, 50377.74811126068, 50458.49541504538, 50559.74720126376, 414824.0, 415326.0, 417765.0, 418368.0, 419360.0, 26618.334541636614, 27197.72802271952, 28156.577651335658, 28172.43655126927, 28872.855635721688, 45534.54465455303, 46272.8431789208, 46539.53460465487, 47349.28976065293, 47652.04695905993, 36777.448338275484, 36904.81547592245, 38185.80907095447, 38971.83084507469, 39254.60745392624, 31170.0, 37990.24019519539, 38150.809245954704, 38160.80919595384, 38492.23015539617, 37295.06762330855, 37679.62320376761, 38285.6171438282, 38461.615383845805, 38798.6120138795, 33153.66846331506, 33937.66062339345, 34007.82996085004, 34022.65977340294, 34050.489242660886, 24398.02407903664, 25182.925920590704, 25555.744442555337, 25662.973481060548, 26087.08695195659], "value": 18206.081690088013, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.7427697719505, 395.0755232755352, 397.0906623831426, 401.19560281635336, 424.87253823852836, 19969.0, 20025.699614505793, 20450.89774551125, 20480.0, 21303.147874085018, 19517.707234391495, 19926.0, 21819.454513637167, 22054.33836984893, 22075.0, 5959.165716799641, 6226.2839773426185, 6242.8439289017915, 6253.530985176108, 6256.343083976185, 6036.396360363972, 6131.14164017038, 6186.505079593644, 6238.376162383748, 6245.875082498347, 5928.644281343111, 6107.572469927107, 6149.661768602725, 6163.106349579309, 6165.969170154143, 5264.947350526484, 6041.6375017499095, 6128.11142384354, 6152.692365381713, 6152.84617884552, 5227.60792940531, 5355.651882627617, 5384.9730751346, 5392.838214853561, 5393.002294575516, 23785.16751913692, 25969.350766230807, 26098.608520872203, 26762.063327783628, 26918.46163076747, 2167.5014746608213, 2189.2447105748493, 2362.244081893795, 2679.5042917060405, 2950.5574163875376, 14966.251687415695, 15043.473478428203, 15174.317155727907, 15555.066695998275, 15892.52322430329, 1631.0, 1777.9022153781536, 1805.5125116218678, 1827.5339788353886, 1835.8714889957707, 113162.0, 117352.0, 117579.99999999914, 117922.0, 119632.0, 9750.414975101517, 11010.009099181112, 11168.497417616065, 11266.66200014001, 11455.656330310105, 14459.349329280207, 14523.564293071013, 14533.128012319296, 14549.854501455062, 14566.85433145655, 7389.187189409161, 8605.784855378666, 8820.735377938543, 8910.242629376575, 8966.103389660982, 8839.425437346552, 8868.15752503511, 8886.0, 8954.462732236087, 8961.59672814725, 6813.557118787263, 6906.792796216121, 6912.654367281616, 6917.446604271646, 6943.062686537252, 1868.9065546722609, 1914.4639500939759, 1916.1185854506805, 1929.199382256348, 1930.5752734398409, 953.6566835939057, 955.2739917662626, 964.9517524123765, 1002.5638847101534, 1007.0835539659022, 15421.9228903857, 17757.378491752985, 21047.473813154793, 21104.366868993955, 21174.941252937293, 985.3201291109254, 985.7979114281555, 993.4734590666958, 1026.1124127629546, 1112.4159816096615, 10900.50947707355, 11383.975442210241, 11410.600628977945, 11428.2000259981, 11448.885511144948, 532712.3457530906, 561745.0, 578321.0, 582865.0, 620341.0, 107082.46458767656, 120631.79368206381, 123990.0, 124383.0, 125631.3718431402, 1004.9899501004896, 1016.5311125423692, 1028.5422986770964, 1029.8712660917386, 1064.3879769132934, 18713.438596842116, 19043.619127617647, 19147.712784308525, 19197.424077277705, 19227.711584326527, 41806.0, 48032.5196748028, 48351.03297934092, 49119.754401227765, 49505.50494495009, 69492.65253673699, 70183.29816701972, 70310.29689703038, 71551.28448715447, 72072.55854882978, 8115.269625733712, 8159.673613055411, 8192.67229310821, 8385.077641459557, 8421.07368189511, 12589.811152832897, 12730.554430594884, 13055.738885222432, 13342.332883355599, 13553.051286409856, 11041.613543525938, 11226.158038147334, 11336.886631133913, 11369.943150284196, 11413.657590272305, 11080.61217857371, 11188.10495160369, 11317.490712917774, 11420.885791142315, 11490.88509114898, 11108.944455277995, 11394.316341019565, 11741.823872642088, 11825.52697892109, 11877.346745928711, 7301.977723118662, 7844.333231675371, 8117.675292988214, 8192.467489613156, 8550.444221125606, 10639.468026598484, 11286.49210785533, 11584.07327413788, 11642.825357619473, 11661.008814250883], "value": 9859.18658741184, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1448.7102579484106, 1478.4381934864753, 1638.3364737281397, 1870.859685523586, 2020.5756791073863, 8029.558374289411, 8136.186381361861, 8488.830223395536, 8490.27832634226, 8493.78765530862, 8234.094249632552, 8883.60023798929, 9043.186113249807, 9054.320925930566, 9055.275577953764, 2420.3465064432635, 2457.631355296711, 2459.3359792855917, 2460.7170175429787, 2461.3723500507344, 2395.221523089661, 2453.987730061356, 2457.213691618683, 2458.1642241637837, 2458.2502336787275, 2411.3383573688916, 2431.659567660529, 2456.0175929628112, 2458.385403649088, 2462.630605409194, 2402.9519409611885, 2405.0139442828445, 2416.758324167578, 2420.201333559921, 2438.317271164077, 2386.880655967212, 2405.8195635327247, 2410.9758902411104, 2415.178839194682, 2416.2630397728685, 8971.0, 9061.13919177677, 9066.410683305565, 9070.18368346852, 9070.319726020509, 4416.40378548895, 5289.629725919187, 5497.862553436156, 5499.560035197174, 5500.862478438031, 3498.545189125423, 3536.469529570559, 3597.874074407383, 3656.5063716398213, 3682.797446140461, 1029.665358758399, 1057.8994995475493, 1066.109798318405, 1068.8984546468073, 1073.5222825842425, 12215.877841221563, 12292.262464252173, 12315.261084334968, 12315.445804938889, 12329.876701233052, 4616.122936642095, 4688.249880019249, 4748.572628463455, 4753.168195565762, 4777.163996300634, 9378.202852757591, 9487.573059212353, 9497.715068547956, 9500.0, 9503.714888553355, 5393.541548968303, 5406.378266499324, 5406.567474602022, 5407.161889907071, 5412.0, 4356.891077723082, 4867.196912509467, 4973.701577905337, 5073.315102461194, 5081.212412076134, 3780.432935059763, 3857.9421308680394, 3890.4438224709775, 3925.3130702127582, 3954.8728612345344, 1114.3091283404342, 1199.772043311768, 1208.323338930202, 1213.150794443893, 1224.6938265433641, 704.7744721689113, 874.2001069021901, 902.873597696323, 964.2334344196381, 1005.5424781724298, 5976.043832986701, 6029.728662210211, 6077.574569780073, 6099.481544068715, 6134.846628834225, 886.2024178239636, 953.4327075390132, 954.6944977607238, 996.6013594562233, 1012.291396022787, 3957.821898014596, 4012.25773231953, 4021.3967904814513, 4074.572169922175, 4091.3453847384276, 10985.0, 11186.216964812547, 11334.546618135182, 11339.376334301693, 11353.0, 11130.944345278222, 11232.382218977871, 11239.04468120219, 11239.550417983353, 11246.943765281121, 7977.920220797834, 8363.749087527262, 8482.575871206414, 8491.150884911583, 8497.575121243914, 5164.406093299319, 6009.429104235091, 6027.879442411215, 6056.485198758155, 6135.478484328882, 9997.650082247086, 10115.59537618487, 10312.381257124596, 10362.5336859843, 10377.740556486147, 10448.0, 10451.634192803216, 10477.266591338534, 10494.422806745702, 10504.527296271533, 7682.193369696101, 7729.381649468141, 7746.57393843344, 7847.882281765664, 7850.214978502333, 8445.577721114043, 8486.236238738544, 8577.185167409336, 8578.485290882567, 8581.0, 7387.8153046174275, 7594.354479869273, 7609.885851712118, 7698.807529811799, 7759.146493885555, 6392.968035159794, 7355.742549010759, 7414.666340014604, 7567.9243207569425, 7608.771736847903, 7166.175889772743, 7175.569465831858, 7257.092863392076, 7264.309890560388, 7269.055022846996, 4870.78081486341, 5015.04714104319, 5110.5911527077005, 5137.640365174402, 5149.304843846033, 4909.533594308535, 5093.541581257705, 5179.559737422211, 5301.23132145829, 5373.274607928036], "value": 4669.3797109729285, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [243.2398753894081, 291.0700312501557, 304.39577438784016, 306.45909968904874, 308.9536569514573, 4769.809207631691, 4849.321095046696, 5222.4777522247805, 5236.779380680882, 5244.737763111843, 4234.661227101832, 4252.89367765806, 4776.307435421862, 4902.803887844482, 5207.0, 3597.1366871950804, 3701.3337599232127, 4001.439438618941, 4004.5194576650842, 4017.437558741764, 3431.5538979932576, 3498.9300213995707, 3651.9269614607692, 3671.5594128704593, 4021.767918805062, 3014.562888381194, 3508.877189298375, 3801.9429708554394, 3934.4262295082053, 4000.0399904023116, 3256.576645036154, 3957.1096503286817, 3983.442318075486, 3985.7480523504128, 3991.600839916014, 2810.775137988956, 2866.3264132928743, 3462.774919630241, 3555.9111022224392, 3582.8745993890348, 4335.978320108379, 5006.07387633289, 5007.119482401132, 5029.7736601853, 5033.84898453047, 4005.5193376794678, 4157.792110394468, 4593.242115051013, 4598.931016034762, 4655.4878963314, 5385.946140538584, 5386.841829006782, 5386.973065134688, 5416.241726158341, 5424.674519528841, 3078.3843231353817, 3820.3572463840537, 3868.207017561393, 3876.7673939563715, 3884.941725874114, 4832.806687732486, 5086.415062267813, 5470.343558772933, 5558.333000039981, 5746.511546518509, 3372.359251742161, 3886.747361421555, 3932.5477570079233, 3935.409688546684, 3989.1822176453757, 3136.8745250190195, 3323.7174840138377, 3373.8819141330428, 3973.562908080167, 4010.5387880393546, 3531.841067151984, 3962.722609417313, 4229.048464095554, 4414.293713005904, 4546.272596384629, 4698.88252793676, 5130.486951304914, 5312.282841816383, 5558.499735023867, 5568.7215639217875, 1314.8224989626278, 1334.8865346445662, 1341.376260039067, 1341.5505805555053, 1342.161149281699, 688.1157712339619, 777.8755399136111, 809.4333966223667, 819.9586525113192, 820.5568992743852, 3903.33643280643, 4309.332053531708, 4336.1978034063795, 4366.995591014084, 4371.409859668904, 4369.563043695669, 4457.946306779866, 4595.931061034087, 4755.358026666356, 4829.420469543677, 3827.2919509890758, 3993.781896521589, 4220.324748040299, 4270.957290427056, 4276.422682937826, 2482.4414506736202, 2688.6639170103736, 2689.341111427713, 2997.685243049448, 2999.6051835896415, 5425.321834770653, 5477.808276710296, 5750.079987202028, 5935.970320148458, 6007.339192688801, 4537.682362234678, 4929.531694489017, 4939.3084968104495, 5020.665390593392, 5837.357890632027, 5874.295084589877, 5892.024527613614, 5894.879972805154, 5909.349971503131, 5929.391067930097, 4638.605718513964, 4775.188218002879, 5585.497305242548, 5624.268845050117, 5661.915071274017, 5131.8717032073, 5280.867978300574, 5591.769810641652, 5604.467575580314, 5613.614213959133, 5204.271402003798, 5834.504067154339, 5875.883582119383, 5889.911651325319, 5908.164081240942, 3679.889603311905, 3760.981195094007, 3770.227103443842, 3831.6168383162576, 3833.6166383362556, 5280.841574752763, 5321.2816269804625, 5535.169724541351, 5567.105105345278, 5628.380878103323, 4831.792051987003, 4875.536824001714, 5293.3118694571, 5429.294191755204, 5452.182172673972, 5385.973070134624, 5438.401775804738, 5468.26178465902, 5471.112466199155, 5482.945170548243, 4547.067141693656, 4949.133901567139, 4953.306537084739, 5087.769266515716, 5362.276092727588, 4574.908501830011, 5411.1883217516115, 5423.810166644149, 5439.0, 5467.617266791445, 4570.428696412949, 5337.372101509077, 5345.1714984177515, 5354.866128346666, 5405.621606487508], "value": 3879.7627318773193, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [641.550914359948, 749.3780162465155, 756.2362014365489, 758.9114984032063, 770.6801677303919, 14929.402823887045, 15304.770428443582, 15940.920295398535, 15976.0, 16005.119718415483, 14681.119132852036, 14750.631234219123, 14760.6309842254, 14765.18791466469, 14770.261486925638, 896.7085697148436, 923.7483210250115, 925.6528801699362, 926.4117285523707, 927.1979737527024, 734.7097896330973, 760.4714723267332, 909.495230147268, 943.5848226780195, 944.9102335278137, 995.8307087795092, 997.7256254530026, 1001.1990407673868, 1002.1581871228167, 1002.3384566186319, 762.2453770766956, 992.072412294507, 994.602159136344, 995.0000249749029, 995.3082607587729, 582.935877053528, 583.0438081546267, 584.8713283077716, 705.4990956420947, 729.9708011679527, 14087.225202613852, 14116.435342586285, 14117.364718587689, 14128.71742565143, 14141.505047323295, 12469.812952805625, 12561.497540098386, 12649.177803442839, 12770.878926404675, 12923.483060677561, 10786.08318292946, 10833.620823271227, 10878.673639790819, 11222.719432014264, 11414.372209528472, 3386.8475918583476, 3569.8572057117685, 3709.4992176056157, 3962.5641179470235, 4322.762248076355, 51345.0, 84566.0, 85753.57123214344, 85999.14000859974, 86087.0, 9306.66164175702, 9339.392939458914, 9390.62437502506, 9402.341836071546, 9741.65904193364, 17178.914105429394, 17234.82765172357, 20481.79518204829, 20496.97515124238, 20523.692144617842, 7429.962850185714, 7439.367653749492, 7503.662335194929, 7556.357709594746, 7627.733029343946, 4043.534993475788, 4090.9293470456946, 4129.938050929239, 4132.504099508078, 4146.3365861462025, 3479.7042251408407, 3556.91107722309, 3560.7863528187872, 3561.30554541867, 3563.8396272167815, 595.2470125291424, 632.5824955529329, 656.9802905912735, 659.5383231737803, 673.4948788408693, 498.09346988480615, 543.3506959183737, 546.2652732075352, 564.3650892745661, 570.497961793619, 7846.9215307847335, 7935.722249721232, 8022.598870056474, 8134.877976830352, 8415.663373465115, 486.83403249217923, 489.4493694593582, 560.3024234827666, 576.3746335226326, 597.3130899465649, 3698.4082546792383, 3752.3620984432714, 3819.923601527954, 3828.349180639298, 3984.920301593952, 96172.51913740387, 96281.03718962721, 98474.53813654715, 99875.50062249642, 101608.0, 80299.0, 82884.17115828885, 83544.0, 84773.0, 84793.15206847976, 73514.0, 73526.63236683782, 73548.0, 73897.89153162707, 75082.0, 7146.606936618536, 7310.59791711461, 7332.0834895638045, 7537.170911199654, 7761.805954851174, 42031.528896488475, 42271.7886410566, 42323.365149522164, 46605.76697116493, 46719.29921051119, 66595.66702166459, 67355.66322168554, 67465.650686985, 67487.66256168688, 67585.66207169129, 11082.944585277022, 11095.288774301784, 11272.66182014541, 11315.377654229096, 11549.6535103947, 11781.705457363634, 11822.763544729229, 11982.760344793229, 12091.15361924692, 13127.93436032852, 11402.486888090221, 11534.307941523535, 11802.350870702194, 12016.33910134951, 12091.39543022863, 9715.36850104741, 11234.3259404436, 11660.425842511491, 11892.643220703392, 12311.138220324492, 11421.828672570084, 11551.249168804, 11669.891360320757, 11787.469563869476, 11867.287962722265, 9441.48071856027, 9618.326717129734, 9627.0, 9631.807363852824, 9857.753556160866, 9400.918894326966, 9682.370645907993, 9778.55996480146, 9899.950500247453, 9929.602815887283], "value": 6888.355278548247, "unit": "score"}], "name": "dromaeo_css", "value": 7096.190134561369, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 106.0, 111.0, 112.0, 111.0, 112.0, 111.0, 111.0, 111.0, 112.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 137.0, 165.0, 197.0, 121.0, 142.0, 134.0, 120.0, 168.0, 118.0], "value": 147.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 185.0, 181.0, 185.0, 185.0, 184.0, 185.0, 180.0, 181.0, 182.0], "value": 183.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 76.0, 57.0, 123.0, 56.0, 60.0, 57.0, 60.0, 110.0, 55.0], "value": 72.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 122.0, 154.0, 78.0, 79.0, 78.0, 101.0, 80.0, 83.0, 101.0], "value": 96.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 87.0, 83.0, 90.0, 84.0, 74.0, 112.0, 128.0, 120.0], "value": 94.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 92.0, 93.0, 92.0, 93.0, 92.0, 93.0, 93.0, 92.0, 93.0], "value": 92.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 83.0, 84.0, 85.0, 83.0, 84.0, 84.0, 103.0, 130.0], "value": 90.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 57.0, 55.0, 55.0, 56.0, 56.0, 116.0, 58.0, 55.0], "value": 62.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 76.0, 76.0, 74.0, 72.0, 67.0, 68.0, 69.0, 67.0, 68.0], "value": 69.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 82.0, 84.0, 85.0, 82.0, 85.0, 84.0, 85.0, 85.0], "value": 84.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 139.0, 139.0, 137.0, 146.0, 141.0, 135.0, 136.0, 219.0, 139.0], "value": 146.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 161.0, 163.0, 162.0, 170.0, 210.0, 209.0, 191.0, 161.0], "value": 175.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 66.0, 76.0, 78.0, 80.0, 81.0, 74.0, 65.0], "value": 71.7, "unit": "score"}], "name": "kraken", "value": 1500.1000000000001, "alertThreshold": 2.0}]} 13:55:15 INFO - Return code: 0 13:55:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 13:55:15 ERROR - # TBPL SUCCESS # 13:55:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:55:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:55:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:55:15 INFO - 2016-05-13 13:55:15.881 screenresolution[1950:16110] starting screenresolution argv=screenresolution get 13:55:15 INFO - 2016-05-13 13:55:15.884 screenresolution[1950:16110] Display 0: 1600x1200x32@60 13:55:15 INFO - 2016-05-13 13:55:15.890 screenresolution[1951:16113] starting screenresolution argv=screenresolution list 13:55:15 INFO - Available Modes on Display 0 13:55:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:55:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:55:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:55:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:55:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:55:16 INFO - Intel Iris: 13:55:16 INFO - Chipset Model: Intel Iris 13:55:16 INFO - Type: GPU 13:55:16 INFO - Bus: Built-In 13:55:16 INFO - VRAM (Dynamic, Max): 1536 MB 13:55:16 INFO - Vendor: Intel (0x8086) 13:55:16 INFO - Device ID: 0x0a2e 13:55:16 INFO - Revision ID: 0x0009 13:55:16 INFO - Displays: 13:55:16 INFO - Display: 13:55:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:55:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:55:16 INFO - Display Serial Number: 1600x1200 60 13:55:16 INFO - Main Display: Yes 13:55:16 INFO - Mirror: Off 13:55:16 INFO - Online: Yes 13:55:16 INFO - Rotation: Supported 13:55:16 INFO - Return code: 0 13:55:16 INFO - Running post-action listener: _resource_record_post_action 13:55:16 INFO - ##### 13:55:16 INFO - ##### Finished run-tests step (success) 13:55:16 INFO - ##### 13:55:16 INFO - Running post-run listener: _resource_record_post_run 13:55:16 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 708608; Write bytes: 694038016; Read time: 8; Write time: 1919 13:55:16 INFO - TinderboxPrint: CPU usage
53.6% 13:55:16 INFO - TinderboxPrint: I/O read bytes / time
708,608 / 8 13:55:16 INFO - TinderboxPrint: I/O write bytes / time
694,038,016 / 1,919 13:55:16 INFO - TinderboxPrint: CPU idle
2,246.0 (46.4%) 13:55:16 INFO - TinderboxPrint: CPU system
961.8 (19.9%) 13:55:16 INFO - TinderboxPrint: CPU user
1,633.9 (33.7%) 13:55:16 INFO - TinderboxPrint: Swap in / out
395,194,368 / 0 13:55:16 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 0; Write bytes: 397936640; Read time: 0; Write time: 812 13:55:16 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 708608; Write bytes: 295253504; Read time: 8; Write time: 1103 13:55:16 INFO - Running post-run listener: _upload_blobber_files 13:55:16 INFO - Blob upload gear active. 13:55:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:55:16 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 13:55:16 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'] 13:55:16 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 13:55:17 INFO - (blobuploader) - INFO - Open directory for files ... 13:55:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:55:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:55:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:55:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:55:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:55:18 INFO - (blobuploader) - INFO - Done attempting. 13:55:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:55:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:55:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:55:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:55:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:55:19 INFO - (blobuploader) - INFO - Done attempting. 13:55:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:55:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:55:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:55:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:55:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:55:21 INFO - (blobuploader) - INFO - Done attempting. 13:55:21 INFO - (blobuploader) - INFO - Iteration through files over. 13:55:21 INFO - Return code: 0 13:55:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:55:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:55:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/537446172de1d9ce057211da896be1ce5ca58fd61abd318d88bcb354bb8ce7e09f3a7ecc7ba0824e12e8631a47fc289a931a9fd2e860a0ad24391a8a9a184cb8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/045659bd08490e217e3d41a68cfaa28aefa6ab59880fd523d25b7b64b928ca4d4ecb8680fff90ccd92ff625b21be76a078ce78d94e20869f4e9cb54ff300505a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0a583bb7b0a7840c330e75c7c1fbe39d5bb60a727c59c2af6e812cb86a7227cbed5895772d607ab2e8c28e3f8365c689c12850610ce6ae370cb19d1f61bbe3f7"} 13:55:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:55:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:55:21 INFO - Contents: 13:55:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/537446172de1d9ce057211da896be1ce5ca58fd61abd318d88bcb354bb8ce7e09f3a7ecc7ba0824e12e8631a47fc289a931a9fd2e860a0ad24391a8a9a184cb8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/045659bd08490e217e3d41a68cfaa28aefa6ab59880fd523d25b7b64b928ca4d4ecb8680fff90ccd92ff625b21be76a078ce78d94e20869f4e9cb54ff300505a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0a583bb7b0a7840c330e75c7c1fbe39d5bb60a727c59c2af6e812cb86a7227cbed5895772d607ab2e8c28e3f8365c689c12850610ce6ae370cb19d1f61bbe3f7"} 13:55:21 INFO - Running post-run listener: copy_logs_to_upload_dir 13:55:21 INFO - Copying logs to upload dir... 13:55:21 INFO - mkdir: /builds/slave/test/build/upload/logs 13:55:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1281.655789 ========= master_lag: 4.94 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2016-05-13 13:55:26.377705) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 13:55:26.382969) ========= 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.3K4LCjtvoO/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.LvwqFJqsRB/Listeners TMPDIR=/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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/537446172de1d9ce057211da896be1ce5ca58fd61abd318d88bcb354bb8ce7e09f3a7ecc7ba0824e12e8631a47fc289a931a9fd2e860a0ad24391a8a9a184cb8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/045659bd08490e217e3d41a68cfaa28aefa6ab59880fd523d25b7b64b928ca4d4ecb8680fff90ccd92ff625b21be76a078ce78d94e20869f4e9cb54ff300505a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0a583bb7b0a7840c330e75c7c1fbe39d5bb60a727c59c2af6e812cb86a7227cbed5895772d607ab2e8c28e3f8365c689c12850610ce6ae370cb19d1f61bbe3f7"} build_url:https://queue.taskcluster.net/v1/task/df378LuaQKSlXmLQ6kw_IA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011295 build_url: 'https://queue.taskcluster.net/v1/task/df378LuaQKSlXmLQ6kw_IA/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/537446172de1d9ce057211da896be1ce5ca58fd61abd318d88bcb354bb8ce7e09f3a7ecc7ba0824e12e8631a47fc289a931a9fd2e860a0ad24391a8a9a184cb8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/045659bd08490e217e3d41a68cfaa28aefa6ab59880fd523d25b7b64b928ca4d4ecb8680fff90ccd92ff625b21be76a078ce78d94e20869f4e9cb54ff300505a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0a583bb7b0a7840c330e75c7c1fbe39d5bb60a727c59c2af6e812cb86a7227cbed5895772d607ab2e8c28e3f8365c689c12850610ce6ae370cb19d1f61bbe3f7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 13:55:26.414893) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 13:55:26.415211) ========= 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.3K4LCjtvoO/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.LvwqFJqsRB/Listeners TMPDIR=/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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.005112 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 13:55:26.463705) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 13:55:26.464006) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 13:55:26.464326) ========= ========= Total master_lag: 5.07 =========