builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0375 starttime: 1463525732.18 results: success (0) buildid: 20160517141544 builduid: 81be15bf541f44fab91cfd7ad9687eb1 revision: f3f2fa1d7eed5a8262f6401ef18ff8117a3ce43e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 15:55:32.182295) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 15:55:32.183052) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 15:55:32.183550) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 15:55:32.204862) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 15:55:32.205464) ========= 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.xb7lXzsVeE/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.Duk1YXzOl5/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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-17 15:55:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 10.1M=0.001s 2016-05-17 15:55:32 (10.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.088609 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 15:55:32.312433) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 15:55:32.312748) ========= 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.xb7lXzsVeE/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.Duk1YXzOl5/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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.019876 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 15:55:32.361244) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-17 15:55:32.361603) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f3f2fa1d7eed5a8262f6401ef18ff8117a3ce43e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev f3f2fa1d7eed5a8262f6401ef18ff8117a3ce43e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xb7lXzsVeE/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.Duk1YXzOl5/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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-17 15:55:32,443 truncating revision to first 12 chars 2016-05-17 15:55:32,444 Setting DEBUG logging. 2016-05-17 15:55:32,444 attempt 1/10 2016-05-17 15:55:32,444 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f3f2fa1d7eed?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-17 15:55:33,431 unpacking tar archive at: mozilla-central-f3f2fa1d7eed/testing/mozharness/ program finished with exit code 0 elapsedTime=1.237951 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-17 15:55:33.617002) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 15:55:33.617353) ========= script_repo_revision: f3f2fa1d7eed5a8262f6401ef18ff8117a3ce43e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 15:55:33.617847) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 15:55:33.618114) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 15:55:33.631872) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-05-17 15:55:33.632194) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xb7lXzsVeE/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.Duk1YXzOl5/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 15:55:33 INFO - MultiFileLogger online at 20160517 15:55:33 in /builds/slave/test 15:55:33 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 15:55:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:55:33 INFO - {'append_to_log': False, 15:55:33 INFO - 'base_work_dir': '/builds/slave/test', 15:55:33 INFO - 'blob_upload_branch': 'Firefox', 15:55:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:55:33 INFO - 'branch': 'Firefox', 15:55:33 INFO - 'buildbot_json_path': 'buildprops.json', 15:55:33 INFO - 'config_files': ('talos/mac_config.py',), 15:55:33 INFO - 'default_actions': ('clobber', 15:55:33 INFO - 'read-buildbot-config', 15:55:33 INFO - 'download-and-extract', 15:55:33 INFO - 'populate-webroot', 15:55:33 INFO - 'create-virtualenv', 15:55:33 INFO - 'install', 15:55:33 INFO - 'run-tests'), 15:55:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:55:33 INFO - 'download_minidump_stackwalk': True, 15:55:33 INFO - 'download_symbols': 'ondemand', 15:55:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:55:33 INFO - 'tooltool.py': '/tools/tooltool.py', 15:55:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:55:33 INFO - '/tools/misc-python/virtualenv.py')}, 15:55:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:55:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:55:33 INFO - 'installer_path': 'installer.exe', 15:55:33 INFO - 'log_level': 'info', 15:55:33 INFO - 'log_name': 'talos', 15:55:33 INFO - 'log_to_console': True, 15:55:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:55:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:55:33 INFO - 'opt_config_files': (), 15:55:33 INFO - 'pip_index': False, 15:55:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:55:33 INFO - 'cmd': ('bash', 15:55:33 INFO - '-c', 15:55:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:55:33 INFO - 'enabled': True, 15:55:33 INFO - 'halt_on_failure': False, 15:55:33 INFO - 'name': 'check_screen_resolution'},), 15:55:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:55:33 INFO - 'cmd': ('bash', 15:55:33 INFO - '-c', 15:55:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:55:33 INFO - 'enabled': True, 15:55:33 INFO - 'halt_on_failure': False, 15:55:33 INFO - 'name': 'check_screen_resolution'},), 15:55:33 INFO - 'run_cmd_checks_enabled': True, 15:55:33 INFO - 'sps_profile': False, 15:55:33 INFO - 'sps_profile_interval': 0, 15:55:33 INFO - 'suite': 'dromaeojs', 15:55:33 INFO - 'system_bits': '32', 15:55:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:55:33 INFO - 'title': 't-yosemite-r7-0375', 15:55:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:55:33 INFO - 'use_talos_json': True, 15:55:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:55:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:55:33 INFO - 'work_dir': 'build'} 15:55:33 INFO - ##### 15:55:33 INFO - ##### Running clobber step. 15:55:33 INFO - ##### 15:55:33 INFO - Running pre-action listener: _resource_record_pre_action 15:55:33 INFO - Running main action method: clobber 15:55:33 INFO - rmtree: /builds/slave/test/build 15:55:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:55:35 INFO - Running post-action listener: _resource_record_post_action 15:55:35 INFO - ##### 15:55:35 INFO - ##### Finished clobber step (success) 15:55:35 INFO - ##### 15:55:35 INFO - ##### 15:55:35 INFO - ##### Running read-buildbot-config step. 15:55:35 INFO - ##### 15:55:35 INFO - Running pre-action listener: _resource_record_pre_action 15:55:35 INFO - Running main action method: read_buildbot_config 15:55:35 INFO - Using buildbot properties: 15:55:35 INFO - { 15:55:35 INFO - "project": "", 15:55:35 INFO - "product": "firefox", 15:55:35 INFO - "script_repo_revision": "production", 15:55:35 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 15:55:35 INFO - "repository": "", 15:55:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 15:55:35 INFO - "buildid": "20160517141544", 15:55:35 INFO - "pgo_build": "False", 15:55:35 INFO - "basedir": "/builds/slave/test", 15:55:35 INFO - "buildnumber": 253, 15:55:35 INFO - "slavename": "t-yosemite-r7-0375", 15:55:35 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 15:55:35 INFO - "platform": "yosemite_r7", 15:55:35 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 15:55:35 INFO - "branch": "mozilla-central", 15:55:35 INFO - "repo_path": "mozilla-central", 15:55:35 INFO - "revision": "f3f2fa1d7eed5a8262f6401ef18ff8117a3ce43e", 15:55:35 INFO - "stage_platform": "macosx64", 15:55:35 INFO - "builduid": "81be15bf541f44fab91cfd7ad9687eb1", 15:55:35 INFO - "slavebuilddir": "test" 15:55:35 INFO - } 15:55:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/Euvofsy6Q7GtCeCTPY3EKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 15:55:35 INFO - Running post-action listener: _resource_record_post_action 15:55:35 INFO - ##### 15:55:35 INFO - ##### Finished read-buildbot-config step (success) 15:55:35 INFO - ##### 15:55:35 INFO - ##### 15:55:35 INFO - ##### Running download-and-extract step. 15:55:35 INFO - ##### 15:55:35 INFO - Running pre-action listener: _resource_record_pre_action 15:55:35 INFO - Running main action method: download_and_extract 15:55:35 INFO - mkdir: /builds/slave/test/build/tests 15:55:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:55:35 INFO - https://queue.taskcluster.net/v1/task/Euvofsy6Q7GtCeCTPY3EKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 15:55:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Euvofsy6Q7GtCeCTPY3EKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 15:55:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Euvofsy6Q7GtCeCTPY3EKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 15:55:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Euvofsy6Q7GtCeCTPY3EKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 15:55:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Euvofsy6Q7GtCeCTPY3EKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 15:55:36 INFO - Downloaded 1199 bytes. 15:55:36 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 15:55:36 INFO - Using the following test package requirements: 15:55:36 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 15:55:36 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:55:36 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 15:55:36 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:55:36 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 15:55:36 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:55:36 INFO - u'jsshell-mac.zip'], 15:55:36 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:55:36 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 15:55:36 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 15:55:36 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:55:36 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 15:55:36 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:55:36 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 15:55:36 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:55:36 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 15:55:36 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:55:36 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 15:55:36 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:55:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:55:36 INFO - https://queue.taskcluster.net/v1/task/Euvofsy6Q7GtCeCTPY3EKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:55:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Euvofsy6Q7GtCeCTPY3EKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:55:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Euvofsy6Q7GtCeCTPY3EKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:55:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Euvofsy6Q7GtCeCTPY3EKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:55:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Euvofsy6Q7GtCeCTPY3EKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:55:37 INFO - Downloaded 18267686 bytes. 15:55:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:55:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:55:40 INFO - Return code: 0 15:55:40 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 15:55:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:55:40 INFO - https://queue.taskcluster.net/v1/task/Euvofsy6Q7GtCeCTPY3EKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:55:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Euvofsy6Q7GtCeCTPY3EKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:55:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Euvofsy6Q7GtCeCTPY3EKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:55:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Euvofsy6Q7GtCeCTPY3EKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:55:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Euvofsy6Q7GtCeCTPY3EKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:55:41 INFO - Downloaded 18267686 bytes. 15:55:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:55:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:55:44 INFO - Return code: 0 15:55:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:55:44 INFO - https://queue.taskcluster.net/v1/task/Euvofsy6Q7GtCeCTPY3EKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 15:55:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Euvofsy6Q7GtCeCTPY3EKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 15:55:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Euvofsy6Q7GtCeCTPY3EKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 15:55:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Euvofsy6Q7GtCeCTPY3EKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 15:55:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Euvofsy6Q7GtCeCTPY3EKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:55:45 INFO - Downloaded 10840194 bytes. 15:55:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:55:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 15:55:46 INFO - Return code: 0 15:55: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')]} 15:55:46 INFO - https://queue.taskcluster.net/v1/task/Euvofsy6Q7GtCeCTPY3EKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 15:55:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Euvofsy6Q7GtCeCTPY3EKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:55:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Euvofsy6Q7GtCeCTPY3EKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:55:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Euvofsy6Q7GtCeCTPY3EKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 15:55:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Euvofsy6Q7GtCeCTPY3EKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 15:55:48 INFO - Downloaded 110147980 bytes. 15:55:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Euvofsy6Q7GtCeCTPY3EKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:55:48 INFO - mkdir: /builds/slave/test/properties 15:55:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:55:48 INFO - Writing to file /builds/slave/test/properties/build_url 15:55:48 INFO - Contents: 15:55:48 INFO - build_url:https://queue.taskcluster.net/v1/task/Euvofsy6Q7GtCeCTPY3EKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:55:49 INFO - Running post-action listener: _resource_record_post_action 15:55:49 INFO - Running post-action listener: set_extra_try_arguments 15:55:49 INFO - ##### 15:55:49 INFO - ##### Finished download-and-extract step (success) 15:55:49 INFO - ##### 15:55:49 INFO - ##### 15:55:49 INFO - ##### Running populate-webroot step. 15:55:49 INFO - ##### 15:55:49 INFO - Running pre-action listener: _resource_record_pre_action 15:55:49 INFO - Running main action method: populate_webroot 15:55:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:55:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:55:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:55:49 INFO - '--tppagecycles', 15:55:49 INFO - '7'], 15:55:49 INFO - 'tests': ['tsvgm']}}, 15:55:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:55:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:55:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:55:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:55:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:55:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:55:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:49 INFO - 'talos_options': ['--disable-e10s'], 15:55:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:55:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:55:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:49 INFO - 'talos_options': ['--disable-e10s'], 15:55:49 INFO - 'tests': ['damp', 'tps']}, 15:55:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:49 INFO - 'tests': ['damp', 'tps']}, 15:55:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:55:49 INFO - 'tests': ['dromaeo_dom']}, 15:55:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:55:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:55:49 INFO - 'tests': ['a11yr', 15:55:49 INFO - 'ts_paint', 15:55:49 INFO - 'tpaint', 15:55:49 INFO - 'sessionrestore', 15:55:49 INFO - 'sessionrestore_no_auto_restore', 15:55:49 INFO - 'tabpaint']}, 15:55:49 INFO - 'other-e10s': {'tests': ['a11yr', 15:55:49 INFO - 'ts_paint', 15:55:49 INFO - 'tpaint', 15:55:49 INFO - 'sessionrestore', 15:55:49 INFO - 'sessionrestore_no_auto_restore', 15:55:49 INFO - 'tabpaint']}, 15:55:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:55:49 INFO - 'ts_paint', 15:55:49 INFO - 'tpaint', 15:55:49 INFO - 'sessionrestore', 15:55:49 INFO - 'sessionrestore_no_auto_restore', 15:55:49 INFO - 'tabpaint']}, 15:55:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:55:49 INFO - 'ts_paint', 15:55:49 INFO - 'tpaint', 15:55:49 INFO - 'sessionrestore', 15:55:49 INFO - 'sessionrestore_no_auto_restore', 15:55:49 INFO - 'tabpaint']}, 15:55:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:55:49 INFO - 'tests': ['a11yr', 15:55:49 INFO - 'ts_paint', 15:55:49 INFO - 'tpaint', 15:55:49 INFO - 'sessionrestore', 15:55:49 INFO - 'sessionrestore_no_auto_restore', 15:55:49 INFO - 'tabpaint']}, 15:55:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:55:49 INFO - 'tests': ['a11yr', 15:55:49 INFO - 'ts_paint', 15:55:49 INFO - 'tpaint', 15:55:49 INFO - 'sessionrestore', 15:55:49 INFO - 'sessionrestore_no_auto_restore', 15:55:49 INFO - 'tabpaint']}, 15:55:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:55:49 INFO - 'tests': ['tsvgx', 15:55:49 INFO - 'tsvgr_opacity', 15:55:49 INFO - 'tart', 15:55:49 INFO - 'tscrollx', 15:55:49 INFO - 'cart']}, 15:55:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:55:49 INFO - 'tsvgr_opacity', 15:55:49 INFO - 'tart', 15:55:49 INFO - 'tscrollx', 15:55:49 INFO - 'cart']}, 15:55:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:49 INFO - 'talos_options': ['--disable-e10s'], 15:55:49 INFO - 'tests': ['tp5o']}, 15:55:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:49 INFO - 'tests': ['tp5o']}, 15:55:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:49 INFO - 'talos_options': ['--disable-e10s', 15:55:49 INFO - '--xperf_path', 15:55:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:55:49 INFO - 'tests': ['tp5n']}, 15:55:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:49 INFO - 'talos_options': ['--xperf_path', 15:55:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:55:49 INFO - 'tests': ['tp5n']}}, 15:55:49 INFO - 'talos.zip': {'path': '', 15:55:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:55:49 INFO - Running post-action listener: _resource_record_post_action 15:55:49 INFO - ##### 15:55:49 INFO - ##### Finished populate-webroot step (success) 15:55:49 INFO - ##### 15:55:49 INFO - ##### 15:55:49 INFO - ##### Running create-virtualenv step. 15:55:49 INFO - ##### 15:55:49 INFO - Running pre-action listener: _resource_record_pre_action 15:55:49 INFO - Running main action method: create_virtualenv 15:55:49 INFO - Creating virtualenv /builds/slave/test/build/venv 15:55:49 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:55:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:55:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:55:49 INFO - Using real prefix '/tools/python27' 15:55:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:55:50 INFO - Installing distribute.............................................................................................................................................................................................done. 15:55:53 INFO - Installing pip.................done. 15:55:53 INFO - Return code: 0 15:55:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:55:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:55:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:55:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b134a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b13a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f825b5bc120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afd8d30>, '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 0x10adfcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afcfb20>, '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.xb7lXzsVeE/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.Duk1YXzOl5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:55:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:55:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:55:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xb7lXzsVeE/Render', 15:55:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:55:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:55:53 INFO - 'HOME': '/Users/cltbld', 15:55:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:55:53 INFO - 'LOGNAME': 'cltbld', 15:55:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:53 INFO - 'MOZ_NO_REMOTE': '1', 15:55:53 INFO - 'NO_EM_RESTART': '1', 15:55:53 INFO - 'PAGER': '/bin/cat', 15:55:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:55:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:53 INFO - 'PWD': '/builds/slave/test', 15:55:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:55:53 INFO - 'SHELL': '/bin/bash', 15:55:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Duk1YXzOl5/Listeners', 15:55:53 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 15:55:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:55:53 INFO - 'USER': 'cltbld', 15:55:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:55:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:55:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:53 INFO - 'XPC_FLAGS': '0x0', 15:55:53 INFO - 'XPC_SERVICE_NAME': '0', 15:55:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:55:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:53 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:55:53 INFO - Cleaning up... 15:55:53 INFO - Return code: 0 15:55:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:55:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:55:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:55:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b134a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b13a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f825b5bc120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afd8d30>, '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 0x10adfcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afcfb20>, '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.xb7lXzsVeE/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.Duk1YXzOl5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:55:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:55:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:55:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xb7lXzsVeE/Render', 15:55:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:55:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:55:53 INFO - 'HOME': '/Users/cltbld', 15:55:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:55:53 INFO - 'LOGNAME': 'cltbld', 15:55:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:53 INFO - 'MOZ_NO_REMOTE': '1', 15:55:53 INFO - 'NO_EM_RESTART': '1', 15:55:53 INFO - 'PAGER': '/bin/cat', 15:55:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:55:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:53 INFO - 'PWD': '/builds/slave/test', 15:55:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:55:53 INFO - 'SHELL': '/bin/bash', 15:55:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Duk1YXzOl5/Listeners', 15:55:53 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 15:55:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:55:53 INFO - 'USER': 'cltbld', 15:55:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:55:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:55:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:53 INFO - 'XPC_FLAGS': '0x0', 15:55:53 INFO - 'XPC_SERVICE_NAME': '0', 15:55:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:55:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:53 INFO - Downloading/unpacking psutil>=3.1.1 15:55: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 15:55: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 15:55: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 15:55: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 15:55: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 15:55: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 15:55:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:55:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:55:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:55:56 INFO - Installing collected packages: psutil 15:55:56 INFO - Running setup.py install for psutil 15:55:56 INFO - building 'psutil._psutil_osx' extension 15:55:56 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 15:55:57 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 15:55:57 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 15:55:57 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 15:55:57 INFO - building 'psutil._psutil_posix' extension 15:55:57 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 15:55:57 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:55:57 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:55:57 INFO - ^ 15:55:57 INFO - 1 warning generated. 15:55:57 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 15:55:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:55:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:55:57 INFO - Successfully installed psutil 15:55:57 INFO - Cleaning up... 15:55:57 INFO - Return code: 0 15:55:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:55:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:55:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:55:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b134a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b13a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f825b5bc120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afd8d30>, '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 0x10adfcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afcfb20>, '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.xb7lXzsVeE/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.Duk1YXzOl5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:55:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:55:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:55:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xb7lXzsVeE/Render', 15:55:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:55:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:55:57 INFO - 'HOME': '/Users/cltbld', 15:55:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:55:57 INFO - 'LOGNAME': 'cltbld', 15:55:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:57 INFO - 'MOZ_NO_REMOTE': '1', 15:55:57 INFO - 'NO_EM_RESTART': '1', 15:55:57 INFO - 'PAGER': '/bin/cat', 15:55:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:55:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:57 INFO - 'PWD': '/builds/slave/test', 15:55:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:55:57 INFO - 'SHELL': '/bin/bash', 15:55:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Duk1YXzOl5/Listeners', 15:55:57 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 15:55:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:55:57 INFO - 'USER': 'cltbld', 15:55:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:55:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:55:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:57 INFO - 'XPC_FLAGS': '0x0', 15:55:57 INFO - 'XPC_SERVICE_NAME': '0', 15:55:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:55:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:55:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:56:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:56:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:56:00 INFO - Installing collected packages: mozsystemmonitor 15:56:00 INFO - Running setup.py install for mozsystemmonitor 15:56:00 INFO - Successfully installed mozsystemmonitor 15:56:00 INFO - Cleaning up... 15:56:00 INFO - Return code: 0 15:56:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:56: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')]} 15:56:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:56:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:56: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')]} 15:56:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:56:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:56: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', '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 0x10b134a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b13a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f825b5bc120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afd8d30>, '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 0x10adfcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afcfb20>, '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.xb7lXzsVeE/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.Duk1YXzOl5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:56: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:56: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 blobuploader==1.2.4 15:56:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xb7lXzsVeE/Render', 15:56:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:56:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:56:00 INFO - 'HOME': '/Users/cltbld', 15:56:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:56:00 INFO - 'LOGNAME': 'cltbld', 15:56:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:56:00 INFO - 'MOZ_NO_REMOTE': '1', 15:56:00 INFO - 'NO_EM_RESTART': '1', 15:56:00 INFO - 'PAGER': '/bin/cat', 15:56:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:56:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:56:00 INFO - 'PWD': '/builds/slave/test', 15:56:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:56:00 INFO - 'SHELL': '/bin/bash', 15:56:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Duk1YXzOl5/Listeners', 15:56:00 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 15:56:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:56:00 INFO - 'USER': 'cltbld', 15:56:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:56:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:56:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:56:00 INFO - 'XPC_FLAGS': '0x0', 15:56:00 INFO - 'XPC_SERVICE_NAME': '0', 15:56:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:56:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:56:01 INFO - Downloading/unpacking blobuploader==1.2.4 15:56:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:56:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:56:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:03 INFO - Downloading blobuploader-1.2.4.tar.gz 15:56:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:56:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:56:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:56:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:56:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:56:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:56:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:56:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:56:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:04 INFO - Downloading docopt-0.6.1.tar.gz 15:56:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:56:04 INFO - Installing collected packages: blobuploader, requests, docopt 15:56:04 INFO - Running setup.py install for blobuploader 15:56:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:56:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:56:04 INFO - Running setup.py install for requests 15:56:05 INFO - Running setup.py install for docopt 15:56:05 INFO - Successfully installed blobuploader requests docopt 15:56:05 INFO - Cleaning up... 15:56:05 INFO - Return code: 0 15:56:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:56:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:56:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:56:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:56:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:56:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:56:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:56:05 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 0x10b134a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b13a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f825b5bc120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afd8d30>, '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 0x10adfcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afcfb20>, '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.xb7lXzsVeE/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.Duk1YXzOl5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:56:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:56:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:56:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xb7lXzsVeE/Render', 15:56:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:56:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:56:05 INFO - 'HOME': '/Users/cltbld', 15:56:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:56:05 INFO - 'LOGNAME': 'cltbld', 15:56:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:56:05 INFO - 'MOZ_NO_REMOTE': '1', 15:56:05 INFO - 'NO_EM_RESTART': '1', 15:56:05 INFO - 'PAGER': '/bin/cat', 15:56:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:56:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:56:05 INFO - 'PWD': '/builds/slave/test', 15:56:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:56:05 INFO - 'SHELL': '/bin/bash', 15:56:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Duk1YXzOl5/Listeners', 15:56:05 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 15:56:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:56:05 INFO - 'USER': 'cltbld', 15:56:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:56:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:56:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:56:05 INFO - 'XPC_FLAGS': '0x0', 15:56:05 INFO - 'XPC_SERVICE_NAME': '0', 15:56:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:56:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:56:05 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-oh8mtv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:56:06 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-xnDl6P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:56:06 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-IobqaQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:56:06 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-tYTF1a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:56:06 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-OWwL3f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:56:06 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-eAnXYN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:56:06 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-8snXoL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:56:06 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-WdwoH8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:56:06 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-80oLB_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:56:06 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-kT9bA4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:56:06 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-VnEUS1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:56:07 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-8AUbQF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:56:07 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-TIt4DN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:56:07 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-dDFyFa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:56:07 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-yC_E4o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:56:07 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-q87MwG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:56:07 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-U98IfH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:56:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:56:07 INFO - Running setup.py install for manifestparser 15:56:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:56:07 INFO - Running setup.py install for mozcrash 15:56:07 INFO - Running setup.py install for mozdebug 15:56:08 INFO - Running setup.py install for mozdevice 15:56:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:56:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:56:08 INFO - Running setup.py install for mozfile 15:56:08 INFO - Running setup.py install for mozhttpd 15:56:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:56:08 INFO - Running setup.py install for mozinfo 15:56:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:56:08 INFO - Running setup.py install for mozInstall 15:56:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:56:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:56:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:56:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:56:08 INFO - Running setup.py install for mozleak 15:56:08 INFO - Running setup.py install for mozlog 15:56:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:56:09 INFO - Running setup.py install for moznetwork 15:56:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:56:09 INFO - Running setup.py install for mozprocess 15:56:09 INFO - Running setup.py install for mozprofile 15:56:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:56:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:56:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:56:09 INFO - Running setup.py install for mozrunner 15:56:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:56:09 INFO - Running setup.py install for mozscreenshot 15:56:09 INFO - Running setup.py install for moztest 15:56:10 INFO - Running setup.py install for mozversion 15:56:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:56:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:56:10 INFO - Cleaning up... 15:56:10 INFO - Return code: 0 15:56:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:56:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:56:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:56:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:56:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:56:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:56:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:56:10 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 0x10b134a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b13a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f825b5bc120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afd8d30>, '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 0x10adfcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afcfb20>, '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.xb7lXzsVeE/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.Duk1YXzOl5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:56:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:56:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:56:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xb7lXzsVeE/Render', 15:56:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:56:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:56:10 INFO - 'HOME': '/Users/cltbld', 15:56:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:56:10 INFO - 'LOGNAME': 'cltbld', 15:56:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:56:10 INFO - 'MOZ_NO_REMOTE': '1', 15:56:10 INFO - 'NO_EM_RESTART': '1', 15:56:10 INFO - 'PAGER': '/bin/cat', 15:56:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:56:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:56:10 INFO - 'PWD': '/builds/slave/test', 15:56:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:56:10 INFO - 'SHELL': '/bin/bash', 15:56:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Duk1YXzOl5/Listeners', 15:56:10 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 15:56:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:56:10 INFO - 'USER': 'cltbld', 15:56:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:56:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:56:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:56:10 INFO - 'XPC_FLAGS': '0x0', 15:56:10 INFO - 'XPC_SERVICE_NAME': '0', 15:56:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:56:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:56:10 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-CXrADs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:56:10 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:56:10 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-PNPlgp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:56:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:56:10 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-QmhAPs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:56:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:56:10 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-dJeGuQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:56:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:56:10 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-u792zS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:56:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:56:11 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-ET3Ue3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:56:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:56:11 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-b7_D52-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:56:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:56:11 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-mXpyY4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:56:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:56:11 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-wNUXb9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:56:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:56:11 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-VNqBbx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:56:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:56:11 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-q8LTL0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:56:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:56:11 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-8EOUNf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:56:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:56:11 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-YUMDSE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:56:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:56:11 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-Nd1Z3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:56:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:56:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:56:12 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-NFXWso-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:56:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:56:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:56:12 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-zH9jmW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:56:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:56:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:56:12 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-7jAZtV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:56:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:56:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:56:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:56:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:56:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:56:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:56:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:56:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:56:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:56:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:14 INFO - Downloading blessings-1.6.tar.gz 15:56:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:56:14 INFO - Installing collected packages: blessings 15:56:14 INFO - Running setup.py install for blessings 15:56:15 INFO - Successfully installed blessings 15:56:15 INFO - Cleaning up... 15:56:15 INFO - Return code: 0 15:56:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:56:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:56:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:56:15 INFO - Reading from file tmpfile_stdout 15:56:15 INFO - Current package versions: 15:56:15 INFO - blessings == 1.6 15:56:15 INFO - blobuploader == 1.2.4 15:56:15 INFO - docopt == 0.6.1 15:56:15 INFO - manifestparser == 1.1 15:56:15 INFO - mozInstall == 1.12 15:56:15 INFO - mozcrash == 0.17 15:56:15 INFO - mozdebug == 0.1 15:56:15 INFO - mozdevice == 0.48 15:56:15 INFO - mozfile == 1.2 15:56:15 INFO - mozhttpd == 0.7 15:56:15 INFO - mozinfo == 0.9 15:56:15 INFO - mozleak == 0.1 15:56:15 INFO - mozlog == 3.2 15:56:15 INFO - moznetwork == 0.27 15:56:15 INFO - mozprocess == 0.22 15:56:15 INFO - mozprofile == 0.28 15:56:15 INFO - mozrunner == 6.11 15:56:15 INFO - mozscreenshot == 0.1 15:56:15 INFO - mozsystemmonitor == 0.3 15:56:15 INFO - moztest == 0.7 15:56:15 INFO - mozversion == 1.4 15:56:15 INFO - psutil == 3.1.1 15:56:15 INFO - requests == 1.2.3 15:56:15 INFO - wsgiref == 0.1.2 15:56:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:56:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:56:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:56:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:56:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:56:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:56:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:56:15 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 0x10b134a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b13a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f825b5bc120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afd8d30>, '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 0x10adfcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afcfb20>, '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.xb7lXzsVeE/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.Duk1YXzOl5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:56:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:56:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:56:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xb7lXzsVeE/Render', 15:56:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:56:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:56:15 INFO - 'HOME': '/Users/cltbld', 15:56:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:56:15 INFO - 'LOGNAME': 'cltbld', 15:56:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:56:15 INFO - 'MOZ_NO_REMOTE': '1', 15:56:15 INFO - 'NO_EM_RESTART': '1', 15:56:15 INFO - 'PAGER': '/bin/cat', 15:56:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:56:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:56:15 INFO - 'PWD': '/builds/slave/test', 15:56:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:56:15 INFO - 'SHELL': '/bin/bash', 15:56:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Duk1YXzOl5/Listeners', 15:56:15 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 15:56:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:56:15 INFO - 'USER': 'cltbld', 15:56:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:56:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:56:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:56:15 INFO - 'XPC_FLAGS': '0x0', 15:56:15 INFO - 'XPC_SERVICE_NAME': '0', 15:56:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:56:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:56:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:56:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:56:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:56:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:56:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:56:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:56:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:56:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:56:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:56:15 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:56:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:56:15 INFO - Cleaning up... 15:56:15 INFO - Return code: 0 15:56:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:56:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:56:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:56:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:56:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:56:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:56:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:56:15 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 0x10b134a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b13a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f825b5bc120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afd8d30>, '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 0x10adfcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afcfb20>, '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.xb7lXzsVeE/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.Duk1YXzOl5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:56:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:56:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:56:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xb7lXzsVeE/Render', 15:56:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:56:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:56:15 INFO - 'HOME': '/Users/cltbld', 15:56:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:56:15 INFO - 'LOGNAME': 'cltbld', 15:56:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:56:15 INFO - 'MOZ_NO_REMOTE': '1', 15:56:15 INFO - 'NO_EM_RESTART': '1', 15:56:15 INFO - 'PAGER': '/bin/cat', 15:56:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:56:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:56:15 INFO - 'PWD': '/builds/slave/test', 15:56:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:56:15 INFO - 'SHELL': '/bin/bash', 15:56:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Duk1YXzOl5/Listeners', 15:56:15 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 15:56:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:56:15 INFO - 'USER': 'cltbld', 15:56:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:56:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:56:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:56:15 INFO - 'XPC_FLAGS': '0x0', 15:56:15 INFO - 'XPC_SERVICE_NAME': '0', 15:56:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:56:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:56:15 INFO - Downloading/unpacking jsonschema 15:56:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:56:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:56:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:56:18 INFO - Downloading/unpacking functools32 (from jsonschema) 15:56:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:56:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:56:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:18 INFO - Downloading functools32-3.2.3-2.tar.gz 15:56:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:56:18 INFO - warning: no files found matching '*.txt' 15:56:18 INFO - no previously-included directories found matching 'build' 15:56:18 INFO - no previously-included directories found matching 'dist' 15:56:18 INFO - no previously-included directories found matching '.git*' 15:56:18 INFO - Installing collected packages: jsonschema, functools32 15:56:18 INFO - Running setup.py install for functools32 15:56:19 INFO - warning: no files found matching '*.txt' 15:56:19 INFO - no previously-included directories found matching 'build' 15:56:19 INFO - no previously-included directories found matching 'dist' 15:56:19 INFO - no previously-included directories found matching '.git*' 15:56:19 INFO - Successfully installed jsonschema functools32 15:56:19 INFO - Cleaning up... 15:56:19 INFO - Return code: 0 15:56:19 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:56:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:56:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:56:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:56:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:56:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:56:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:56:19 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 0x10b134a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b13a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f825b5bc120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afd8d30>, '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 0x10adfcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afcfb20>, '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.xb7lXzsVeE/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.Duk1YXzOl5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:56:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 15:56:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 15:56:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xb7lXzsVeE/Render', 15:56:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:56:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:56:19 INFO - 'HOME': '/Users/cltbld', 15:56:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:56:19 INFO - 'LOGNAME': 'cltbld', 15:56:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:56:19 INFO - 'MOZ_NO_REMOTE': '1', 15:56:19 INFO - 'NO_EM_RESTART': '1', 15:56:19 INFO - 'PAGER': '/bin/cat', 15:56:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:56:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:56:19 INFO - 'PWD': '/builds/slave/test', 15:56:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:56:19 INFO - 'SHELL': '/bin/bash', 15:56:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Duk1YXzOl5/Listeners', 15:56:19 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 15:56:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:56:19 INFO - 'USER': 'cltbld', 15:56:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:56:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:56:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:56:19 INFO - 'XPC_FLAGS': '0x0', 15:56:19 INFO - 'XPC_SERVICE_NAME': '0', 15:56:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:56:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:56:19 INFO - Downloading/unpacking flake8 15:56:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:56:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:56:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:21 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:56:21 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:56:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:56:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:56:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:22 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:56:22 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:56:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:56:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:56:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:22 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:56:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:56:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:56:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:56:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:56:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:56:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:56:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:56:23 INFO - no previously-included directories found matching 'docs/_build' 15:56:23 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:56:23 INFO - Running setup.py install for pep8 15:56:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:56:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:56:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:56:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:56:23 INFO - no previously-included directories found matching 'docs/_build' 15:56:23 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:56:23 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:56:23 INFO - Cleaning up... 15:56:23 INFO - Return code: 0 15:56:23 INFO - Running post-action listener: _resource_record_post_action 15:56:23 INFO - Running post-action listener: _start_resource_monitoring 15:56:23 INFO - Starting resource monitoring. 15:56:23 INFO - ##### 15:56:23 INFO - ##### Finished create-virtualenv step (success) 15:56:23 INFO - ##### 15:56:23 INFO - ##### 15:56:23 INFO - ##### Running install step. 15:56:23 INFO - ##### 15:56:23 INFO - Running pre-action listener: _resource_record_pre_action 15:56:23 INFO - Running main action method: install 15:56:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:56:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:56:23 INFO - Reading from file tmpfile_stdout 15:56:23 INFO - Detecting whether we're running mozinstall >=1.0... 15:56:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:56:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:56:23 INFO - Reading from file tmpfile_stdout 15:56:23 INFO - Output received: 15:56:23 INFO - Usage: mozinstall [options] installer 15:56:23 INFO - Options: 15:56:23 INFO - -h, --help show this help message and exit 15:56:23 INFO - -d DEST, --destination=DEST 15:56:23 INFO - Directory to install application into. [default: 15:56:23 INFO - "/builds/slave/test"] 15:56:23 INFO - --app=APP Application being installed. [default: firefox] 15:56:23 INFO - mkdir: /builds/slave/test/build/application 15:56:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:56:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 15:56:57 INFO - Reading from file tmpfile_stdout 15:56:57 INFO - Output received: 15:56:57 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:56:57 INFO - Running post-action listener: _resource_record_post_action 15:56:57 INFO - ##### 15:56:57 INFO - ##### Finished install step (success) 15:56:57 INFO - ##### 15:56:57 INFO - ##### 15:56:57 INFO - ##### Running run-tests step. 15:56:57 INFO - ##### 15:56:57 INFO - Running pre-action listener: _resource_record_pre_action 15:56:57 INFO - Running main action method: run_tests 15:56:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:56:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:56:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:56:57 INFO - 2016-05-17 15:56:57.453 screenresolution[1915:12776] starting screenresolution argv=screenresolution get 15:56:57 INFO - 2016-05-17 15:56:57.455 screenresolution[1915:12776] Display 0: 1600x1200x32@60 15:56:57 INFO - 2016-05-17 15:56:57.461 screenresolution[1916:12778] starting screenresolution argv=screenresolution list 15:56:57 INFO - Available Modes on Display 0 15:56:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:56:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:56:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:56:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:56:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:56:57 INFO - Intel Iris: 15:56:57 INFO - Chipset Model: Intel Iris 15:56:57 INFO - Type: GPU 15:56:57 INFO - Bus: Built-In 15:56:57 INFO - VRAM (Dynamic, Max): 1536 MB 15:56:57 INFO - Vendor: Intel (0x8086) 15:56:57 INFO - Device ID: 0x0a2e 15:56:57 INFO - Revision ID: 0x0009 15:56:57 INFO - Displays: 15:56:57 INFO - Display: 15:56:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:56:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:56:57 INFO - Display Serial Number: 1600x1200 60 15:56:57 INFO - Main Display: Yes 15:56:57 INFO - Mirror: Off 15:56:57 INFO - Online: Yes 15:56:57 INFO - Rotation: Supported 15:56:57 INFO - Return code: 0 15:56:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:56:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:56:57 INFO - Python 2.7.3 15:56:57 INFO - Return code: 0 15:56:57 INFO - grabbing minidump binary from tooltool 15:56:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:56:57 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 0x10afd8d30>, '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 0x10adfcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afcfb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:56:57 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:56:57 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 15:56:57 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:56:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:56:57 INFO - Return code: 0 15:56:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:56:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:56:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:56:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:56:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:56:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:56:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:56:58 INFO - Return code: 0 15:56:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Euvofsy6Q7GtCeCTPY3EKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0375', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:56:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Euvofsy6Q7GtCeCTPY3EKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0375 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:56:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xb7lXzsVeE/Render', 15:56:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:56:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:56:58 INFO - 'HOME': '/Users/cltbld', 15:56:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:56:58 INFO - 'LOGNAME': 'cltbld', 15:56:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:56:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:56:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:56:58 INFO - 'MOZ_NO_REMOTE': '1', 15:56:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:56:58 INFO - 'NO_EM_RESTART': '1', 15:56:58 INFO - 'PAGER': '/bin/cat', 15:56:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:56:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:56:58 INFO - 'PWD': '/builds/slave/test', 15:56:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:56:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:56:58 INFO - 'SHELL': '/bin/bash', 15:56:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Duk1YXzOl5/Listeners', 15:56:58 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 15:56:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:56:58 INFO - 'USER': 'cltbld', 15:56:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:56:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:56:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:56:58 INFO - 'XPC_FLAGS': '0x0', 15:56:58 INFO - 'XPC_SERVICE_NAME': '0', 15:56:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:56:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Euvofsy6Q7GtCeCTPY3EKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0375', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:56:59 INFO - mozversion application_buildid: 20160517141544 15:56:59 INFO - mozversion application_changeset: f3f2fa1d7eed5a8262f6401ef18ff8117a3ce43e 15:56:59 INFO - mozversion application_display_name: Nightly 15:56:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:56:59 INFO - mozversion application_name: Firefox 15:56:59 INFO - mozversion application_remotingname: firefox 15:56:59 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:56:59 INFO - mozversion application_vendor: Mozilla 15:56:59 INFO - mozversion application_version: 49.0a1 15:56:59 INFO - mozversion platform_buildid: 20160517141544 15:56:59 INFO - mozversion platform_changeset: f3f2fa1d7eed5a8262f6401ef18ff8117a3ce43e 15:56:59 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:56:59 INFO - mozversion platform_version: 49.0a1 15:56:59 INFO - using testdate: 1463525819 15:56:59 INFO - actual date: 1463525819 15:56:59 INFO - starting webserver on 'localhost:51166' 15:56:59 INFO - SUITE-START | Running 2 tests 15:56:59 INFO - TEST-START | dromaeo_css 15:56:59 INFO - operating with platform_type : mac_ 15:56:59 INFO - Initialising browser for dromaeo_css test... 15:56:59 INFO - TEST-INFO | started process 1934 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/tmpujrJER/profile http://localhost:51166/getInfo.html) 15:57:01 INFO - PROCESS | 1934 | __metrics Screen width/height:1600/1200 15:57:01 INFO - PROCESS | 1934 | colorDepth:24 15:57:01 INFO - PROCESS | 1934 | Browser inner width/height: 1024/689 15:57:01 INFO - PROCESS | 1934 | __metrics 15:57:01 INFO - PROCESS | 1934 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:57:01 ERROR - PROCESS | 1934 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:57:02 INFO - TEST-INFO | 1934: exit 0 15:57:02 INFO - Browser initialized. 15:57:02 INFO - Running cycle 1/1 for dromaeo_css test... 15:57:02 INFO - TEST-INFO | started process 1936 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/tmpujrJER/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:57:14 INFO - PROCESS | 1936 | RSS: Main: 238546944 15:57:14 INFO - PROCESS | 1936 | 15:58:05 INFO - PROCESS | 1936 | 1463525885395 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] 16:00:15 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:51166/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51166/tests/dromaeo/cssquery-ext.html) 16:00:17 INFO - PROCESS | 1936 | RSS: Main: 290533376 16:00:17 INFO - PROCESS | 1936 | 16:03:20 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:51166/tests/dromaeo/cssquery-ext.html (next: http://localhost:51166/tests/dromaeo/cssquery-jquery.html) 16:03:20 INFO - PROCESS | 1936 | RSS: Main: 372604928 16:03:20 INFO - PROCESS | 1936 | 16:06:22 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:51166/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51166/tests/dromaeo/cssquery-mootools.html) 16:06:23 INFO - PROCESS | 1936 | RSS: Main: 367689728 16:06:23 INFO - PROCESS | 1936 | 16:09:25 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:51166/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51166/tests/dromaeo/cssquery-prototype.html) 16:09:26 INFO - PROCESS | 1936 | RSS: Main: 365789184 16:09:26 INFO - PROCESS | 1936 | 16:12:29 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:51166/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51166/tests/dromaeo/cssquery-yui.html) 16:12:30 INFO - PROCESS | 1936 | RSS: Main: 332914688 16:12:30 INFO - PROCESS | 1936 | 16:15:32 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:51166/tests/dromaeo/cssquery-yui.html (next: http://localhost:51166/tests/dromaeo/cssquery-dojo.html) 16:15:33 INFO - PROCESS | 1936 | RSS: Main: 372576256 16:15:33 INFO - PROCESS | 1936 | 16:15:33 INFO - PROCESS | 1936 | __start_tp_report 16:15:33 INFO - PROCESS | 1936 | _x_x_mozilla_page_load 16:15:33 INFO - PROCESS | 1936 | _x_x_mozilla_page_load_details 16:15:33 INFO - PROCESS | 1936 | |i|pagename|runs| 16:15:33 INFO - PROCESS | 1936 | |0;dojo.html;3217.3726123405936;3402.2515046689737;3427.3830710472107;3430.0224436035733;3431.468122441021;5623.915641265374;5627.380988091307;5663.745131469083;5720.113382425726;5802.12968054792;5680.460356266159;5693.28833895763;5706.201131841535;5755.68343741094;5757.740901659436;5041.092603331399;5098.949010509903;5160.948390516103;5166.560842328407;5255.973720131394;5233.555147812422;5238.79044838206;5268.157094864822;5276.314079169702;5317.73411329432;5047.242913562976;5113.642045056848;5120.564751996085;5129.897402051956;5138.665986710852;4325.653947684178;5086.237064440326;5088.898222035538;5095.006473737622;5157.9941911327305;5102.719350435725;5132.307138536325;5150.922736158961;5153.665011774223;5161.741912904377;5495.835124946258;5545.861353466155;5596.944030559724;5609.5792815539;5632.07070833312;6184.628922264678;6186.84532886677;6224.688765561704;6228.5951413158;6259.029850373199;6073.689983546856;6266.749330026793;6301.243850737895;6316.526260530432;6324;5602.831915042555;5744.540436765049;5826.504747096459;5830.300363956352;5836.795712150054;6291.622502649764;6312.52656050798;6318.62088274705;6319.8104056878365;6324.335944725737;5976.402359764076;6095.420935011167;6097.17688112108;6106.5114790817515;6138.447539721446;6129.969350153221;6143.139960405547;6186.81439556814;6215.906761398583;6253.249610046732;6112.290716697417;6197.752089916443;6253.312135665074;6258.280297765724;6259.374062593795;6236.719347629277;6266.373362663697;6268.09112678659;6281.277653069864;6298.7480500780375;4790.257510085942;4830.637702172351;4857.611391088775;4918.065567542156;4921.9507804921495;4378.627816635558;4385.210662080793;4392.253316936129;4420.469543654782;4472.08322293929;5720.456556627197;5727.77088916447;5741.741621627037;5768.451997060357;5810.941890581124;6069.787557435468;6154.261488621304;6317.989121740499;6331.081993110973;6351.904721429182;5625.09998400254;5682.232898558725;5689.715514224272;5700.25896633435;5736.885262294731;5348.598855085884;5388.326459192601;5393.6224464287125;5395.73021348931;5405.324334458192;6081.5134789217645;6088.665123418077;6316.242050953915;6338.746450141943;6382.106505089291;5872.794452194153;5899;5926.111083337534;5934.258217722785;5943.167956486009;6238.875222495434;6296.937030629635;6307.495400368051;6350.269718982377;6359;6203.2556093269095;6233.158523599256;6247.187865577627;6247.468965137832;6272.341404152681;5234.33837683653;6109.677964500999;6307.180066591313;6339.96830015847;6356.904646430215;6076.756929722762;6103.511719062553;6128.979283094455;6133.785317513865;6177.752889884354;6105.84735381619;6123.836471070483;6131.515109163381;6135.723892424762;6148.692565371624;4611.930821037754;5737.799177028784;5802.970985145047;5954.583179177416;5960.0761881908375;5971.701414929149;6017.852001151666;6055.72749226277;6103.481204097701;6141.048137538689;5865.677387743715;5918.940810592011;6013.759449621966;6025.87948241024;6062.120992455981 16:15:33 INFO - PROCESS | 1936 | |1;ext.html;505.2320472881223;513.7354262554785;514.3442111308082;554.0525701051203;567.452408425869;30880;31665.841670791644;42888.356674649905;44090.897727556745;44189.558104418946;45432;45891.541084589146;45912.852178695546;45914.62256132313;45930.7703461483;7517.774466765978;8527.31781457485;9631.711048668516;9692.12770850625;9886.802263954753;8700.608472618716;8764.60559274831;8765.956170219171;8769.429987050822;11600.477978490988;11285.943570282137;11553.422328883564;11555.30668159909;11570.132240081986;11581.536738530449;11744.588939387124;11815.291082535075;11819.34993575353;11821.758715334896;11833.881661183364;8574;11444.599439019594;11565.363904985223;11759.7060073498;11780.764384712258;41960.370594441105;42467;42519.57480425218;42524.78737606292;42528;3266.7549933755063;3367.1582104473882;3399.42209824328;3455.1362159460136;3692.5753538342897;28604.284892877637;28841.56737648937;29122;29471.852640736874;30183.547246791317;2720.8367497950185;2739.8630068496495;2762.350847550824;2805.6773471050888;2871.6984716604666;544242;550523;551194;551259;552621.9999999959;65845;66845.66577167083;66957.33042669512;67162.66418667875;67247.32752672509;70655.64672176709;70828.64585677038;70844;70917.64541177364;71061;16407.753883691756;16876.409325673787;16888.324467021426;16913.985160890385;16927.40754073626;11011.174161937886;12741.10812243134;12831.679208019874;12837.743245135045;12864.871351286367;17415.738763918296;17817.821821781617;19765.40703778889;20335;20348;8246.752597422086;8258.050324212672;8268.710595129141;8295.294899933573;8367.497950123012;982.3074732313702;1016.9694909152737;1031.7214352124884;1086.8315411111291;1090.0571006079683;27950.86024569905;30497.968798347232;30694;30881.892347387715;31407.685923140933;1093.436880006797;1098.242212873115;1152.4295473740551;1179.941002949849;1296.1445446005591;14382.928085359506;15360;15391.230438478031;15636.765448518281;15672;746100;1023393;1046520;1047493;1047723.7613812034;64654;64723.67638161779;65247.69504609976;65358;65510.01734973884;1338.444545513601;1387.6322774464977;1489.2851431312813;1505.164633628325;1530.3572499550216;28968;30026.849865751406;30366.93715719939;31945.520817187295;32113.8394308027;59802;60017.39982600118;60606.39393606008;60661.39338606734;60771.784564307585;655562;659428;659487;661311;662563;24160.63759043651;26849.463010740066;27197.592036119077;27828.72171278261;28221.858890705415;46700.06599868052;47080.7645961768;47210;47422.288665670734;47976.7601161992;34248.45881935269;34797.47803782894;37506.812465937495;38213.42679859749;38890;37452;37599.81200093982;38046.61953380542;38056.287467064605;38540.03649908775;38201.61798382092;38445.807770960964;39184;39278;39379.80310098431;32554;33188.642427621286;33400.66599334073;33773.15567110842;33794.94460664736;24706.629400558646;25019.74980250174;25144;25163.87418062898;25332.366690832878 16:15:33 INFO - PROCESS | 1936 | |2;jquery.html;397.2273927211574;401.2396508616174;401.4901075633944;406.2301937827816;424.5860286220933;20007.799922000777;20248.696269555967;21101.788982110174;21128.57742845144;21262.255821046267;19810.504737381572;20131.993400329997;21195.364139075813;21429.785702142974;21459.034343454543;5792.855178620547;6017.48851347636;6051.27384713835;6058.666773327465;6066.817995460143;5776.653400795944;6000.75996960121;6014.578979471439;6070.817875463743;6131.049687298453;6111.419415155553;6150.446459818615;6152.784652537162;6154.046122850943;6157.661328626924;5982.880342393128;6062.272527296708;6135.9079613805825;6137.539684523634;6147.631142131487;5920.437558431942;5932.169496270526;5942.465178133989;5946.583739138262;5955.166276721263;14141.717165656732;14212.360443779951;22308.776912230995;25083.87458062698;26437.20688379352;1950.2394066314148;2048.24215040436;2095.829299693614;2104.894755262231;2325.267540724666;9905.455199963999;10292.425623477717;10392.896071039268;10863.67408977732;14880.925595372062;1492.9029613075115;1680.15992003998;1681.6888875557938;1705.5224537129623;1721.6298495823457;97321.51339243258;125711;132692.33653831764;133281;134660.9800852988;11629.011534019703;11815.11386646005;11860.525578976916;11909.52361905514;11998.220115692626;15327;15385.230738463031;15386.230688465532;15398;15459.458918937784;8767.693130740521;8827.337949653802;8838.690645827364;8865.202131808168;8938.24024957886;7913.802154946057;7952.204779522117;8663.263622592025;8685.956570217108;8807.07525709804;4695.694779839299;4722.709485899336;4726;4728.1252968201;4799.568038876518;1938.9127489263017;1988.2047181127375;1997.8701384409967;1998.5703073838904;1998.8600797944005;913.2237598041744;948.672707915768;954.5418199264253;975.9024097590326;985.0395864032511;18589.907050464662;19521.804781952284;19781.208751649774;20366;21005.36983890486;790.8497385496737;938.5635679408971;958.5303201431249;979.4661909259471;995.7560397702609;10971.286866353661;11253.987141157171;11265.831012534818;11268.774624507463;11273;606514.96742516;606735;615021;627372;628993;125605;125778;130762;132240;136231.31884340514;1078.0297732041226;1090.1823632275793;1126.099120703434;1148.0585919545892;1150.6375491720082;15629.071883983084;17588.91205543964;18672;18925.526861828563;18953.43139705811;43979.560204397545;58161;58200;58879.82240355255;58918.70540647269;67805.3219467799;80943;82412.76380854173;84219.15780842114;86680;5892;7664.31021208094;7681.500702454323;7702.884456733042;8415.032271288881;9414.67048653294;10300.433476158883;10567.418791966516;12859.29273889945;13318.600441986757;6350.968245158745;6818.590884546943;11105.777884442105;11354.54581816718;11456.770864582828;6726.461883049442;7907.367410607253;10876.836847447137;10977.725556861142;11021.669349919515;9738.318317717974;11246.606368777053;11389.348544461172;11891.345975971404;11929.880701193224;7556.58438785872;7631.771046868603;7739.613019348897;7771;8016.118226995142;8853.601587928688;9213.447193168431;9280.257579393767;10401.94799026;10789.514471848628 16:15:33 INFO - PROCESS | 1936 | |3;mootools.html;1454.3746189138667;1629.853313201812;1658.5273197138813;1924.8267655910965;2032.9898350508254;7931.801704957372;8623.956880215606;8894.955525222382;8913.910860891388;9016.909830901688;8599.312055035598;8828.514431706253;8836.425632333894;8837.381383303155;8839.381243312971;2455.3125124964995;2482.9379265518414;2488.5645012122895;2488.8382255153447;2489.601663733803;2446.449548851512;2505.1232068775876;2505.924822255335;2507.761762632556;2520.823542352036;2453.092355828346;2475.938101547467;2495.6506089147533;2506.548821212181;2507.1726330310953;2437.5856104462196;2461.938451538708;2468.814838887082;2469.8271121021535;2476.73994230605;2433.987830060856;2451.705795304557;2467.4324905271887;2467.605183170684;2470.28361775085;8591.355648326338;8778.122187781235;8865.537186364241;8872.024077351487;8878.378513504058;5315;5451.236826844245;5454.863628409282;5455.208994695787;5460.535854452376;3194.9201269968203;3438.5186073949667;3464.497647841074;3514.490398892172;3591.2278860044953;1023.2683631203726;1036;1036.7812880435797;1038.055369613648;1039.8440233964966;11676.941615291955;11718.765624687543;11738.471768770425;11760.88239117615;11769.41152942358;4464.17412778637;4584.724916505021;4589.036302376471;4747.643926705511;4759.405074365704;9321.487318197429;9325.020872808393;9334.859977100348;9334.953325233331;9337.95331023354;5317.202419637086;5323.733813309319;5324.201369794562;5324.361076670824;5327.653702509325;4487.618552423015;4634.976825115853;4808.783604737795;4929.964707411484;4967.503249675004;3878.5733569307918;3897.649211570972;3970.7617542947514;3988.3219852625125;4020.155767288902;1209.7701436726993;1229.8093795461718;1234.6789834643057;1253.2981530343036;1255.1151438236022;663.797541749771;822.9547374894438;886.0297973718767;916.5371487398821;929.4887811703671;5811.854703632443;6008.09878518217;6024.1566180734735;6082.391760823883;6231.470325022424;810.5217921426387;956.6795123633522;967.5887747707337;979.9167070799062;1003.4330603209163;3913.7064720146104;4047.4738284022887;4066.186762647423;4096.180763847243;4137.648299894482;10504.264701470824;10929.234953553176;11027.724306892393;11055.502502387411;11060;10945.288556243819;10964.28732132409;10975.231733778719;10977.176711746652;11001;7832.882506762403;8173.305269052078;8224.259816616413;8234.835303293901;8335.208155225122;6042.607230530001;6085.786997455068;6131.356207598138;6209.937900620936;6294.055891616111;9124.676921846429;9999.500024998866;10264.64073757415;10277.229207809594;10285.228607854291;9712.611495540383;10014.499275036365;10138.847917281098;10409.635662751767;10415.114715249289;7619.428542859197;7702.691892324245;7754.961225193838;7804.453688241996;7812.218778122142;8328.45865018796;8410.705625303084;8416.2004609562;8418.494890306602;8460.450070745383;6145.446909778141;7449.702011919462;7450;7520.247975202436;7723.420743444377;6284.717187726655;6837.247902730795;7137.929310603244;7505.737299194502;7576.204498527575;7052.0832291801735;7149.177844547736;7154.892676609751;7156.105486814148;7157.105361829771;4397.758123303249;4860;4949.455559888337;4958.429780575136;5194.35070616173;4977.900441991213;5034.798608055637;5218.556422704112;5250.789968401374;5285.524302812765 16:15:33 INFO - PROCESS | 1936 | |4;prototype.html;308.42478777080737;313.9811611303322;324.85056873838033;328.3957518166573;329.3577523828535;4945.8516244512675;5353.866153346168;5362.27609272748;5410.675359478424;5412.377576578695;5045.949540504603;5104.208847628614;5217.530422261995;5222.373315202171;5248.921266181001;3870.5935876732888;4042.6159514846045;4043.9780109945027;4104.343305071175;4127.6585109839425;4088.2232375848644;4109;4120.175964807036;4128.3601041838565;4131.5248746394245;3321.4021476134285;3858.594847540996;3925.5878132796074;4068.0643452006057;4105.67154627629;3430.9142271443166;3574.1064733816547;3578.8210589470423;3986.7009974251755;4036.4147198656324;3427.8628854845774;3557.3280558137635;3581.194231297963;3589.5513060867393;3601.603823579404;4113.773742444165;4454.35411865281;4760.357351757538;4879.268109783526;5010.874728131789;3989.40158976153;4593.438231001475;4625.32932724753;4628.467726211461;4664.790084446208;4845.588125009378;5045.016221836743;5108.770105345268;5642.971785141089;5651.8304450866535;3469.2367679110557;3892.163184915257;3899.298126337258;3901.224942651087;3911.471951286568;4777.402824646919;5478.890422191534;5865.736041878125;5880.705964701748;5930.347661757246;3460.3252365788935;3842.9624001519433;3881.650651441327;3884.669803066771;3887.475190849256;3980.800959951991;3995.4606128172904;4000.8399664013696;4001.1797581495725;4004.5594984551676;4380.080183161572;4407.933880991788;4583.839565615188;4604.8388306409115;4619.838305659353;5285.814996475105;5562.193481945095;5629.8592535186945;5636.830895073154;5649.774009039593;1133.6032388663903;1328.0504439325734;1352.3508715816463;1359.7960305953989;1360.31984007996;765.6439755513713;811.736185739637;824.4229039672254;900.7883147460342;915.9358844880927;4684.320773487893;4800.927986080212;5135.35808023997;5234;5238.292830467914;5391.436483419764;5522.558195344443;5530.640508366943;5546.889062218733;5763.221965034751;4386.8683939481225;4763.118823017753;5040.521150490697;5095.694258344512;5137.255098010767;2533.847099569691;2695.167286245372;2786.9303267418477;2988.2678743707497;2989.1331513860737;5088.796448142033;5335.973320133452;5948.226730525091;5970.910436343458;5986.311574168939;4751.738654374042;5371.811986580529;5574.275344205227;5810.970945145332;5870.182312490028;5671.546276297886;5901;5936.714364969518;5938.713595265767;6008.669523176267;5278.60410469224;5494.752736126964;5516.641418307797;5518.696471693935;5748;4769.9046019080115;5086.491350865032;5326.627136100436;5461.617686762047;5661.348944871393;4584.15193189261;5487.094629386106;5513.917291240555;5963.940360596339;5972.223610930551;3544.5746510417885;3623.583287921923;3899.239648268674;3902.57071722116;3930.7051971102856;4634.490206077397;5453.781848726166;5684.7157642118555;5703.771849125988;5723.5707321950185;5168.250603662523;5244.685318880879;5653.236813030353;5680.573956953162;5710.2862142232225;5163.786510170032;5307.124324486416;5361.632783640147;5579.442055794388;5591.4688104629995;4581.9232480367755;5074.31496747949;5283.735813209248;5363.865903352447;5465.836024919259;4762.476127626028;5550.417206193291;5605.523530499953;5632.9436705632415;5635.9436405635415;4764.809407623795;5498.917516237179;5531.834044978657;5532.612717109763;5557.277553918127 16:15:33 INFO - PROCESS | 1936 | |5;yui.html;662.9138212032436;703.5251205436332;741.8182545276404;754.7811134770917;758.2493331601715;15049.62375940602;15208.847911520881;15339;15576.766348504767;15744.763828542553;13703.040787144904;13847.723045539084;13872.098313609607;13876.791848122286;13879.30603469825;723.8407550338943;746.6789280609336;905.5472263868066;929.2890938432105;930.692871352452;894.2220268366531;925.5973651461602;928.9442633442015;934.700895713372;936.166811537732;821.8520666280098;981.4503877828404;984.6652138272985;987.9407235565853;990.6384169778015;750.8385697075128;950.8336041192764;977.9119879210905;982.219135787051;982.7002764156929;721.5670597641373;723.1719680965265;724.1527412926888;724.2540183610889;724.2829598697305;14955.177465239407;15006.849931500654;15023;15041.323140458706;15049.247537623185;12334.259944403364;12360.567380141742;12450.937745311216;12692.365381730877;12699.619011429579;10676.572937082507;10923.180761442924;10936.835947460795;11130.666080017612;11363.829542556869;4028.4158796974275;4031.4759081319535;4052.5744796796503;4061.8578349757618;4300.935485967713;81151.59424202841;83950;87439.25121497535;87667;87734.56132719359;9319.440833550008;9334.253259739204;9396.812063758687;9487.66793162236;9900.009999000042;20042.498937526387;20876.895615521826;21497.57004859894;21555.78444215569;21607.351779446642;7320.597367144858;7353.522021068614;7413.666385012687;7481.85036299271;7587.772366829004;3849.095462566295;3869.845206191721;4004.1591265833918;4022.5374081980362;4025.4163146343617;3576.2668652926086;3709.109813644706;3711.0351308659942;3716.535433070866;3727.1800203955086;637.6238019568477;649.8830210562049;653;655.0926966151914;658.7233361987977;493.50402845140405;537.3337062043039;594.1592646405375;617.6880675259056;628.3402427451158;7214.170370407365;8088.9595552021865;8170.264676179172;8284.295834853985;8415.579221038923;583.6848102024859;600.504583718434;612.935641757618;616.5283558078074;620.2804746494045;3874.360730479439;3964.5044369453817;4001.3197756381487;4038.5759495253164;4042.535108462506;87272;88559.55720221357;88790;92946;94876;82348;84056;85706.57146714226;86801;87906.56046719853;68534;70351.64824175846;72202.27797722061;76254.09364765925;78051.60974195093;8280;8292.17078292166;8304.54325012106;8467.745967620982;8599.828003440021;34925;41481;45152.54847451615;45982.54017459782;48166;61616.69191654013;63913.7217255643;64096;64437;64444.67777661081;9430.669926552811;11259.155563332619;11497.712557186138;11595.768084638092;11770.176087674123;11851.940740296242;12167.33079680626;12497.875021250034;12498.187617805177;13093.934530327288;7652.80867978305;11431.714207144887;11618.5352585899;11839.881601183877;11954.70113247141;11562.390010063078;11568.190226684388;11734;12526.060545458944;12581.993440524919;11224;11408;11417.08043694141;11577.536898524302;11589.942050289694;8283.710070147516;9501;9511.61953521879;9530.046995300414;9840.803183936137;9633.277504187074;9678.70963871085;9864.21086313079;9895.158911492605;10020.24848136378 16:15:33 INFO - PROCESS | 1936 | __end_tp_report 16:15:33 INFO - PROCESS | 1936 | __start_cc_report 16:15:33 INFO - PROCESS | 1936 | _x_x_mozilla_cycle_collect,1369 16:15:33 INFO - PROCESS | 1936 | __end_cc_report 16:15:33 INFO - PROCESS | 1936 | __startTimestamp1463526933254__endTimestamp 16:15:33 INFO - PROCESS | 1936 | 16:15:33 INFO - PROCESS | 1936 | ------- Summary: start ------- 16:15:33 INFO - PROCESS | 1936 | Number of tests: 6 16:15:33 INFO - PROCESS | 1936 | 16:15:33 INFO - PROCESS | 1936 | [#0] dojo.html Cycles:165 Average:5706.40 Median:5918.94 stddev:656.97 (11.1%) stddev-sans-first:629.30 16:15:33 INFO - PROCESS | 1936 | Values: 3217.4 3402.3 3427.4 3430.0 3431.5 5623.9 5627.4 5663.7 5720.1 5802.1 5680.5 5693.3 5706.2 5755.7 5757.7 5041.1 5098.9 5160.9 5166.6 5256.0 5233.6 5238.8 5268.2 5276.3 5317.7 5047.2 5113.6 5120.6 5129.9 5138.7 4325.7 5086.2 5088.9 5095.0 5158.0 5102.7 5132.3 5150.9 5153.7 5161.7 5495.8 5545.9 5596.9 5609.6 5632.1 6184.6 6186.8 6224.7 6228.6 6259.0 6073.7 6266.7 6301.2 6316.5 6324.0 5602.8 5744.5 5826.5 5830.3 5836.8 6291.6 6312.5 6318.6 6319.8 6324.3 5976.4 6095.4 6097.2 6106.5 6138.4 6130.0 6143.1 6186.8 6215.9 6253.2 6112.3 6197.8 6253.3 6258.3 6259.4 6236.7 6266.4 6268.1 6281.3 6298.7 4790.3 4830.6 4857.6 4918.1 4922.0 4378.6 4385.2 4392.3 4420.5 4472.1 5720.5 5727.8 5741.7 5768.5 5810.9 6069.8 6154.3 6318.0 6331.1 6351.9 5625.1 5682.2 5689.7 5700.3 5736.9 5348.6 5388.3 5393.6 5395.7 5405.3 6081.5 6088.7 6316.2 6338.7 6382.1 5872.8 5899.0 5926.1 5934.3 5943.2 6238.9 6296.9 6307.5 6350.3 6359.0 6203.3 6233.2 6247.2 6247.5 6272.3 5234.3 6109.7 6307.2 6340.0 6356.9 6076.8 6103.5 6129.0 6133.8 6177.8 6105.8 6123.8 6131.5 6135.7 6148.7 4611.9 5737.8 5803.0 5954.6 5960.1 5971.7 6017.9 6055.7 6103.5 6141.0 5865.7 5918.9 6013.8 6025.9 6062.1 16:15:33 INFO - PROCESS | 1936 | 16:15:33 INFO - PROCESS | 1936 | [#1] ext.html Cycles:180 Average:84816.76 Median:33784.05 stddev:204264.74 (604.6%) stddev-sans-first:204739.66 16:15:33 INFO - PROCESS | 1936 | Values: 505.2 513.7 514.3 554.1 567.5 30880.0 31665.8 42888.4 44090.9 44189.6 45432.0 45891.5 45912.9 45914.6 45930.8 7517.8 8527.3 9631.7 9692.1 9886.8 8700.6 8764.6 8766.0 8769.4 11600.5 11285.9 11553.4 11555.3 11570.1 11581.5 11744.6 11815.3 11819.3 11821.8 11833.9 8574.0 11444.6 11565.4 11759.7 11780.8 41960.4 42467.0 42519.6 42524.8 42528.0 3266.8 3367.2 3399.4 3455.1 3692.6 28604.3 28841.6 29122.0 29471.9 30183.5 2720.8 2739.9 2762.4 2805.7 2871.7 544242.0 550523.0 551194.0 551259.0 552622.0 65845.0 66845.7 66957.3 67162.7 67247.3 70655.6 70828.6 70844.0 70917.6 71061.0 16407.8 16876.4 16888.3 16914.0 16927.4 11011.2 12741.1 12831.7 12837.7 12864.9 17415.7 17817.8 19765.4 20335.0 20348.0 8246.8 8258.1 8268.7 8295.3 8367.5 982.3 1017.0 1031.7 1086.8 1090.1 27950.9 30498.0 30694.0 30881.9 31407.7 1093.4 1098.2 1152.4 1179.9 1296.1 14382.9 15360.0 15391.2 15636.8 15672.0 746100.0 1023393.0 1046520.0 1047493.0 1047723.8 64654.0 64723.7 65247.7 65358.0 65510.0 1338.4 1387.6 1489.3 1505.2 1530.4 28968.0 30026.8 30366.9 31945.5 32113.8 59802.0 60017.4 60606.4 60661.4 60771.8 655562.0 659428.0 659487.0 661311.0 662563.0 24160.6 26849.5 27197.6 27828.7 28221.9 46700.1 47080.8 47210.0 47422.3 47976.8 34248.5 34797.5 37506.8 38213.4 38890.0 37452.0 37599.8 38046.6 38056.3 38540.0 38201.6 38445.8 39184.0 39278.0 39379.8 32554.0 33188.6 33400.7 33773.2 33794.9 24706.6 25019.7 25144.0 25163.9 25332.4 16:15:33 INFO - PROCESS | 1936 | 16:15:33 INFO - PROCESS | 1936 | [#2] jquery.html Cycles:180 Average:35695.03 Median:401.36 stddev:103142.24 (25697.9%) stddev-sans-first:103397.52 16:15:33 INFO - PROCESS | 1936 | Values: 397.2 401.2 401.5 406.2 424.6 20007.8 20248.7 21101.8 21128.6 21262.3 19810.5 20132.0 21195.4 21429.8 21459.0 5792.9 6017.5 6051.3 6058.7 6066.8 5776.7 6000.8 6014.6 6070.8 6131.0 6111.4 6150.4 6152.8 6154.0 6157.7 5982.9 6062.3 6135.9 6137.5 6147.6 5920.4 5932.2 5942.5 5946.6 5955.2 14141.7 14212.4 22308.8 25083.9 26437.2 1950.2 2048.2 2095.8 2104.9 2325.3 9905.5 10292.4 10392.9 10863.7 14880.9 1492.9 1680.2 1681.7 1705.5 1721.6 97321.5 125711.0 132692.3 133281.0 134661.0 11629.0 11815.1 11860.5 11909.5 11998.2 15327.0 15385.2 15386.2 15398.0 15459.5 8767.7 8827.3 8838.7 8865.2 8938.2 7913.8 7952.2 8663.3 8686.0 8807.1 4695.7 4722.7 4726.0 4728.1 4799.6 1938.9 1988.2 1997.9 1998.6 1998.9 913.2 948.7 954.5 975.9 985.0 18589.9 19521.8 19781.2 20366.0 21005.4 790.8 938.6 958.5 979.5 995.8 10971.3 11254.0 11265.8 11268.8 11273.0 606515.0 606735.0 615021.0 627372.0 628993.0 125605.0 125778.0 130762.0 132240.0 136231.3 1078.0 1090.2 1126.1 1148.1 1150.6 15629.1 17588.9 18672.0 18925.5 18953.4 43979.6 58161.0 58200.0 58879.8 58918.7 67805.3 80943.0 82412.8 84219.2 86680.0 5892.0 7664.3 7681.5 7702.9 8415.0 9414.7 10300.4 10567.4 12859.3 13318.6 6351.0 6818.6 11105.8 11354.5 11456.8 6726.5 7907.4 10876.8 10977.7 11021.7 9738.3 11246.6 11389.3 11891.3 11929.9 7556.6 7631.8 7739.6 7771.0 8016.1 8853.6 9213.4 9280.3 10401.9 10789.5 16:15:33 INFO - PROCESS | 1936 | 16:15:33 INFO - PROCESS | 1936 | [#3] mootools.html Cycles:180 Average:5752.58 Median:4962.97 stddev:3196.06 (64.4%) stddev-sans-first:3188.71 16:15:33 INFO - PROCESS | 1936 | Values: 1454.4 1629.9 1658.5 1924.8 2033.0 7931.8 8624.0 8895.0 8913.9 9016.9 8599.3 8828.5 8836.4 8837.4 8839.4 2455.3 2482.9 2488.6 2488.8 2489.6 2446.4 2505.1 2505.9 2507.8 2520.8 2453.1 2475.9 2495.7 2506.5 2507.2 2437.6 2461.9 2468.8 2469.8 2476.7 2434.0 2451.7 2467.4 2467.6 2470.3 8591.4 8778.1 8865.5 8872.0 8878.4 5315.0 5451.2 5454.9 5455.2 5460.5 3194.9 3438.5 3464.5 3514.5 3591.2 1023.3 1036.0 1036.8 1038.1 1039.8 11676.9 11718.8 11738.5 11760.9 11769.4 4464.2 4584.7 4589.0 4747.6 4759.4 9321.5 9325.0 9334.9 9335.0 9338.0 5317.2 5323.7 5324.2 5324.4 5327.7 4487.6 4635.0 4808.8 4930.0 4967.5 3878.6 3897.6 3970.8 3988.3 4020.2 1209.8 1229.8 1234.7 1253.3 1255.1 663.8 823.0 886.0 916.5 929.5 5811.9 6008.1 6024.2 6082.4 6231.5 810.5 956.7 967.6 979.9 1003.4 3913.7 4047.5 4066.2 4096.2 4137.6 10504.3 10929.2 11027.7 11055.5 11060.0 10945.3 10964.3 10975.2 10977.2 11001.0 7832.9 8173.3 8224.3 8234.8 8335.2 6042.6 6085.8 6131.4 6209.9 6294.1 9124.7 9999.5 10264.6 10277.2 10285.2 9712.6 10014.5 10138.8 10409.6 10415.1 7619.4 7702.7 7755.0 7804.5 7812.2 8328.5 8410.7 8416.2 8418.5 8460.5 6145.4 7449.7 7450.0 7520.2 7723.4 6284.7 6837.2 7137.9 7505.7 7576.2 7052.1 7149.2 7154.9 7156.1 7157.1 4397.8 4860.0 4949.5 4958.4 5194.4 4977.9 5034.8 5218.6 5250.8 5285.5 16:15:33 INFO - PROCESS | 1936 | 16:15:33 INFO - PROCESS | 1936 | [#4] prototype.html Cycles:180 Average:4486.84 Median:5045.48 stddev:1370.63 (27.2%) stddev-sans-first:1338.12 16:15:33 INFO - PROCESS | 1936 | Values: 308.4 314.0 324.9 328.4 329.4 4945.9 5353.9 5362.3 5410.7 5412.4 5045.9 5104.2 5217.5 5222.4 5248.9 3870.6 4042.6 4044.0 4104.3 4127.7 4088.2 4109.0 4120.2 4128.4 4131.5 3321.4 3858.6 3925.6 4068.1 4105.7 3430.9 3574.1 3578.8 3986.7 4036.4 3427.9 3557.3 3581.2 3589.6 3601.6 4113.8 4454.4 4760.4 4879.3 5010.9 3989.4 4593.4 4625.3 4628.5 4664.8 4845.6 5045.0 5108.8 5643.0 5651.8 3469.2 3892.2 3899.3 3901.2 3911.5 4777.4 5478.9 5865.7 5880.7 5930.3 3460.3 3843.0 3881.7 3884.7 3887.5 3980.8 3995.5 4000.8 4001.2 4004.6 4380.1 4407.9 4583.8 4604.8 4619.8 5285.8 5562.2 5629.9 5636.8 5649.8 1133.6 1328.1 1352.4 1359.8 1360.3 765.6 811.7 824.4 900.8 915.9 4684.3 4800.9 5135.4 5234.0 5238.3 5391.4 5522.6 5530.6 5546.9 5763.2 4386.9 4763.1 5040.5 5095.7 5137.3 2533.8 2695.2 2786.9 2988.3 2989.1 5088.8 5336.0 5948.2 5970.9 5986.3 4751.7 5371.8 5574.3 5811.0 5870.2 5671.5 5901.0 5936.7 5938.7 6008.7 5278.6 5494.8 5516.6 5518.7 5748.0 4769.9 5086.5 5326.6 5461.6 5661.3 4584.2 5487.1 5513.9 5963.9 5972.2 3544.6 3623.6 3899.2 3902.6 3930.7 4634.5 5453.8 5684.7 5703.8 5723.6 5168.3 5244.7 5653.2 5680.6 5710.3 5163.8 5307.1 5361.6 5579.4 5591.5 4581.9 5074.3 5283.7 5363.9 5465.8 4762.5 5550.4 5605.5 5632.9 5635.9 4764.8 5498.9 5531.8 5532.6 5557.3 16:15:33 INFO - PROCESS | 1936 | 16:15:33 INFO - PROCESS | 1936 | [#5] yui.html Cycles:180 Average:18423.50 Median:32275.67 stddev:26149.47 (81.0%) stddev-sans-first:26188.82 16:15:33 INFO - PROCESS | 1936 | Values: 662.9 703.5 741.8 754.8 758.2 15049.6 15208.8 15339.0 15576.8 15744.8 13703.0 13847.7 13872.1 13876.8 13879.3 723.8 746.7 905.5 929.3 930.7 894.2 925.6 928.9 934.7 936.2 821.9 981.5 984.7 987.9 990.6 750.8 950.8 977.9 982.2 982.7 721.6 723.2 724.2 724.3 724.3 14955.2 15006.8 15023.0 15041.3 15049.2 12334.3 12360.6 12450.9 12692.4 12699.6 10676.6 10923.2 10936.8 11130.7 11363.8 4028.4 4031.5 4052.6 4061.9 4300.9 81151.6 83950.0 87439.3 87667.0 87734.6 9319.4 9334.3 9396.8 9487.7 9900.0 20042.5 20876.9 21497.6 21555.8 21607.4 7320.6 7353.5 7413.7 7481.9 7587.8 3849.1 3869.8 4004.2 4022.5 4025.4 3576.3 3709.1 3711.0 3716.5 3727.2 637.6 649.9 653.0 655.1 658.7 493.5 537.3 594.2 617.7 628.3 7214.2 8089.0 8170.3 8284.3 8415.6 583.7 600.5 612.9 616.5 620.3 3874.4 3964.5 4001.3 4038.6 4042.5 87272.0 88559.6 88790.0 92946.0 94876.0 82348.0 84056.0 85706.6 86801.0 87906.6 68534.0 70351.6 72202.3 76254.1 78051.6 8280.0 8292.2 8304.5 8467.7 8599.8 34925.0 41481.0 45152.5 45982.5 48166.0 61616.7 63913.7 64096.0 64437.0 64444.7 9430.7 11259.2 11497.7 11595.8 11770.2 11851.9 12167.3 12497.9 12498.2 13093.9 7652.8 11431.7 11618.5 11839.9 11954.7 11562.4 11568.2 11734.0 12526.1 12582.0 11224.0 11408.0 11417.1 11577.5 11589.9 8283.7 9501.0 9511.6 9530.0 9840.8 9633.3 9678.7 9864.2 9895.2 10020.2 16:15:33 INFO - PROCESS | 1936 | -------- Summary: end -------- 16:15:33 INFO - PROCESS | 1936 | 16:15:33 INFO - TEST-INFO | 1936: exit 0 16:15:33 INFO - TEST-OK | dromaeo_css | took 1114715ms 16:15:33 INFO - TEST-START | kraken 16:15:33 INFO - operating with platform_type : mac_ 16:15:33 INFO - Initialising browser for kraken test... 16:15:33 INFO - TEST-INFO | started process 1941 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/tmpm3qoiX/profile http://localhost:51166/getInfo.html) 16:15:36 INFO - PROCESS | 1941 | __metrics Screen width/height:1600/1200 16:15:36 INFO - PROCESS | 1941 | colorDepth:24 16:15:36 INFO - PROCESS | 1941 | Browser inner width/height: 1024/689 16:15:36 INFO - PROCESS | 1941 | __metrics 16:15:36 INFO - PROCESS | 1941 | 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" 16:15:36 ERROR - PROCESS | 1941 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:15:36 INFO - TEST-INFO | 1941: exit 0 16:15:36 INFO - Browser initialized. 16:15:36 INFO - Running cycle 1/1 for kraken test... 16:15:36 INFO - TEST-INFO | started process 1943 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/tmpm3qoiX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:15:48 INFO - PROCESS | 1943 | RSS: Main: 238948352 16:15:48 INFO - PROCESS | 1943 | 16:15:51 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:51166/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51166/tests/kraken/driver.html?testName=audio-beat-detection) 16:15:52 INFO - PROCESS | 1943 | RSS: Main: 286248960 16:15:52 INFO - PROCESS | 1943 | 16:15:55 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:51166/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51166/tests/kraken/driver.html?testName=audio-dft) 16:15:55 INFO - PROCESS | 1943 | RSS: Main: 295555072 16:15:55 INFO - PROCESS | 1943 | 16:15:59 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:51166/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51166/tests/kraken/driver.html?testName=audio-fft) 16:16:00 INFO - PROCESS | 1943 | RSS: Main: 380973056 16:16:00 INFO - PROCESS | 1943 | 16:16:02 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:51166/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51166/tests/kraken/driver.html?testName=audio-oscillator) 16:16:02 INFO - PROCESS | 1943 | RSS: Main: 283537408 16:16:02 INFO - PROCESS | 1943 | 16:16:04 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:51166/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51166/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:16:05 INFO - PROCESS | 1943 | RSS: Main: 287731712 16:16:05 INFO - PROCESS | 1943 | 16:16:08 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:51166/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51166/tests/kraken/driver.html?testName=imaging-darkroom) 16:16:09 INFO - PROCESS | 1943 | RSS: Main: 325922816 16:16:09 INFO - PROCESS | 1943 | 16:16:12 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:51166/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51166/tests/kraken/driver.html?testName=imaging-desaturate) 16:16:12 INFO - PROCESS | 1943 | RSS: Main: 329281536 16:16:12 INFO - PROCESS | 1943 | 16:16:15 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:51166/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51166/tests/kraken/driver.html?testName=json-parse-financial) 16:16:16 INFO - PROCESS | 1943 | RSS: Main: 328531968 16:16:16 INFO - PROCESS | 1943 | 16:16:18 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:51166/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51166/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:16:19 INFO - PROCESS | 1943 | RSS: Main: 316055552 16:16:19 INFO - PROCESS | 1943 | 16:16:20 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:51166/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51166/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:16:21 INFO - PROCESS | 1943 | RSS: Main: 322883584 16:16:21 INFO - PROCESS | 1943 | 16:16:23 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:51166/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51166/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:16:24 INFO - PROCESS | 1943 | RSS: Main: 325652480 16:16:24 INFO - PROCESS | 1943 | 16:16:27 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:51166/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51166/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:16:28 INFO - PROCESS | 1943 | RSS: Main: 330043392 16:16:28 INFO - PROCESS | 1943 | 16:16:30 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:51166/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51166/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:16:31 INFO - PROCESS | 1943 | RSS: Main: 328146944 16:16:31 INFO - PROCESS | 1943 | 16:16:33 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:51166/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51166/tests/kraken/driver.html?testName=ai-astar) 16:16:33 INFO - PROCESS | 1943 | RSS: Main: 374874112 16:16:33 INFO - PROCESS | 1943 | 16:16:33 INFO - PROCESS | 1943 | __start_tp_report 16:16:33 INFO - PROCESS | 1943 | _x_x_mozilla_page_load 16:16:33 INFO - PROCESS | 1943 | _x_x_mozilla_page_load_details 16:16:33 INFO - PROCESS | 1943 | |i|pagename|runs| 16:16:33 INFO - PROCESS | 1943 | |0;ai-astar;112;107;110;112;112;112;112;113;112;112 16:16:33 INFO - PROCESS | 1943 | |1;audio-beat-detection;161;113;137;196;117;149;161;136;170;118 16:16:33 INFO - PROCESS | 1943 | |2;audio-dft;195;254;188;184;181;183;179;179;181;183 16:16:33 INFO - PROCESS | 1943 | |3;audio-fft;77;73;56;125;60;63;54;59;109;54 16:16:33 INFO - PROCESS | 1943 | |4;audio-oscillator;90;100;133;79;79;77;101;78;78;98 16:16:33 INFO - PROCESS | 1943 | |5;imaging-gaussian-blur;87;84;87;86;87;85;89;84;126;83 16:16:33 INFO - PROCESS | 1943 | |6;imaging-darkroom;94;93;94;101;123;93;92;113;145;94 16:16:33 INFO - PROCESS | 1943 | |7;imaging-desaturate;124;128;85;84;85;84;85;83;86;84 16:16:33 INFO - PROCESS | 1943 | |8;json-parse-financial;57;57;59;58;54;57;57;125;88;85 16:16:33 INFO - PROCESS | 1943 | |9;json-stringify-tinderbox;63;64;79;71;70;67;68;70;68;68 16:16:33 INFO - PROCESS | 1943 | |10;stanford-crypto-aes;86;85;82;79;73;79;84;83;85;84 16:16:33 INFO - PROCESS | 1943 | |11;stanford-crypto-ccm;140;137;135;149;121;143;140;145;212;144 16:16:33 INFO - PROCESS | 1943 | |12;stanford-crypto-pbkdf2;164;163;164;162;162;162;163;162;162;161 16:16:33 INFO - PROCESS | 1943 | |13;stanford-crypto-sha256-iterative;64;65;65;63;64;65;64;64;65;65 16:16:33 INFO - PROCESS | 1943 | __end_tp_report 16:16:33 INFO - PROCESS | 1943 | __start_cc_report 16:16:33 INFO - PROCESS | 1943 | _x_x_mozilla_cycle_collect,2236 16:16:33 INFO - PROCESS | 1943 | __end_cc_report 16:16:33 INFO - PROCESS | 1943 | __startTimestamp1463526993582__endTimestamp 16:16:33 INFO - PROCESS | 1943 | 16:16:33 INFO - PROCESS | 1943 | ------- Summary: start ------- 16:16:33 INFO - PROCESS | 1943 | Number of tests: 14 16:16:33 INFO - PROCESS | 1943 | 16:16:33 INFO - PROCESS | 1943 | [#0] ai-astar Cycles:10 Average:111.40 Median:112.00 stddev:1.71 (1.5%) stddev-sans-first:1.80 16:16:33 INFO - PROCESS | 1943 | Values: 112.0 107.0 110.0 112.0 112.0 112.0 112.0 113.0 112.0 112.0 16:16:33 INFO - PROCESS | 1943 | 16:16:33 INFO - PROCESS | 1943 | [#1] audio-beat-detection Cycles:10 Average:145.80 Median:155.00 stddev:26.73 (17.2%) stddev-sans-first:27.78 16:16:33 INFO - PROCESS | 1943 | Values: 161.0 113.0 137.0 196.0 117.0 149.0 161.0 136.0 170.0 118.0 16:16:33 INFO - PROCESS | 1943 | 16:16:33 INFO - PROCESS | 1943 | [#2] audio-dft Cycles:10 Average:190.70 Median:183.50 stddev:22.75 (12.4%) stddev-sans-first:24.08 16:16:33 INFO - PROCESS | 1943 | Values: 195.0 254.0 188.0 184.0 181.0 183.0 179.0 179.0 181.0 183.0 16:16:33 INFO - PROCESS | 1943 | 16:16:33 INFO - PROCESS | 1943 | [#3] audio-fft Cycles:10 Average:73.00 Median:59.50 stddev:24.70 (41.5%) stddev-sans-first:26.16 16:16:33 INFO - PROCESS | 1943 | Values: 77.0 73.0 56.0 125.0 60.0 63.0 54.0 59.0 109.0 54.0 16:16:33 INFO - PROCESS | 1943 | 16:16:33 INFO - PROCESS | 1943 | [#4] audio-oscillator Cycles:10 Average:91.30 Median:78.50 stddev:17.69 (22.5%) stddev-sans-first:18.76 16:16:33 INFO - PROCESS | 1943 | Values: 90.0 100.0 133.0 79.0 79.0 77.0 101.0 78.0 78.0 98.0 16:16:33 INFO - PROCESS | 1943 | 16:16:33 INFO - PROCESS | 1943 | [#5] imaging-gaussian-blur Cycles:10 Average:89.80 Median:86.50 stddev:12.85 (14.9%) stddev-sans-first:13.59 16:16:33 INFO - PROCESS | 1943 | Values: 87.0 84.0 87.0 86.0 87.0 85.0 89.0 84.0 126.0 83.0 16:16:33 INFO - PROCESS | 1943 | 16:16:33 INFO - PROCESS | 1943 | [#6] imaging-darkroom Cycles:10 Average:104.20 Median:93.00 stddev:17.69 (19.0%) stddev-sans-first:18.38 16:16:33 INFO - PROCESS | 1943 | Values: 94.0 93.0 94.0 101.0 123.0 93.0 92.0 113.0 145.0 94.0 16:16:33 INFO - PROCESS | 1943 | 16:16:33 INFO - PROCESS | 1943 | [#7] imaging-desaturate Cycles:10 Average:92.80 Median:84.50 stddev:17.54 (20.8%) stddev-sans-first:14.53 16:16:33 INFO - PROCESS | 1943 | Values: 124.0 128.0 85.0 84.0 85.0 84.0 85.0 83.0 86.0 84.0 16:16:33 INFO - PROCESS | 1943 | 16:16:33 INFO - PROCESS | 1943 | [#8] json-parse-financial Cycles:10 Average:69.70 Median:57.50 stddev:23.02 (40.0%) stddev-sans-first:23.96 16:16:33 INFO - PROCESS | 1943 | Values: 57.0 57.0 59.0 58.0 54.0 57.0 57.0 125.0 88.0 85.0 16:16:33 INFO - PROCESS | 1943 | 16:16:33 INFO - PROCESS | 1943 | [#9] json-stringify-tinderbox Cycles:10 Average:68.80 Median:69.00 stddev:4.39 (6.4%) stddev-sans-first:4.13 16:16:33 INFO - PROCESS | 1943 | Values: 63.0 64.0 79.0 71.0 70.0 67.0 68.0 70.0 68.0 68.0 16:16:33 INFO - PROCESS | 1943 | 16:16:33 INFO - PROCESS | 1943 | [#10] stanford-crypto-aes Cycles:10 Average:82.00 Median:84.00 stddev:3.97 (4.7%) stddev-sans-first:3.94 16:16:33 INFO - PROCESS | 1943 | Values: 86.0 85.0 82.0 79.0 73.0 79.0 84.0 83.0 85.0 84.0 16:16:33 INFO - PROCESS | 1943 | 16:16:33 INFO - PROCESS | 1943 | [#11] stanford-crypto-ccm Cycles:10 Average:146.60 Median:143.50 stddev:24.21 (16.9%) stddev-sans-first:25.56 16:16:33 INFO - PROCESS | 1943 | Values: 140.0 137.0 135.0 149.0 121.0 143.0 140.0 145.0 212.0 144.0 16:16:33 INFO - PROCESS | 1943 | 16:16:33 INFO - PROCESS | 1943 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.50 Median:162.50 stddev:0.97 (0.6%) stddev-sans-first:0.87 16:16:33 INFO - PROCESS | 1943 | Values: 164.0 163.0 164.0 162.0 162.0 162.0 163.0 162.0 162.0 161.0 16:16:33 INFO - PROCESS | 1943 | 16:16:33 INFO - PROCESS | 1943 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.40 Median:65.00 stddev:0.70 (1.1%) stddev-sans-first:0.73 16:16:33 INFO - PROCESS | 1943 | Values: 64.0 65.0 65.0 63.0 64.0 65.0 64.0 64.0 65.0 65.0 16:16:33 INFO - PROCESS | 1943 | -------- Summary: end -------- 16:16:33 INFO - PROCESS | 1943 | 16:16:34 INFO - TEST-INFO | 1943: exit 0 16:16:34 INFO - TEST-OK | kraken | took 60577ms 16:16:34 INFO - SUITE-END | took 1175s 16:16:34 INFO - Completed test suite (00:19:35) 16:16:34 INFO - javascript benchmark 16:16:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3217.3726123405936, 3402.2515046689737, 3427.3830710472107, 3430.0224436035733, 3431.468122441021, 5623.915641265374, 5627.380988091307, 5663.745131469083, 5720.113382425726, 5802.12968054792, 5680.460356266159, 5693.28833895763, 5706.201131841535, 5755.68343741094, 5757.740901659436, 5041.092603331399, 5098.949010509903, 5160.948390516103, 5166.560842328407, 5255.973720131394, 5233.555147812422, 5238.79044838206, 5268.157094864822, 5276.314079169702, 5317.73411329432, 5047.242913562976, 5113.642045056848, 5120.564751996085, 5129.897402051956, 5138.665986710852, 4325.653947684178, 5086.237064440326, 5088.898222035538, 5095.006473737622, 5157.9941911327305, 5102.719350435725, 5132.307138536325, 5150.922736158961, 5153.665011774223, 5161.741912904377, 5495.835124946258, 5545.861353466155, 5596.944030559724, 5609.5792815539, 5632.07070833312, 6184.628922264678, 6186.84532886677, 6224.688765561704, 6228.5951413158, 6259.029850373199, 6073.689983546856, 6266.749330026793, 6301.243850737895, 6316.526260530432, 6324.0, 5602.831915042555, 5744.540436765049, 5826.504747096459, 5830.300363956352, 5836.795712150054, 6291.622502649764, 6312.52656050798, 6318.62088274705, 6319.8104056878365, 6324.335944725737, 5976.402359764076, 6095.420935011167, 6097.17688112108, 6106.5114790817515, 6138.447539721446, 6129.969350153221, 6143.139960405547, 6186.81439556814, 6215.906761398583, 6253.249610046732, 6112.290716697417, 6197.752089916443, 6253.312135665074, 6258.280297765724, 6259.374062593795, 6236.719347629277, 6266.373362663697, 6268.09112678659, 6281.277653069864, 6298.7480500780375, 4790.257510085942, 4830.637702172351, 4857.611391088775, 4918.065567542156, 4921.9507804921495, 4378.627816635558, 4385.210662080793, 4392.253316936129, 4420.469543654782, 4472.08322293929, 5720.456556627197, 5727.77088916447, 5741.741621627037, 5768.451997060357, 5810.941890581124, 6069.787557435468, 6154.261488621304, 6317.989121740499, 6331.081993110973, 6351.904721429182, 5625.09998400254, 5682.232898558725, 5689.715514224272, 5700.25896633435, 5736.885262294731, 5348.598855085884, 5388.326459192601, 5393.6224464287125, 5395.73021348931, 5405.324334458192, 6081.5134789217645, 6088.665123418077, 6316.242050953915, 6338.746450141943, 6382.106505089291, 5872.794452194153, 5899.0, 5926.111083337534, 5934.258217722785, 5943.167956486009, 6238.875222495434, 6296.937030629635, 6307.495400368051, 6350.269718982377, 6359.0, 6203.2556093269095, 6233.158523599256, 6247.187865577627, 6247.468965137832, 6272.341404152681, 5234.33837683653, 6109.677964500999, 6307.180066591313, 6339.96830015847, 6356.904646430215, 6076.756929722762, 6103.511719062553, 6128.979283094455, 6133.785317513865, 6177.752889884354, 6105.84735381619, 6123.836471070483, 6131.515109163381, 6135.723892424762, 6148.692565371624, 4611.930821037754, 5737.799177028784, 5802.970985145047, 5954.583179177416, 5960.0761881908375, 5971.701414929149, 6017.852001151666, 6055.72749226277, 6103.481204097701, 6141.048137538689, 5865.677387743715, 5918.940810592011, 6013.759449621966, 6025.87948241024, 6062.120992455981], "value": 5664.147900591505, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [505.2320472881223, 513.7354262554785, 514.3442111308082, 554.0525701051203, 567.452408425869, 30880.0, 31665.841670791644, 42888.356674649905, 44090.897727556745, 44189.558104418946, 45432.0, 45891.541084589146, 45912.852178695546, 45914.62256132313, 45930.7703461483, 7517.774466765978, 8527.31781457485, 9631.711048668516, 9692.12770850625, 9886.802263954753, 8700.608472618716, 8764.60559274831, 8765.956170219171, 8769.429987050822, 11600.477978490988, 11285.943570282137, 11553.422328883564, 11555.30668159909, 11570.132240081986, 11581.536738530449, 11744.588939387124, 11815.291082535075, 11819.34993575353, 11821.758715334896, 11833.881661183364, 8574.0, 11444.599439019594, 11565.363904985223, 11759.7060073498, 11780.764384712258, 41960.370594441105, 42467.0, 42519.57480425218, 42524.78737606292, 42528.0, 3266.7549933755063, 3367.1582104473882, 3399.42209824328, 3455.1362159460136, 3692.5753538342897, 28604.284892877637, 28841.56737648937, 29122.0, 29471.852640736874, 30183.547246791317, 2720.8367497950185, 2739.8630068496495, 2762.350847550824, 2805.6773471050888, 2871.6984716604666, 544242.0, 550523.0, 551194.0, 551259.0, 552621.9999999959, 65845.0, 66845.66577167083, 66957.33042669512, 67162.66418667875, 67247.32752672509, 70655.64672176709, 70828.64585677038, 70844.0, 70917.64541177364, 71061.0, 16407.753883691756, 16876.409325673787, 16888.324467021426, 16913.985160890385, 16927.40754073626, 11011.174161937886, 12741.10812243134, 12831.679208019874, 12837.743245135045, 12864.871351286367, 17415.738763918296, 17817.821821781617, 19765.40703778889, 20335.0, 20348.0, 8246.752597422086, 8258.050324212672, 8268.710595129141, 8295.294899933573, 8367.497950123012, 982.3074732313702, 1016.9694909152737, 1031.7214352124884, 1086.8315411111291, 1090.0571006079683, 27950.86024569905, 30497.968798347232, 30694.0, 30881.892347387715, 31407.685923140933, 1093.436880006797, 1098.242212873115, 1152.4295473740551, 1179.941002949849, 1296.1445446005591, 14382.928085359506, 15360.0, 15391.230438478031, 15636.765448518281, 15672.0, 746100.0, 1023393.0, 1046520.0, 1047493.0, 1047723.7613812034, 64654.0, 64723.67638161779, 65247.69504609976, 65358.0, 65510.01734973884, 1338.444545513601, 1387.6322774464977, 1489.2851431312813, 1505.164633628325, 1530.3572499550216, 28968.0, 30026.849865751406, 30366.93715719939, 31945.520817187295, 32113.8394308027, 59802.0, 60017.39982600118, 60606.39393606008, 60661.39338606734, 60771.784564307585, 655562.0, 659428.0, 659487.0, 661311.0, 662563.0, 24160.63759043651, 26849.463010740066, 27197.592036119077, 27828.72171278261, 28221.858890705415, 46700.06599868052, 47080.7645961768, 47210.0, 47422.288665670734, 47976.7601161992, 34248.45881935269, 34797.47803782894, 37506.812465937495, 38213.42679859749, 38890.0, 37452.0, 37599.81200093982, 38046.61953380542, 38056.287467064605, 38540.03649908775, 38201.61798382092, 38445.807770960964, 39184.0, 39278.0, 39379.80310098431, 32554.0, 33188.642427621286, 33400.66599334073, 33773.15567110842, 33794.94460664736, 24706.629400558646, 25019.74980250174, 25144.0, 25163.87418062898, 25332.366690832878], "value": 21069.635866436725, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.2273927211574, 401.2396508616174, 401.4901075633944, 406.2301937827816, 424.5860286220933, 20007.799922000777, 20248.696269555967, 21101.788982110174, 21128.57742845144, 21262.255821046267, 19810.504737381572, 20131.993400329997, 21195.364139075813, 21429.785702142974, 21459.034343454543, 5792.855178620547, 6017.48851347636, 6051.27384713835, 6058.666773327465, 6066.817995460143, 5776.653400795944, 6000.75996960121, 6014.578979471439, 6070.817875463743, 6131.049687298453, 6111.419415155553, 6150.446459818615, 6152.784652537162, 6154.046122850943, 6157.661328626924, 5982.880342393128, 6062.272527296708, 6135.9079613805825, 6137.539684523634, 6147.631142131487, 5920.437558431942, 5932.169496270526, 5942.465178133989, 5946.583739138262, 5955.166276721263, 14141.717165656732, 14212.360443779951, 22308.776912230995, 25083.87458062698, 26437.20688379352, 1950.2394066314148, 2048.24215040436, 2095.829299693614, 2104.894755262231, 2325.267540724666, 9905.455199963999, 10292.425623477717, 10392.896071039268, 10863.67408977732, 14880.925595372062, 1492.9029613075115, 1680.15992003998, 1681.6888875557938, 1705.5224537129623, 1721.6298495823457, 97321.51339243258, 125711.0, 132692.33653831764, 133281.0, 134660.9800852988, 11629.011534019703, 11815.11386646005, 11860.525578976916, 11909.52361905514, 11998.220115692626, 15327.0, 15385.230738463031, 15386.230688465532, 15398.0, 15459.458918937784, 8767.693130740521, 8827.337949653802, 8838.690645827364, 8865.202131808168, 8938.24024957886, 7913.802154946057, 7952.204779522117, 8663.263622592025, 8685.956570217108, 8807.07525709804, 4695.694779839299, 4722.709485899336, 4726.0, 4728.1252968201, 4799.568038876518, 1938.9127489263017, 1988.2047181127375, 1997.8701384409967, 1998.5703073838904, 1998.8600797944005, 913.2237598041744, 948.672707915768, 954.5418199264253, 975.9024097590326, 985.0395864032511, 18589.907050464662, 19521.804781952284, 19781.208751649774, 20366.0, 21005.36983890486, 790.8497385496737, 938.5635679408971, 958.5303201431249, 979.4661909259471, 995.7560397702609, 10971.286866353661, 11253.987141157171, 11265.831012534818, 11268.774624507463, 11273.0, 606514.96742516, 606735.0, 615021.0, 627372.0, 628993.0, 125605.0, 125778.0, 130762.0, 132240.0, 136231.31884340514, 1078.0297732041226, 1090.1823632275793, 1126.099120703434, 1148.0585919545892, 1150.6375491720082, 15629.071883983084, 17588.91205543964, 18672.0, 18925.526861828563, 18953.43139705811, 43979.560204397545, 58161.0, 58200.0, 58879.82240355255, 58918.70540647269, 67805.3219467799, 80943.0, 82412.76380854173, 84219.15780842114, 86680.0, 5892.0, 7664.31021208094, 7681.500702454323, 7702.884456733042, 8415.032271288881, 9414.67048653294, 10300.433476158883, 10567.418791966516, 12859.29273889945, 13318.600441986757, 6350.968245158745, 6818.590884546943, 11105.777884442105, 11354.54581816718, 11456.770864582828, 6726.461883049442, 7907.367410607253, 10876.836847447137, 10977.725556861142, 11021.669349919515, 9738.318317717974, 11246.606368777053, 11389.348544461172, 11891.345975971404, 11929.880701193224, 7556.58438785872, 7631.771046868603, 7739.613019348897, 7771.0, 8016.118226995142, 8853.601587928688, 9213.447193168431, 9280.257579393767, 10401.94799026, 10789.514471848628], "value": 9510.357849037806, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1454.3746189138667, 1629.853313201812, 1658.5273197138813, 1924.8267655910965, 2032.9898350508254, 7931.801704957372, 8623.956880215606, 8894.955525222382, 8913.910860891388, 9016.909830901688, 8599.312055035598, 8828.514431706253, 8836.425632333894, 8837.381383303155, 8839.381243312971, 2455.3125124964995, 2482.9379265518414, 2488.5645012122895, 2488.8382255153447, 2489.601663733803, 2446.449548851512, 2505.1232068775876, 2505.924822255335, 2507.761762632556, 2520.823542352036, 2453.092355828346, 2475.938101547467, 2495.6506089147533, 2506.548821212181, 2507.1726330310953, 2437.5856104462196, 2461.938451538708, 2468.814838887082, 2469.8271121021535, 2476.73994230605, 2433.987830060856, 2451.705795304557, 2467.4324905271887, 2467.605183170684, 2470.28361775085, 8591.355648326338, 8778.122187781235, 8865.537186364241, 8872.024077351487, 8878.378513504058, 5315.0, 5451.236826844245, 5454.863628409282, 5455.208994695787, 5460.535854452376, 3194.9201269968203, 3438.5186073949667, 3464.497647841074, 3514.490398892172, 3591.2278860044953, 1023.2683631203726, 1036.0, 1036.7812880435797, 1038.055369613648, 1039.8440233964966, 11676.941615291955, 11718.765624687543, 11738.471768770425, 11760.88239117615, 11769.41152942358, 4464.17412778637, 4584.724916505021, 4589.036302376471, 4747.643926705511, 4759.405074365704, 9321.487318197429, 9325.020872808393, 9334.859977100348, 9334.953325233331, 9337.95331023354, 5317.202419637086, 5323.733813309319, 5324.201369794562, 5324.361076670824, 5327.653702509325, 4487.618552423015, 4634.976825115853, 4808.783604737795, 4929.964707411484, 4967.503249675004, 3878.5733569307918, 3897.649211570972, 3970.7617542947514, 3988.3219852625125, 4020.155767288902, 1209.7701436726993, 1229.8093795461718, 1234.6789834643057, 1253.2981530343036, 1255.1151438236022, 663.797541749771, 822.9547374894438, 886.0297973718767, 916.5371487398821, 929.4887811703671, 5811.854703632443, 6008.09878518217, 6024.1566180734735, 6082.391760823883, 6231.470325022424, 810.5217921426387, 956.6795123633522, 967.5887747707337, 979.9167070799062, 1003.4330603209163, 3913.7064720146104, 4047.4738284022887, 4066.186762647423, 4096.180763847243, 4137.648299894482, 10504.264701470824, 10929.234953553176, 11027.724306892393, 11055.502502387411, 11060.0, 10945.288556243819, 10964.28732132409, 10975.231733778719, 10977.176711746652, 11001.0, 7832.882506762403, 8173.305269052078, 8224.259816616413, 8234.835303293901, 8335.208155225122, 6042.607230530001, 6085.786997455068, 6131.356207598138, 6209.937900620936, 6294.055891616111, 9124.676921846429, 9999.500024998866, 10264.64073757415, 10277.229207809594, 10285.228607854291, 9712.611495540383, 10014.499275036365, 10138.847917281098, 10409.635662751767, 10415.114715249289, 7619.428542859197, 7702.691892324245, 7754.961225193838, 7804.453688241996, 7812.218778122142, 8328.45865018796, 8410.705625303084, 8416.2004609562, 8418.494890306602, 8460.450070745383, 6145.446909778141, 7449.702011919462, 7450.0, 7520.247975202436, 7723.420743444377, 6284.717187726655, 6837.247902730795, 7137.929310603244, 7505.737299194502, 7576.204498527575, 7052.0832291801735, 7149.177844547736, 7154.892676609751, 7156.105486814148, 7157.105361829771, 4397.758123303249, 4860.0, 4949.455559888337, 4958.429780575136, 5194.35070616173, 4977.900441991213, 5034.798608055637, 5218.556422704112, 5250.789968401374, 5285.524302812765], "value": 4633.541877002341, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.42478777080737, 313.9811611303322, 324.85056873838033, 328.3957518166573, 329.3577523828535, 4945.8516244512675, 5353.866153346168, 5362.27609272748, 5410.675359478424, 5412.377576578695, 5045.949540504603, 5104.208847628614, 5217.530422261995, 5222.373315202171, 5248.921266181001, 3870.5935876732888, 4042.6159514846045, 4043.9780109945027, 4104.343305071175, 4127.6585109839425, 4088.2232375848644, 4109.0, 4120.175964807036, 4128.3601041838565, 4131.5248746394245, 3321.4021476134285, 3858.594847540996, 3925.5878132796074, 4068.0643452006057, 4105.67154627629, 3430.9142271443166, 3574.1064733816547, 3578.8210589470423, 3986.7009974251755, 4036.4147198656324, 3427.8628854845774, 3557.3280558137635, 3581.194231297963, 3589.5513060867393, 3601.603823579404, 4113.773742444165, 4454.35411865281, 4760.357351757538, 4879.268109783526, 5010.874728131789, 3989.40158976153, 4593.438231001475, 4625.32932724753, 4628.467726211461, 4664.790084446208, 4845.588125009378, 5045.016221836743, 5108.770105345268, 5642.971785141089, 5651.8304450866535, 3469.2367679110557, 3892.163184915257, 3899.298126337258, 3901.224942651087, 3911.471951286568, 4777.402824646919, 5478.890422191534, 5865.736041878125, 5880.705964701748, 5930.347661757246, 3460.3252365788935, 3842.9624001519433, 3881.650651441327, 3884.669803066771, 3887.475190849256, 3980.800959951991, 3995.4606128172904, 4000.8399664013696, 4001.1797581495725, 4004.5594984551676, 4380.080183161572, 4407.933880991788, 4583.839565615188, 4604.8388306409115, 4619.838305659353, 5285.814996475105, 5562.193481945095, 5629.8592535186945, 5636.830895073154, 5649.774009039593, 1133.6032388663903, 1328.0504439325734, 1352.3508715816463, 1359.7960305953989, 1360.31984007996, 765.6439755513713, 811.736185739637, 824.4229039672254, 900.7883147460342, 915.9358844880927, 4684.320773487893, 4800.927986080212, 5135.35808023997, 5234.0, 5238.292830467914, 5391.436483419764, 5522.558195344443, 5530.640508366943, 5546.889062218733, 5763.221965034751, 4386.8683939481225, 4763.118823017753, 5040.521150490697, 5095.694258344512, 5137.255098010767, 2533.847099569691, 2695.167286245372, 2786.9303267418477, 2988.2678743707497, 2989.1331513860737, 5088.796448142033, 5335.973320133452, 5948.226730525091, 5970.910436343458, 5986.311574168939, 4751.738654374042, 5371.811986580529, 5574.275344205227, 5810.970945145332, 5870.182312490028, 5671.546276297886, 5901.0, 5936.714364969518, 5938.713595265767, 6008.669523176267, 5278.60410469224, 5494.752736126964, 5516.641418307797, 5518.696471693935, 5748.0, 4769.9046019080115, 5086.491350865032, 5326.627136100436, 5461.617686762047, 5661.348944871393, 4584.15193189261, 5487.094629386106, 5513.917291240555, 5963.940360596339, 5972.223610930551, 3544.5746510417885, 3623.583287921923, 3899.239648268674, 3902.57071722116, 3930.7051971102856, 4634.490206077397, 5453.781848726166, 5684.7157642118555, 5703.771849125988, 5723.5707321950185, 5168.250603662523, 5244.685318880879, 5653.236813030353, 5680.573956953162, 5710.2862142232225, 5163.786510170032, 5307.124324486416, 5361.632783640147, 5579.442055794388, 5591.4688104629995, 4581.9232480367755, 5074.31496747949, 5283.735813209248, 5363.865903352447, 5465.836024919259, 4762.476127626028, 5550.417206193291, 5605.523530499953, 5632.9436705632415, 5635.9436405635415, 4764.809407623795, 5498.917516237179, 5531.834044978657, 5532.612717109763, 5557.277553918127], "value": 4051.50660710239, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [662.9138212032436, 703.5251205436332, 741.8182545276404, 754.7811134770917, 758.2493331601715, 15049.62375940602, 15208.847911520881, 15339.0, 15576.766348504767, 15744.763828542553, 13703.040787144904, 13847.723045539084, 13872.098313609607, 13876.791848122286, 13879.30603469825, 723.8407550338943, 746.6789280609336, 905.5472263868066, 929.2890938432105, 930.692871352452, 894.2220268366531, 925.5973651461602, 928.9442633442015, 934.700895713372, 936.166811537732, 821.8520666280098, 981.4503877828404, 984.6652138272985, 987.9407235565853, 990.6384169778015, 750.8385697075128, 950.8336041192764, 977.9119879210905, 982.219135787051, 982.7002764156929, 721.5670597641373, 723.1719680965265, 724.1527412926888, 724.2540183610889, 724.2829598697305, 14955.177465239407, 15006.849931500654, 15023.0, 15041.323140458706, 15049.247537623185, 12334.259944403364, 12360.567380141742, 12450.937745311216, 12692.365381730877, 12699.619011429579, 10676.572937082507, 10923.180761442924, 10936.835947460795, 11130.666080017612, 11363.829542556869, 4028.4158796974275, 4031.4759081319535, 4052.5744796796503, 4061.8578349757618, 4300.935485967713, 81151.59424202841, 83950.0, 87439.25121497535, 87667.0, 87734.56132719359, 9319.440833550008, 9334.253259739204, 9396.812063758687, 9487.66793162236, 9900.009999000042, 20042.498937526387, 20876.895615521826, 21497.57004859894, 21555.78444215569, 21607.351779446642, 7320.597367144858, 7353.522021068614, 7413.666385012687, 7481.85036299271, 7587.772366829004, 3849.095462566295, 3869.845206191721, 4004.1591265833918, 4022.5374081980362, 4025.4163146343617, 3576.2668652926086, 3709.109813644706, 3711.0351308659942, 3716.535433070866, 3727.1800203955086, 637.6238019568477, 649.8830210562049, 653.0, 655.0926966151914, 658.7233361987977, 493.50402845140405, 537.3337062043039, 594.1592646405375, 617.6880675259056, 628.3402427451158, 7214.170370407365, 8088.9595552021865, 8170.264676179172, 8284.295834853985, 8415.579221038923, 583.6848102024859, 600.504583718434, 612.935641757618, 616.5283558078074, 620.2804746494045, 3874.360730479439, 3964.5044369453817, 4001.3197756381487, 4038.5759495253164, 4042.535108462506, 87272.0, 88559.55720221357, 88790.0, 92946.0, 94876.0, 82348.0, 84056.0, 85706.57146714226, 86801.0, 87906.56046719853, 68534.0, 70351.64824175846, 72202.27797722061, 76254.09364765925, 78051.60974195093, 8280.0, 8292.17078292166, 8304.54325012106, 8467.745967620982, 8599.828003440021, 34925.0, 41481.0, 45152.54847451615, 45982.54017459782, 48166.0, 61616.69191654013, 63913.7217255643, 64096.0, 64437.0, 64444.67777661081, 9430.669926552811, 11259.155563332619, 11497.712557186138, 11595.768084638092, 11770.176087674123, 11851.940740296242, 12167.33079680626, 12497.875021250034, 12498.187617805177, 13093.934530327288, 7652.80867978305, 11431.714207144887, 11618.5352585899, 11839.881601183877, 11954.70113247141, 11562.390010063078, 11568.190226684388, 11734.0, 12526.060545458944, 12581.993440524919, 11224.0, 11408.0, 11417.08043694141, 11577.536898524302, 11589.942050289694, 8283.710070147516, 9501.0, 9511.61953521879, 9530.046995300414, 9840.803183936137, 9633.277504187074, 9678.70963871085, 9864.21086313079, 9895.158911492605, 10020.24848136378], "value": 6963.301596552177, "unit": "score"}], "name": "dromaeo_css", "value": 7276.070596384467, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 107.0, 110.0, 112.0, 112.0, 112.0, 112.0, 113.0, 112.0, 112.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 113.0, 137.0, 196.0, 117.0, 149.0, 161.0, 136.0, 170.0, 118.0], "value": 145.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 254.0, 188.0, 184.0, 181.0, 183.0, 179.0, 179.0, 181.0, 183.0], "value": 190.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 73.0, 56.0, 125.0, 60.0, 63.0, 54.0, 59.0, 109.0, 54.0], "value": 73.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 100.0, 133.0, 79.0, 79.0, 77.0, 101.0, 78.0, 78.0, 98.0], "value": 91.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 86.0, 87.0, 85.0, 89.0, 84.0, 126.0, 83.0], "value": 89.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 101.0, 123.0, 93.0, 92.0, 113.0, 145.0, 94.0], "value": 104.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 128.0, 85.0, 84.0, 85.0, 84.0, 85.0, 83.0, 86.0, 84.0], "value": 92.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 59.0, 58.0, 54.0, 57.0, 57.0, 125.0, 88.0, 85.0], "value": 69.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 79.0, 71.0, 70.0, 67.0, 68.0, 70.0, 68.0, 68.0], "value": 68.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 82.0, 79.0, 73.0, 79.0, 84.0, 83.0, 85.0, 84.0], "value": 82.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 137.0, 135.0, 149.0, 121.0, 143.0, 140.0, 145.0, 212.0, 144.0], "value": 146.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 164.0, 162.0, 162.0, 162.0, 163.0, 162.0, 162.0, 161.0], "value": 162.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 65.0, 63.0, 64.0, 65.0, 64.0, 64.0, 65.0, 65.0], "value": 64.4, "unit": "score"}], "name": "kraken", "value": 1493.0, "alertThreshold": 2.0}]} 16:16:34 INFO - Return code: 0 16:16:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:16:34 ERROR - # TBPL SUCCESS # 16:16:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:16:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:16:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:16:34 INFO - 2016-05-17 16:16:34.948 screenresolution[1947:14887] starting screenresolution argv=screenresolution get 16:16:34 INFO - 2016-05-17 16:16:34.950 screenresolution[1947:14887] Display 0: 1600x1200x32@60 16:16:34 INFO - 2016-05-17 16:16:34.956 screenresolution[1948:14890] starting screenresolution argv=screenresolution list 16:16:34 INFO - Available Modes on Display 0 16:16:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:16:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:16:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:16:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:16:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:16:35 INFO - Intel Iris: 16:16:35 INFO - Chipset Model: Intel Iris 16:16:35 INFO - Type: GPU 16:16:35 INFO - Bus: Built-In 16:16:35 INFO - VRAM (Dynamic, Max): 1536 MB 16:16:35 INFO - Vendor: Intel (0x8086) 16:16:35 INFO - Device ID: 0x0a2e 16:16:35 INFO - Revision ID: 0x0009 16:16:35 INFO - Displays: 16:16:35 INFO - Display: 16:16:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:16:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:16:35 INFO - Display Serial Number: 1600x1200 60 16:16:35 INFO - Main Display: Yes 16:16:35 INFO - Mirror: Off 16:16:35 INFO - Online: Yes 16:16:35 INFO - Rotation: Supported 16:16:35 INFO - Return code: 0 16:16:35 INFO - Running post-action listener: _resource_record_post_action 16:16:35 INFO - ##### 16:16:35 INFO - ##### Finished run-tests step (success) 16:16:35 INFO - ##### 16:16:35 INFO - Running post-run listener: _resource_record_post_run 16:16:35 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 1089536; Write bytes: 695621120; Read time: 11; Write time: 1908 16:16:35 INFO - TinderboxPrint: CPU usage
53.9% 16:16:35 INFO - TinderboxPrint: I/O read bytes / time
1,089,536 / 11 16:16:35 INFO - TinderboxPrint: I/O write bytes / time
695,621,120 / 1,908 16:16:35 INFO - TinderboxPrint: CPU idle
2,233.3 (46.1%) 16:16:35 INFO - TinderboxPrint: CPU system
970.9 (20.0%) 16:16:35 INFO - TinderboxPrint: CPU user
1,641.4 (33.9%) 16:16:35 INFO - TinderboxPrint: Swap in / out
396,865,536 / 0 16:16:35 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 374321664; Write bytes: 369735680; Read time: 28339; Write time: 758 16:16:35 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 1085440; Write bytes: 296807936; Read time: 11; Write time: 1080 16:16:35 INFO - Running post-run listener: _upload_blobber_files 16:16:35 INFO - Blob upload gear active. 16:16:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:16:35 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 16:16:35 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:16:35 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:16:36 INFO - (blobuploader) - INFO - Open directory for files ... 16:16:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:16:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:16:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:16:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:16:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:16:36 INFO - (blobuploader) - INFO - Done attempting. 16:16:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:16:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:16:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:16:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:16:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:16:39 INFO - (blobuploader) - INFO - Done attempting. 16:16:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:16:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:16:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:16:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:16:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:16:42 INFO - (blobuploader) - INFO - Done attempting. 16:16:42 INFO - (blobuploader) - INFO - Iteration through files over. 16:16:42 INFO - Return code: 0 16:16:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:16:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:16:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/17dd8ed4f661664d647bcf322059df31e9e572c8087797db634f55d3333e7f91afa25538fcf50ba2bd33b681f3ec9d473e52acd58833802f5f75c185070983c6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/556a023fe24c2d13581c6454b19bb435ea19e1e0b9f32c1234f2e18ee9cdec7cad55d72c376ac8d5c881072acbcab8ed00a87c22cb4d9c7c1f1cdbf34e7527b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa68bf76ada91354d116daf07c980a3ddd1330b05a68d152a7349b3eadc4d0113d81e1cf5c42aa3717f2dd2957aeaf0f9c0f779c579bdfaff39f8de507683f2b"} 16:16:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:16:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:16:42 INFO - Contents: 16:16:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/17dd8ed4f661664d647bcf322059df31e9e572c8087797db634f55d3333e7f91afa25538fcf50ba2bd33b681f3ec9d473e52acd58833802f5f75c185070983c6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/556a023fe24c2d13581c6454b19bb435ea19e1e0b9f32c1234f2e18ee9cdec7cad55d72c376ac8d5c881072acbcab8ed00a87c22cb4d9c7c1f1cdbf34e7527b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa68bf76ada91354d116daf07c980a3ddd1330b05a68d152a7349b3eadc4d0113d81e1cf5c42aa3717f2dd2957aeaf0f9c0f779c579bdfaff39f8de507683f2b"} 16:16:42 INFO - Running post-run listener: copy_logs_to_upload_dir 16:16:42 INFO - Copying logs to upload dir... 16:16:42 INFO - mkdir: /builds/slave/test/build/upload/logs 16:16:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.714029 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-05-17 16:16:42.385213) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 16:16:42.388913) ========= 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.xb7lXzsVeE/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.Duk1YXzOl5/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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/Firefox/sha512/17dd8ed4f661664d647bcf322059df31e9e572c8087797db634f55d3333e7f91afa25538fcf50ba2bd33b681f3ec9d473e52acd58833802f5f75c185070983c6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/556a023fe24c2d13581c6454b19bb435ea19e1e0b9f32c1234f2e18ee9cdec7cad55d72c376ac8d5c881072acbcab8ed00a87c22cb4d9c7c1f1cdbf34e7527b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa68bf76ada91354d116daf07c980a3ddd1330b05a68d152a7349b3eadc4d0113d81e1cf5c42aa3717f2dd2957aeaf0f9c0f779c579bdfaff39f8de507683f2b"} build_url:https://queue.taskcluster.net/v1/task/Euvofsy6Q7GtCeCTPY3EKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011111 build_url: 'https://queue.taskcluster.net/v1/task/Euvofsy6Q7GtCeCTPY3EKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/17dd8ed4f661664d647bcf322059df31e9e572c8087797db634f55d3333e7f91afa25538fcf50ba2bd33b681f3ec9d473e52acd58833802f5f75c185070983c6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/556a023fe24c2d13581c6454b19bb435ea19e1e0b9f32c1234f2e18ee9cdec7cad55d72c376ac8d5c881072acbcab8ed00a87c22cb4d9c7c1f1cdbf34e7527b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa68bf76ada91354d116daf07c980a3ddd1330b05a68d152a7349b3eadc4d0113d81e1cf5c42aa3717f2dd2957aeaf0f9c0f779c579bdfaff39f8de507683f2b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 16:16:42.420887) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 16:16:42.421602) ========= 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.xb7lXzsVeE/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.Duk1YXzOl5/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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.004974 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 16:16:42.474124) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 16:16:42.474932) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 16:16:42.475393) ========= ========= Total master_lag: 0.17 =========