builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0366 starttime: 1497112795.45 results: success (0) revision: 8a990794c2ee923ccd839750912d10ff3a690a2a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-10 09:39:55.451055) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-10 09:39:55.451502) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-10 09:39:55.451774) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-10 09:39:55.481153) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-10 09:39:55.481419) ========= 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.1YYtlPNFA7/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.Udnp68UKr5/Listeners TMPDIR=/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/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 --2017-06-10 09:39:55-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 161M=0s 2017-06-10 09:39:56 (161 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.649376 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-10 09:39:56.152451) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-10 09:39:56.152737) ========= 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.1YYtlPNFA7/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.Udnp68UKr5/Listeners TMPDIR=/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/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.021106 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-10 09:39:56.200748) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-10 09:39:56.201089) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8a990794c2ee923ccd839750912d10ff3a690a2a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 8a990794c2ee923ccd839750912d10ff3a690a2a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1YYtlPNFA7/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.Udnp68UKr5/Listeners TMPDIR=/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/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 2017-06-10 09:39:56,256 truncating revision to first 12 chars 2017-06-10 09:39:56,256 Setting DEBUG logging. 2017-06-10 09:39:56,256 attempt 1/10 2017-06-10 09:39:56,256 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8a990794c2ee?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-10 09:39:57,466 unpacking tar archive at: mozilla-central-8a990794c2ee/testing/mozharness/ program finished with exit code 0 elapsedTime=1.486838 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-10 09:39:57.709961) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-10 09:39:57.710328) ========= script_repo_revision: 8a990794c2ee923ccd839750912d10ff3a690a2a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-10 09:39:57.710775) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-10 09:39:57.711100) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-10 09:39:57.729663) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-06-10 09:39:57.729983) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --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', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--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.1YYtlPNFA7/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.Udnp68UKr5/Listeners TMPDIR=/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:39:57 INFO - MultiFileLogger online at 20170610 09:39:57 in /builds/slave/test 09:39:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:39:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:39:57 INFO - {'append_to_log': False, 09:39:57 INFO - 'base_work_dir': '/builds/slave/test', 09:39:57 INFO - 'blob_upload_branch': 'Firefox', 09:39:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:39:57 INFO - 'branch': 'Firefox', 09:39:57 INFO - 'buildbot_json_path': 'buildprops.json', 09:39:57 INFO - 'config_files': ('talos/mac_config.py',), 09:39:57 INFO - 'default_actions': ('clobber', 09:39:57 INFO - 'read-buildbot-config', 09:39:57 INFO - 'download-and-extract', 09:39:57 INFO - 'populate-webroot', 09:39:57 INFO - 'create-virtualenv', 09:39:57 INFO - 'install', 09:39:57 INFO - 'setup-mitmproxy', 09:39:57 INFO - 'run-tests'), 09:39:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:39:57 INFO - 'download_minidump_stackwalk': True, 09:39:57 INFO - 'download_symbols': 'ondemand', 09:39:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:39:57 INFO - 'tooltool.py': '/tools/tooltool.py', 09:39:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:39:57 INFO - '/tools/misc-python/virtualenv.py')}, 09:39:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:39:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:39:57 INFO - 'gecko_profile': False, 09:39:57 INFO - 'gecko_profile_interval': 0, 09:39:57 INFO - 'installer_path': 'installer.exe', 09:39:57 INFO - 'log_level': 'info', 09:39:57 INFO - 'log_name': 'talos', 09:39:57 INFO - 'log_to_console': True, 09:39:57 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:39:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:39:57 INFO - 'opt_config_files': (), 09:39:57 INFO - 'pip_index': False, 09:39:57 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:39:57 INFO - 'cmd': ('bash', 09:39:57 INFO - '-c', 09:39:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:39:57 INFO - 'enabled': True, 09:39:57 INFO - 'halt_on_failure': False, 09:39:57 INFO - 'name': 'check_screen_resolution'},), 09:39:57 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:39:57 INFO - 'cmd': ('bash', 09:39:57 INFO - '-c', 09:39:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:39:57 INFO - 'enabled': True, 09:39:57 INFO - 'halt_on_failure': False, 09:39:57 INFO - 'name': 'check_screen_resolution'},), 09:39:57 INFO - 'run_cmd_checks_enabled': True, 09:39:57 INFO - 'suite': 'dromaeojs-e10s', 09:39:57 INFO - 'system_bits': '32', 09:39:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:39:57 INFO - 'title': 't-yosemite-r7-0366', 09:39:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:39:57 INFO - 'use_talos_json': True, 09:39:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:39:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:39:57 INFO - 'work_dir': 'build'} 09:39:57 INFO - [mozharness: 2017-06-10 16:39:57.905662Z] Running clobber step. 09:39:57 INFO - Running pre-action listener: _resource_record_pre_action 09:39:57 INFO - Running main action method: clobber 09:39:57 INFO - rmtree: /builds/slave/test/build 09:39:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:39:58 INFO - Running post-action listener: _resource_record_post_action 09:39:58 INFO - [mozharness: 2017-06-10 16:39:58.242158Z] Finished clobber step (success) 09:39:58 INFO - [mozharness: 2017-06-10 16:39:58.242359Z] Running read-buildbot-config step. 09:39:58 INFO - Running pre-action listener: _resource_record_pre_action 09:39:58 INFO - Running main action method: read_buildbot_config 09:39:58 INFO - Using buildbot properties: 09:39:58 INFO - { 09:39:58 INFO - "project": "", 09:39:58 INFO - "product": "firefox", 09:39:58 INFO - "who": "ffxbld@noreply.mozilla.org", 09:39:58 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 09:39:58 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:39:58 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 09:39:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:39:58 INFO - "stage_platform": "macosx64", 09:39:58 INFO - "basedir": "/builds/slave/test", 09:39:58 INFO - "buildnumber": 96, 09:39:58 INFO - "platform": "yosemite_r7", 09:39:58 INFO - "parent_task_id": "ObLsJjpsRtqAbwQE4rK4iA", 09:39:58 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 09:39:58 INFO - "slavebuilddir": "test", 09:39:58 INFO - "taskId": "dddamPREQoOjdN096_9y7g", 09:39:58 INFO - "branch": "mozilla-central", 09:39:58 INFO - "script_repo_revision": "production", 09:39:58 INFO - "revision": "8a990794c2ee923ccd839750912d10ff3a690a2a", 09:39:58 INFO - "slavename": "t-yosemite-r7-0366", 09:39:58 INFO - "repo_path": "mozilla-central" 09:39:58 INFO - } 09:39:58 INFO - Finding installer, test and symbols from parent task. 09:39:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dddamPREQoOjdN096_9y7g'}, attempt #1 09:39:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dddamPREQoOjdN096_9y7g'}, attempt #1 09:39:59 INFO - Task dependencies: eVg8-IbsQhSwGrjEB66MLQ ObLsJjpsRtqAbwQE4rK4iA 09:39:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ObLsJjpsRtqAbwQE4rK4iA'}, attempt #1 09:40:00 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ObLsJjpsRtqAbwQE4rK4iA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:40:00 INFO - Set test_url: https://queue.taskcluster.net/v1/task/ObLsJjpsRtqAbwQE4rK4iA/artifacts/public/build/test_packages.json 09:40:00 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ObLsJjpsRtqAbwQE4rK4iA/artifacts/public/build/target.crashreporter-symbols.zip 09:40:00 INFO - Running post-action listener: _resource_record_post_action 09:40:00 INFO - [mozharness: 2017-06-10 16:40:00.094850Z] Finished read-buildbot-config step (success) 09:40:00 INFO - [mozharness: 2017-06-10 16:40:00.094977Z] Running download-and-extract step. 09:40:00 INFO - Running pre-action listener: _resource_record_pre_action 09:40:00 INFO - Running main action method: download_and_extract 09:40:00 INFO - mkdir: /builds/slave/test/build/tests 09:40:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:00 INFO - trying https://queue.taskcluster.net/v1/task/ObLsJjpsRtqAbwQE4rK4iA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 09:40:00 INFO - Downloading https://queue.taskcluster.net/v1/task/ObLsJjpsRtqAbwQE4rK4iA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:40:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ObLsJjpsRtqAbwQE4rK4iA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 09:40:01 INFO - Downloaded 1328 bytes. 09:40:01 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:40:01 INFO - Using the following test package requirements: 09:40:01 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:40:01 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 09:40:01 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:40:01 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:40:01 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 09:40:01 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:40:01 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 09:40:01 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:40:01 INFO - u'jsshell-mac.zip'], 09:40:01 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:40:01 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 09:40:01 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:40:01 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:40:01 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 09:40:01 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:40:01 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 09:40:01 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:40:01 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 09:40:01 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:40:01 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 09:40:01 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:40:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ObLsJjpsRtqAbwQE4rK4iA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:40:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ObLsJjpsRtqAbwQE4rK4iA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:40:01 INFO - Fetch https://queue.taskcluster.net/v1/task/ObLsJjpsRtqAbwQE4rK4iA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:40:02 INFO - Content-Length response header: 59541494 09:40:02 INFO - Bytes received: 59541494 09:40:20 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:40:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ObLsJjpsRtqAbwQE4rK4iA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:40:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ObLsJjpsRtqAbwQE4rK4iA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:40:20 INFO - Fetch https://queue.taskcluster.net/v1/task/ObLsJjpsRtqAbwQE4rK4iA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:40:22 INFO - Content-Length response header: 59541494 09:40:22 INFO - Bytes received: 59541494 09:40:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ObLsJjpsRtqAbwQE4rK4iA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 09:40:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ObLsJjpsRtqAbwQE4rK4iA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:40:39 INFO - Fetch https://queue.taskcluster.net/v1/task/ObLsJjpsRtqAbwQE4rK4iA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 09:40:40 INFO - Content-Length response header: 14450010 09:40:40 INFO - Bytes received: 14450010 09:40:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:40 INFO - trying https://queue.taskcluster.net/v1/task/ObLsJjpsRtqAbwQE4rK4iA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:40:40 INFO - Downloading https://queue.taskcluster.net/v1/task/ObLsJjpsRtqAbwQE4rK4iA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 09:40:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ObLsJjpsRtqAbwQE4rK4iA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 09:40:42 INFO - Downloaded 69245413 bytes. 09:40:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ObLsJjpsRtqAbwQE4rK4iA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:40:42 INFO - mkdir: /builds/slave/test/properties 09:40:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:40:42 INFO - Writing to file /builds/slave/test/properties/build_url 09:40:42 INFO - Contents: 09:40:42 INFO - build_url:https://queue.taskcluster.net/v1/task/ObLsJjpsRtqAbwQE4rK4iA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:40:42 INFO - Running post-action listener: _resource_record_post_action 09:40:42 INFO - Running post-action listener: set_extra_try_arguments 09:40:42 INFO - [mozharness: 2017-06-10 16:40:42.685075Z] Finished download-and-extract step (success) 09:40:42 INFO - [mozharness: 2017-06-10 16:40:42.685193Z] Running populate-webroot step. 09:40:42 INFO - Running pre-action listener: _resource_record_pre_action 09:40:42 INFO - Running main action method: populate_webroot 09:40:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:40:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:40:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:40:42 INFO - '--tppagecycles', 09:40:42 INFO - '7'], 09:40:42 INFO - 'tests': ['tsvgm']}}, 09:40:42 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:40:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:40:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:40:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:40:42 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:40:42 INFO - 'talos_options': ['--disable-e10s'], 09:40:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:40:42 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:40:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:40:42 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:40:42 INFO - 'talos_options': ['--disable-e10s'], 09:40:42 INFO - 'tests': ['damp', 'tps']}, 09:40:42 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:40:42 INFO - 'tests': ['damp', 'tps']}, 09:40:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:40:42 INFO - 'tests': ['dromaeo_dom']}, 09:40:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:40:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:40:42 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 09:40:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:40:42 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 09:40:42 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 09:40:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:40:42 INFO - 'tests': ['a11yr', 09:40:42 INFO - 'ts_paint', 09:40:42 INFO - 'tpaint', 09:40:42 INFO - 'sessionrestore', 09:40:42 INFO - 'sessionrestore_no_auto_restore', 09:40:42 INFO - 'tabpaint']}, 09:40:42 INFO - 'other-e10s': {'tests': ['a11yr', 09:40:42 INFO - 'ts_paint', 09:40:42 INFO - 'tpaint', 09:40:42 INFO - 'sessionrestore', 09:40:42 INFO - 'sessionrestore_no_auto_restore', 09:40:42 INFO - 'tabpaint']}, 09:40:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:40:42 INFO - 'ts_paint', 09:40:42 INFO - 'tpaint', 09:40:42 INFO - 'sessionrestore', 09:40:42 INFO - 'sessionrestore_no_auto_restore', 09:40:42 INFO - 'tabpaint']}, 09:40:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:40:42 INFO - 'ts_paint', 09:40:42 INFO - 'tpaint', 09:40:42 INFO - 'sessionrestore', 09:40:42 INFO - 'sessionrestore_no_auto_restore', 09:40:42 INFO - 'tabpaint']}, 09:40:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:40:42 INFO - 'tests': ['a11yr', 09:40:42 INFO - 'ts_paint', 09:40:42 INFO - 'tpaint', 09:40:42 INFO - 'sessionrestore', 09:40:42 INFO - 'sessionrestore_no_auto_restore', 09:40:42 INFO - 'tabpaint']}, 09:40:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:40:42 INFO - 'tests': ['a11yr', 09:40:42 INFO - 'ts_paint', 09:40:42 INFO - 'tpaint', 09:40:42 INFO - 'sessionrestore', 09:40:42 INFO - 'sessionrestore_no_auto_restore', 09:40:42 INFO - 'tabpaint']}, 09:40:42 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 09:40:42 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 09:40:42 INFO - 'bloom_basic_ref']}, 09:40:42 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:40:42 INFO - 'talos_options': ['--mitmproxy', 09:40:42 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:40:42 INFO - '--firstNonBlankPaint'], 09:40:42 INFO - 'tests': ['quantum_pageload_google', 09:40:42 INFO - 'quantum_pageload_youtube', 09:40:42 INFO - 'quantum_pageload_amazon', 09:40:42 INFO - 'quantum_pageload_facebook']}, 09:40:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:40:42 INFO - 'tests': ['tsvgx', 09:40:42 INFO - 'tsvgr_opacity', 09:40:42 INFO - 'tart', 09:40:42 INFO - 'tscrollx', 09:40:42 INFO - 'cart', 09:40:42 INFO - 'tsvg_static']}, 09:40:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:40:42 INFO - 'tsvgr_opacity', 09:40:42 INFO - 'tart', 09:40:42 INFO - 'tscrollx', 09:40:42 INFO - 'cart', 09:40:42 INFO - 'tsvg_static']}, 09:40:42 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:40:42 INFO - 'talos_options': ['--disable-e10s'], 09:40:42 INFO - 'tests': ['tp5o']}, 09:40:42 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:40:42 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:40:42 INFO - 'talos_options': ['--disable-e10s', 09:40:42 INFO - '--xperf_path', 09:40:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:40:42 INFO - 'tests': ['tp5n']}, 09:40:42 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:40:42 INFO - 'talos_options': ['--xperf_path', 09:40:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:40:42 INFO - 'tests': ['tp5n']}}, 09:40:42 INFO - 'talos.zip': {'path': '', 09:40:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:40:42 INFO - Running post-action listener: _resource_record_post_action 09:40:42 INFO - [mozharness: 2017-06-10 16:40:42.708311Z] Finished populate-webroot step (success) 09:40:42 INFO - [mozharness: 2017-06-10 16:40:42.708453Z] Running create-virtualenv step. 09:40:42 INFO - Running pre-action listener: _resource_record_pre_action 09:40:42 INFO - Running main action method: create_virtualenv 09:40:42 INFO - Creating virtualenv /builds/slave/test/build/venv 09:40:42 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:40:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:40:42 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 09:40:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:40:42 INFO - Using real prefix '/tools/python27' 09:40:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:40:43 INFO - Installing distribute.............................................................................................................................................................................................done. 09:40:46 INFO - Installing pip.................done. 09:40:46 INFO - Return code: 0 09:40:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:40:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:40:46 INFO - Reading from file tmpfile_stdout 09:40:46 INFO - Output received: 09:40:46 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:40:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:40:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:46 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/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 0x1026ecc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9512c734c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102442d30>, '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 0x10240ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102433be8>, '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.1YYtlPNFA7/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.Udnp68UKr5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:40:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:40:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:40:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1YYtlPNFA7/Render', 09:40:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:46 INFO - 'HOME': '/Users/cltbld', 09:40:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:46 INFO - 'LOGNAME': 'cltbld', 09:40:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:46 INFO - 'MOZ_NO_REMOTE': '1', 09:40:46 INFO - 'NO_EM_RESTART': '1', 09:40:46 INFO - 'PAGER': '/bin/cat', 09:40:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:46 INFO - 'PWD': '/builds/slave/test', 09:40:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:46 INFO - 'SHELL': '/bin/bash', 09:40:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Udnp68UKr5/Listeners', 09:40:46 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 09:40:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:46 INFO - 'USER': 'cltbld', 09:40:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:46 INFO - 'XPC_FLAGS': '0x0', 09:40:46 INFO - 'XPC_SERVICE_NAME': '0', 09:40:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:46 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:40:46 INFO - Cleaning up... 09:40:46 INFO - Return code: 0 09:40:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:40:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:46 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/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 0x1026ecc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9512c734c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102442d30>, '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 0x10240ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102433be8>, '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.1YYtlPNFA7/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.Udnp68UKr5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:40:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:40:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:40:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1YYtlPNFA7/Render', 09:40:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:46 INFO - 'HOME': '/Users/cltbld', 09:40:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:46 INFO - 'LOGNAME': 'cltbld', 09:40:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:46 INFO - 'MOZ_NO_REMOTE': '1', 09:40:46 INFO - 'NO_EM_RESTART': '1', 09:40:46 INFO - 'PAGER': '/bin/cat', 09:40:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:46 INFO - 'PWD': '/builds/slave/test', 09:40:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:46 INFO - 'SHELL': '/bin/bash', 09:40:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Udnp68UKr5/Listeners', 09:40:46 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 09:40:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:46 INFO - 'USER': 'cltbld', 09:40:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:46 INFO - 'XPC_FLAGS': '0x0', 09:40:46 INFO - 'XPC_SERVICE_NAME': '0', 09:40:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:47 INFO - Downloading/unpacking psutil>=3.1.1 09:40:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:40:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:40:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:40:49 INFO - Installing collected packages: psutil 09:40:49 INFO - Running setup.py install for psutil 09:40:49 INFO - building 'psutil._psutil_osx' extension 09:40:49 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:40:49 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:40:49 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:40:49 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:40:49 INFO - building 'psutil._psutil_posix' extension 09:40:49 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:40:50 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:40:50 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:40:50 INFO - ^ 09:40:50 INFO - 1 warning generated. 09:40:50 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:40:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:40:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:40:50 INFO - Successfully installed psutil 09:40:50 INFO - Cleaning up... 09:40:50 INFO - Return code: 0 09:40:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:40:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:50 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/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 0x1026ecc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9512c734c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102442d30>, '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 0x10240ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102433be8>, '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.1YYtlPNFA7/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.Udnp68UKr5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:40:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:40:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:40:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1YYtlPNFA7/Render', 09:40:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:50 INFO - 'HOME': '/Users/cltbld', 09:40:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:50 INFO - 'LOGNAME': 'cltbld', 09:40:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:50 INFO - 'MOZ_NO_REMOTE': '1', 09:40:50 INFO - 'NO_EM_RESTART': '1', 09:40:50 INFO - 'PAGER': '/bin/cat', 09:40:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:50 INFO - 'PWD': '/builds/slave/test', 09:40:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:50 INFO - 'SHELL': '/bin/bash', 09:40:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Udnp68UKr5/Listeners', 09:40:50 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 09:40:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:50 INFO - 'USER': 'cltbld', 09:40:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:50 INFO - 'XPC_FLAGS': '0x0', 09:40:50 INFO - 'XPC_SERVICE_NAME': '0', 09:40:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:40:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:40:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:40:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:40:51 INFO - Installing collected packages: mozsystemmonitor 09:40:51 INFO - Running setup.py install for mozsystemmonitor 09:40:52 INFO - Successfully installed mozsystemmonitor 09:40:52 INFO - Cleaning up... 09:40:52 INFO - Return code: 0 09:40:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:40:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:52 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1026ecc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9512c734c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102442d30>, '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 0x10240ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102433be8>, '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.1YYtlPNFA7/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.Udnp68UKr5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:40:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:40:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:40:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1YYtlPNFA7/Render', 09:40:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:52 INFO - 'HOME': '/Users/cltbld', 09:40:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:52 INFO - 'LOGNAME': 'cltbld', 09:40:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:52 INFO - 'MOZ_NO_REMOTE': '1', 09:40:52 INFO - 'NO_EM_RESTART': '1', 09:40:52 INFO - 'PAGER': '/bin/cat', 09:40:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:52 INFO - 'PWD': '/builds/slave/test', 09:40:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:52 INFO - 'SHELL': '/bin/bash', 09:40:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Udnp68UKr5/Listeners', 09:40:52 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 09:40:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:52 INFO - 'USER': 'cltbld', 09:40:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:52 INFO - 'XPC_FLAGS': '0x0', 09:40:52 INFO - 'XPC_SERVICE_NAME': '0', 09:40:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:52 INFO - Downloading/unpacking jsonschema==2.5.1 09:40:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:40:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:40: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 09:40: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 09:40: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 09:40: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 09:40:54 INFO - Downloading functools32-3.2.3-2.tar.gz 09:40:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:40:54 INFO - warning: no files found matching '*.txt' 09:40:54 INFO - no previously-included directories found matching 'build' 09:40:54 INFO - no previously-included directories found matching 'dist' 09:40:54 INFO - no previously-included directories found matching '.git*' 09:40:54 INFO - Installing collected packages: jsonschema, functools32 09:40:54 INFO - Running setup.py install for functools32 09:40:54 INFO - warning: no files found matching '*.txt' 09:40:54 INFO - no previously-included directories found matching 'build' 09:40:54 INFO - no previously-included directories found matching 'dist' 09:40:54 INFO - no previously-included directories found matching '.git*' 09:40:54 INFO - Successfully installed jsonschema functools32 09:40:54 INFO - Cleaning up... 09:40:54 INFO - Return code: 0 09:40:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:40:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1026ecc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9512c734c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102442d30>, '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 0x10240ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102433be8>, '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.1YYtlPNFA7/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.Udnp68UKr5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:40:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:40:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:40:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1YYtlPNFA7/Render', 09:40:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:54 INFO - 'HOME': '/Users/cltbld', 09:40:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:54 INFO - 'LOGNAME': 'cltbld', 09:40:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:54 INFO - 'MOZ_NO_REMOTE': '1', 09:40:54 INFO - 'NO_EM_RESTART': '1', 09:40:54 INFO - 'PAGER': '/bin/cat', 09:40:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:54 INFO - 'PWD': '/builds/slave/test', 09:40:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:54 INFO - 'SHELL': '/bin/bash', 09:40:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Udnp68UKr5/Listeners', 09:40:54 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 09:40:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:54 INFO - 'USER': 'cltbld', 09:40:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:54 INFO - 'XPC_FLAGS': '0x0', 09:40:54 INFO - 'XPC_SERVICE_NAME': '0', 09:40:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:40:54 INFO - Cleaning up... 09:40:54 INFO - Return code: 0 09:40:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:40:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1026ecc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9512c734c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102442d30>, '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 0x10240ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102433be8>, '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.1YYtlPNFA7/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.Udnp68UKr5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:40:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:40:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:40:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1YYtlPNFA7/Render', 09:40:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:54 INFO - 'HOME': '/Users/cltbld', 09:40:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:54 INFO - 'LOGNAME': 'cltbld', 09:40:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:54 INFO - 'MOZ_NO_REMOTE': '1', 09:40:54 INFO - 'NO_EM_RESTART': '1', 09:40:54 INFO - 'PAGER': '/bin/cat', 09:40:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:54 INFO - 'PWD': '/builds/slave/test', 09:40:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:54 INFO - 'SHELL': '/bin/bash', 09:40:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Udnp68UKr5/Listeners', 09:40:54 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 09:40:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:54 INFO - 'USER': 'cltbld', 09:40:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:54 INFO - 'XPC_FLAGS': '0x0', 09:40:54 INFO - 'XPC_SERVICE_NAME': '0', 09:40:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:54 INFO - Downloading/unpacking blobuploader==1.2.4 09:40:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:57 INFO - Downloading blobuploader-1.2.4.tar.gz 09:40:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:40:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:40:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:40:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:40:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:58 INFO - Downloading docopt-0.6.1.tar.gz 09:40:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:40:58 INFO - Installing collected packages: blobuploader, requests, docopt 09:40:58 INFO - Running setup.py install for blobuploader 09:40:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:40:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:40:58 INFO - Running setup.py install for requests 09:40:58 INFO - Running setup.py install for docopt 09:40:59 INFO - Successfully installed blobuploader requests docopt 09:40:59 INFO - Cleaning up... 09:40:59 INFO - Return code: 0 09:40:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:40:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1026ecc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9512c734c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102442d30>, '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 0x10240ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102433be8>, '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.1YYtlPNFA7/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.Udnp68UKr5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:40:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:40:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:40:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1YYtlPNFA7/Render', 09:40:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:59 INFO - 'HOME': '/Users/cltbld', 09:40:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:59 INFO - 'LOGNAME': 'cltbld', 09:40:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:59 INFO - 'MOZ_NO_REMOTE': '1', 09:40:59 INFO - 'NO_EM_RESTART': '1', 09:40:59 INFO - 'PAGER': '/bin/cat', 09:40:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:59 INFO - 'PWD': '/builds/slave/test', 09:40:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:59 INFO - 'SHELL': '/bin/bash', 09:40:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Udnp68UKr5/Listeners', 09:40:59 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 09:40:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:59 INFO - 'USER': 'cltbld', 09:40:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:59 INFO - 'XPC_FLAGS': '0x0', 09:40:59 INFO - 'XPC_SERVICE_NAME': '0', 09:40:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:40:59 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-PPJYwL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:40:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:40:59 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-seXW_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:40:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:40:59 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-vdYQnV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:40:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:40:59 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-Qpa0AC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:40:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:40:59 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-3uF8O_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:40:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:40:59 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-c8pFob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:41:00 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-0RYHWs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:41:00 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-SqyPdV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:41:00 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-cUKgA1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:41:00 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-LH2yb5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:41:00 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-6pEQbl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:41:00 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-6Kg0tH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:41:00 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-IZ3_cI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:41:00 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-IMYgMB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:41:00 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-DET9rL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:41:00 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-Nbt_wg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:41:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:41:01 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-TU0bu6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:41:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:41:01 INFO - Running setup.py install for manifestparser 09:41:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:41:01 INFO - Running setup.py install for mozcrash 09:41:01 INFO - Running setup.py install for mozdebug 09:41:01 INFO - Running setup.py install for mozdevice 09:41:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:41:01 INFO - Running setup.py install for mozfile 09:41:01 INFO - Running setup.py install for mozhttpd 09:41:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:41:02 INFO - Running setup.py install for mozinfo 09:41:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:41:02 INFO - Running setup.py install for mozInstall 09:41:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:41:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:41:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:41:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:41:02 INFO - Running setup.py install for mozleak 09:41:02 INFO - Running setup.py install for mozlog 09:41:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:41:02 INFO - Running setup.py install for moznetwork 09:41:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:41:02 INFO - Running setup.py install for mozprocess 09:41:02 INFO - Running setup.py install for mozprofile 09:41:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:41:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:41:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:41:03 INFO - Running setup.py install for mozrunner 09:41:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:41:03 INFO - Running setup.py install for mozscreenshot 09:41:03 INFO - Running setup.py install for moztest 09:41:03 INFO - Running setup.py install for mozversion 09:41:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:41:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:41:03 INFO - Cleaning up... 09:41:03 INFO - Return code: 0 09:41:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:41:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:41:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:41:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:41:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:41:03 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/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 0x1026ecc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9512c734c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102442d30>, '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 0x10240ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102433be8>, '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.1YYtlPNFA7/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.Udnp68UKr5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:41:03 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:41:03 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:41:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1YYtlPNFA7/Render', 09:41:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:41:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:41:03 INFO - 'HOME': '/Users/cltbld', 09:41:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:41:03 INFO - 'LOGNAME': 'cltbld', 09:41:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:41:03 INFO - 'MOZ_NO_REMOTE': '1', 09:41:03 INFO - 'NO_EM_RESTART': '1', 09:41:03 INFO - 'PAGER': '/bin/cat', 09:41:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:41:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:41:03 INFO - 'PWD': '/builds/slave/test', 09:41:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:41:03 INFO - 'SHELL': '/bin/bash', 09:41:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Udnp68UKr5/Listeners', 09:41:03 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 09:41:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:41:03 INFO - 'USER': 'cltbld', 09:41:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:41:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:41:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:41:03 INFO - 'XPC_FLAGS': '0x0', 09:41:03 INFO - 'XPC_SERVICE_NAME': '0', 09:41:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:41:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:41:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:41:04 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-EUgRkg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:41:04 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:41:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:41:04 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-NZlrLF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:41:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:41:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:41:04 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-4vu0Wp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:41:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:41:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:41:04 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-aCHmyt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:41:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:41:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:41:04 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-9IcjpR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:41:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:41:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:41:04 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-lTb5hW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:41:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:41:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:41:04 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-VcbDld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:41:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:41:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:41:04 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-vxZZL5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:41:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:41:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:41:04 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-of0DAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:41:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:41:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:41:04 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-SYj3py-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:41:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:41:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:41:05 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-GNKZZQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:41:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:41:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:41:05 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-wq8txE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:41:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:41:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:41:05 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-uhNITm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:41:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:41:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:41:05 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-W_Wjwt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:41:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:41:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:41:05 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-OPYBja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:41:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:41:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:41:05 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-g_hmSG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:41:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:41:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:41:05 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-miEf3G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:41:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:41:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:41:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:41:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:41:05 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:41:05 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:41:05 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:41:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:41:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:41:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:41:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:41:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:41:07 INFO - Downloading blessings-1.6.tar.gz 09:41:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:41:07 INFO - Installing collected packages: blessings 09:41:07 INFO - Running setup.py install for blessings 09:41:07 INFO - Successfully installed blessings 09:41:07 INFO - Cleaning up... 09:41:07 INFO - Return code: 0 09:41:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:41:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:41:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:41:07 INFO - Reading from file tmpfile_stdout 09:41:07 INFO - Current package versions: 09:41:07 INFO - blessings == 1.6 09:41:07 INFO - blobuploader == 1.2.4 09:41:07 INFO - docopt == 0.6.1 09:41:07 INFO - functools32 == 3.2.3-2 09:41:07 INFO - jsonschema == 2.5.1 09:41:07 INFO - manifestparser == 1.1 09:41:07 INFO - mozInstall == 1.13 09:41:07 INFO - mozcrash == 1.0 09:41:07 INFO - mozdebug == 0.1 09:41:07 INFO - mozdevice == 0.51 09:41:07 INFO - mozfile == 1.2 09:41:07 INFO - mozhttpd == 0.7 09:41:07 INFO - mozinfo == 0.10 09:41:07 INFO - mozleak == 0.1 09:41:07 INFO - mozlog == 3.4 09:41:07 INFO - moznetwork == 0.27 09:41:07 INFO - mozprocess == 0.25 09:41:07 INFO - mozprofile == 0.28 09:41:07 INFO - mozrunner == 6.13 09:41:07 INFO - mozscreenshot == 0.1 09:41:07 INFO - mozsystemmonitor == 0.3 09:41:07 INFO - moztest == 0.8 09:41:07 INFO - mozversion == 1.4 09:41:07 INFO - psutil == 3.1.1 09:41:07 INFO - requests == 1.2.3 09:41:07 INFO - wsgiref == 0.1.2 09:41:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:41:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:41:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:41:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:41:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:41:07 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/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 0x1026ecc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9512c734c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102442d30>, '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 0x10240ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102433be8>, '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.1YYtlPNFA7/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.Udnp68UKr5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:41:07 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:41:07 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:41:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1YYtlPNFA7/Render', 09:41:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:41:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:41:07 INFO - 'HOME': '/Users/cltbld', 09:41:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:41:07 INFO - 'LOGNAME': 'cltbld', 09:41:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:41:07 INFO - 'MOZ_NO_REMOTE': '1', 09:41:07 INFO - 'NO_EM_RESTART': '1', 09:41:07 INFO - 'PAGER': '/bin/cat', 09:41:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:41:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:41:07 INFO - 'PWD': '/builds/slave/test', 09:41:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:41:07 INFO - 'SHELL': '/bin/bash', 09:41:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Udnp68UKr5/Listeners', 09:41:07 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 09:41:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:41:07 INFO - 'USER': 'cltbld', 09:41:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:41:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:41:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:41:07 INFO - 'XPC_FLAGS': '0x0', 09:41:07 INFO - 'XPC_SERVICE_NAME': '0', 09:41:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:41:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:41:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:41:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:41:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:41:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:41:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:41:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:41:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:41:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:41:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:41:08 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:41:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:41:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:41:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:41:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:41:09 INFO - Downloading marionette_driver-1.4.0.tar.gz 09:41:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 09:41:09 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:41:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:41:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:41:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:41:09 INFO - Installing collected packages: marionette-driver 09:41:09 INFO - Running setup.py install for marionette-driver 09:41:09 INFO - Successfully installed marionette-driver 09:41:09 INFO - Cleaning up... 09:41:09 INFO - Return code: 0 09:41:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:41:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:41:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:41:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:41:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:41:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1026ecc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9512c734c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102442d30>, '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 0x10240ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102433be8>, '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.1YYtlPNFA7/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.Udnp68UKr5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:41:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:41:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:41:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1YYtlPNFA7/Render', 09:41:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:41:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:41:09 INFO - 'HOME': '/Users/cltbld', 09:41:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:41:09 INFO - 'LOGNAME': 'cltbld', 09:41:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:41:09 INFO - 'MOZ_NO_REMOTE': '1', 09:41:09 INFO - 'NO_EM_RESTART': '1', 09:41:09 INFO - 'PAGER': '/bin/cat', 09:41:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:41:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:41:09 INFO - 'PWD': '/builds/slave/test', 09:41:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:41:09 INFO - 'SHELL': '/bin/bash', 09:41:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Udnp68UKr5/Listeners', 09:41:09 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 09:41:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:41:09 INFO - 'USER': 'cltbld', 09:41:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:41:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:41:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:41:09 INFO - 'XPC_FLAGS': '0x0', 09:41:09 INFO - 'XPC_SERVICE_NAME': '0', 09:41:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:41:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:41:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:41:10 INFO - Cleaning up... 09:41:10 INFO - Return code: 0 09:41:10 INFO - Running post-action listener: _resource_record_post_action 09:41:10 INFO - Running post-action listener: _start_resource_monitoring 09:41:10 INFO - Starting resource monitoring. 09:41:10 INFO - [mozharness: 2017-06-10 16:41:10.260876Z] Finished create-virtualenv step (success) 09:41:10 INFO - [mozharness: 2017-06-10 16:41:10.261851Z] Running install step. 09:41:10 INFO - Running pre-action listener: _resource_record_pre_action 09:41:10 INFO - Running main action method: install 09:41:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:41:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:41:10 INFO - Reading from file tmpfile_stdout 09:41:10 INFO - Detecting whether we're running mozinstall >=1.0... 09:41:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:41:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:41:10 INFO - Reading from file tmpfile_stdout 09:41:10 INFO - Output received: 09:41:10 INFO - Usage: mozinstall [options] installer 09:41:10 INFO - Options: 09:41:10 INFO - -h, --help show this help message and exit 09:41:10 INFO - -d DEST, --destination=DEST 09:41:10 INFO - Directory to install application into. [default: 09:41:10 INFO - "/builds/slave/test"] 09:41:10 INFO - --app=APP Application being installed. [default: firefox] 09:41:10 INFO - mkdir: /builds/slave/test/build/application 09:41:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:41:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:41:32 INFO - Reading from file tmpfile_stdout 09:41:32 INFO - Output received: 09:41:32 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:41:32 INFO - Running post-action listener: _resource_record_post_action 09:41:32 INFO - [mozharness: 2017-06-10 16:41:32.345191Z] Finished install step (success) 09:41:32 INFO - [mozharness: 2017-06-10 16:41:32.345328Z] Running setup-mitmproxy step. 09:41:32 INFO - Running pre-action listener: _resource_record_pre_action 09:41:32 INFO - Running main action method: setup_mitmproxy 09:41:32 INFO - Skipping: mitmproxy is not required 09:41:32 INFO - Running post-action listener: _resource_record_post_action 09:41:32 INFO - [mozharness: 2017-06-10 16:41:32.345989Z] Finished setup-mitmproxy step (success) 09:41:32 INFO - [mozharness: 2017-06-10 16:41:32.346143Z] Running run-tests step. 09:41:32 INFO - Running pre-action listener: _resource_record_pre_action 09:41:32 INFO - Running main action method: run_tests 09:41:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:41:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:41:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:41:32 INFO - 2017-06-10 09:41:32.360 screenresolution[1974:282791] starting screenresolution argv=screenresolution get 09:41:32 INFO - 2017-06-10 09:41:32.363 screenresolution[1974:282791] Display 0: 1600x1200x32@60 09:41:32 INFO - 2017-06-10 09:41:32.369 screenresolution[1975:282793] starting screenresolution argv=screenresolution list 09:41:32 INFO - Available Modes on Display 0 09:41:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:41:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:41:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:41:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:41:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:41:32 INFO - Intel Iris: 09:41:32 INFO - Chipset Model: Intel Iris 09:41:32 INFO - Type: GPU 09:41:32 INFO - Bus: Built-In 09:41:32 INFO - VRAM (Dynamic, Max): 1536 MB 09:41:32 INFO - Vendor: Intel (0x8086) 09:41:32 INFO - Device ID: 0x0a2e 09:41:32 INFO - Revision ID: 0x0009 09:41:32 INFO - Displays: 09:41:32 INFO - Display: 09:41:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:41:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:41:32 INFO - Display Serial Number: 1600x1200 60 09:41:32 INFO - Main Display: Yes 09:41:32 INFO - Mirror: Off 09:41:32 INFO - Online: Yes 09:41:32 INFO - Rotation: Supported 09:41:32 INFO - Return code: 0 09:41:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:41:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:41:32 INFO - Python 2.7.3 09:41:32 INFO - Return code: 0 09:41:32 INFO - grabbing minidump binary from tooltool 09:41:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:41:32 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 0x102442d30>, '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 0x10240ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102433be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:41:32 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:41:32 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:41:32 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:41:32 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:41:32 INFO - Return code: 0 09:41:32 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:41:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:41:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:41:32 INFO - ENV: RUST_BACKTRACE is now 1 09:41:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:41:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:41:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ObLsJjpsRtqAbwQE4rK4iA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0366', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:41:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ObLsJjpsRtqAbwQE4rK4iA/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0366 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:41:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1YYtlPNFA7/Render', 09:41:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:41:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:41:32 INFO - 'HOME': '/Users/cltbld', 09:41:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:41:32 INFO - 'LOGNAME': 'cltbld', 09:41:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:41:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:41:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:41:32 INFO - 'MOZ_NO_REMOTE': '1', 09:41:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:41:32 INFO - 'NO_EM_RESTART': '1', 09:41:32 INFO - 'PAGER': '/bin/cat', 09:41:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:41:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:41:32 INFO - 'PWD': '/builds/slave/test', 09:41:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:41:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:41:32 INFO - 'RUST_BACKTRACE': '1', 09:41:32 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:41:32 INFO - 'SHELL': '/bin/bash', 09:41:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Udnp68UKr5/Listeners', 09:41:32 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 09:41:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:41:32 INFO - 'USER': 'cltbld', 09:41:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:41:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:41:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:41:32 INFO - 'XPC_FLAGS': '0x0', 09:41:32 INFO - 'XPC_SERVICE_NAME': '0', 09:41:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:41:32 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ObLsJjpsRtqAbwQE4rK4iA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0366', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:41:32 INFO - mozversion application_buildid: 20170610083230 09:41:32 INFO - mozversion application_changeset: 8a990794c2ee923ccd839750912d10ff3a690a2a 09:41:32 INFO - mozversion application_display_name: Nightly 09:41:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:41:32 INFO - mozversion application_name: Firefox 09:41:32 INFO - mozversion application_remotingname: firefox 09:41:32 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:41:32 INFO - mozversion application_vendor: Mozilla 09:41:32 INFO - mozversion application_version: 55.0a1 09:41:32 INFO - mozversion platform_buildid: 20170610083230 09:41:32 INFO - mozversion platform_changeset: 8a990794c2ee923ccd839750912d10ff3a690a2a 09:41:32 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:41:32 INFO - mozversion platform_version: 55.0a1 09:41:32 INFO - using testdate: 1497112892 09:41:32 INFO - actual date: 1497112892 09:41:32 INFO - starting webserver on 'localhost:49283' 09:41:33 INFO - SUITE-START | Running 2 tests 09:41:33 INFO - TEST-START | dromaeo_css 09:41:33 INFO - operating with platform_type : mac_ 09:41:33 INFO - Initialising browser for dromaeo_css test... 09:41:33 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49283/getInfo.html -foreground -profile /var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/tmpYh7GLW/profile 09:41:33 INFO - TEST-INFO | started process 1985 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49283/getInfo.html -foreground) 09:42:07 INFO - TEST-INFO | 1985: killed by SIGTERM 09:42:07 INFO - Browser initialized. 09:42:07 INFO - Running cycle 1/1 for dromaeo_css test... 09:42:07 INFO - TEST-INFO | started process 1995 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/tmpYh7GLW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:42:09 INFO - PID 1995 | 2017-06-10 09:42:09.097 plugin-container[1996:283460] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x993f, name = 'com.apple.tsm.portname' 09:42:09 INFO - PID 1995 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:42:09 INFO - PID 1995 | 2017-06-10 09:42:09.098 plugin-container[1996:283460] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f17, name = 'com.apple.CFPasteboardClient' 09:42:09 INFO - PID 1995 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:42:09 INFO - PID 1995 | 2017-06-10 09:42:09.098 plugin-container[1996:283460] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:42:09 INFO - PID 1995 | 2017-06-10 09:42:09.847 plugin-container[1997:283507] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 09:42:09 INFO - PID 1995 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:42:09 INFO - PID 1995 | 2017-06-10 09:42:09.847 plugin-container[1997:283507] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 09:42:09 INFO - PID 1995 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:42:09 INFO - PID 1995 | 2017-06-10 09:42:09.847 plugin-container[1997:283507] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:42:19 INFO - PID 1995 | RSS: Main: 253059072 09:42:19 INFO - PID 1995 | 09:45:21 INFO - PID 1995 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49283/tests/dromaeo/cssquery-ext.html) 09:45:22 INFO - PID 1995 | RSS: Main: 263106560 09:45:22 INFO - PID 1995 | 09:48:24 INFO - PID 1995 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-ext.html (next: http://localhost:49283/tests/dromaeo/cssquery-jquery.html) 09:48:25 INFO - PID 1995 | RSS: Main: 274497536 09:48:25 INFO - PID 1995 | 09:51:27 INFO - PID 1995 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49283/tests/dromaeo/cssquery-mootools.html) 09:51:27 INFO - PID 1995 | RSS: Main: 277876736 09:51:27 INFO - PID 1995 | 09:54:29 INFO - PID 1995 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49283/tests/dromaeo/cssquery-prototype.html) 09:54:30 INFO - PID 1995 | RSS: Main: 280633344 09:54:30 INFO - PID 1995 | 09:57:33 INFO - PID 1995 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49283/tests/dromaeo/cssquery-yui.html) 09:57:33 INFO - PID 1995 | RSS: Main: 279678976 09:57:33 INFO - PID 1995 | 10:00:35 INFO - PID 1995 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-yui.html (next: http://localhost:49283/tests/dromaeo/cssquery-dojo.html) 10:00:35 INFO - PID 1995 | RSS: Main: 278872064 10:00:35 INFO - PID 1995 | 10:00:35 INFO - PID 1995 | __start_tp_report 10:00:35 INFO - PID 1995 | _x_x_mozilla_page_load 10:00:35 INFO - PID 1995 | _x_x_mozilla_page_load_details 10:00:35 INFO - PID 1995 | |i|pagename|runs| 10:00:35 INFO - PID 1995 | |0;dojo.html;2868.211241908475;3587.0494319005466;3630.1287690954164;3640.1991561856385;3648.1426864686796;4806.399200099988;5356.330458692663;5701.657900525971;6242.157308763316;6375.5218358623115;6257.468115210201;6265.906011409821;6300.55896087273;6334.841628959279;6355.8411039723915;4821.807127714887;5244.531531171278;5506.862328441781;5550.222968784364;5620.494155526002;5232.822614911653;5514.283143191379;5660.5471562274915;5675.177099320596;5689.800856970007;5469.835904922858;5510.559155267569;5526.336839579276;5603.663780173202;5608.130739735348;5555.083411237141;5562.2212890195;5564.081926482126;5604.299462567179;5653;5530.695811730353;5538.556915446755;5584.916226256569;5588.161775733632;5600.327960644708;4728.40894888139;6173.950428427178;6298.716557754912;6336.778212762531;6341.778037768656;6561.540692151551;6675.332466753286;6694.163229596301;6696.5647232929705;6710.7315707371645;6968.930310696929;6973.05863708403;6975.546589471668;6985.406240469566;6987.755428560027;6332.018537126661;6402.356818295264;6435.742570297136;6441.967790161066;6451.903221451682;6994.33553812387;7000.124984376953;7083.92916070843;7096.50324477292;7134.607596582134;6572.572782769104;6643.60138391698;6682.298358672367;6685.197776266879;6770.932290677129;6853.931460685429;6964.825879353057;6969.686364113627;6976.023356730062;6980.197277313073;5323.653962492425;6836.623985680835;6848.794536163923;6858.971154326891;6877.828054298582;6658.1344425224415;6734.730610775612;6784.3894049535775;6813.4889883257965;6856.965715171392;5431.755570999315;5481.177823326533;5495.368032676213;5496.340439147248;5497.725113744297;4824.372831531879;4838.491958344393;4843.927341089887;4858.975705121523;4884.438289596671;5214.478552144831;5277.102892508283;6119.602225855394;6299.275583307886;6381.266154392211;6990.335918087773;6997.755078572225;7007.4394048476;7009.649517524103;7032.156141263081;6419.807405777834;6448.967755161194;6467.611943283418;6468;6487.481001519867;4776.880577985508;5810.94189058104;5902.61632993854;6003.099535069775;6074.0281554951;7031.824204394931;7088.751893683696;7095.3968912643;7118.857622847618;7132.786016419515;6939.618320992394;6964.094667693167;6981.847995080755;7007.194172670208;7022.016917631434;6878.105846239957;6954.826129346605;7078.1506219254015;7082.256363082009;7120.394766444911;6713.362230588086;6720.227173874871;6820.658967051529;6827.385535301847;6866.416354609714;6977.720891164499;6981.197162326194;6987.02181694553;6988.266232045562;7000.054992575937;6581.769638062645;6635.535512514078;6648.667566621746;6665.433438157617;6698.129243198548;6078.696065196811;6415.550911436155;6747.156605424322;6749.920012797928;6774.593524388552;5561.916571251515;6413.134226879311;6603.339666033358;6636.303188165173;6641.236257830411;6496.090547323473;6601.669916504252;6638.701258443478;6756.932430675631;6757.932420675927;6501.707423166064;6527.11883895687;6574.013897915351;6579.572327798678;6601.471882249504 10:00:35 INFO - PID 1995 | |1;ext.html;790.1663744749291;820.7619790260825;841.4488510025933;1258.9559365422206;1317.3215793866154;37516.812415937915;38840.61159388405;38854.028649283784;38997.22005559887;39018.60981390185;39270.410943835865;39530.802345988304;39545.40681889774;39564.21961011754;39633;7900.32847207988;8056.274935255826;8184.345252379795;8407;8408.747737567883;8355.498670079814;8458.154184581554;8467.788305292355;8470.44942078766;8478.152184781504;8440.78898027551;8494.065652778221;8498.405111642156;8504.532250726208;8511.74464766058;8444.535550544717;8463.66145354185;8476.915230847675;8485.193906578865;8518.914810851875;8044.316233120192;8160.30637395822;8281.37889658278;8362.707305244348;8384.916150838475;36801.89594312175;36840;36878;37070;37126.25747485062;3560.6795388414907;3596.2987217492337;3597.478365636968;3600.261946301002;3603.0812142903656;30384.696153038407;30493.237669058228;30502.84748576265;30520.694793052007;30572.08283751491;3043.3913217356394;3049.6340439147443;3064.6935306469177;3096.612923384577;3099.876004959799;217811;218375;251835;286440;288850.55574722204;47532;48025.75987120042;48093.75953120212;48094.27858582124;48158.759206203744;49328;49855.252171217464;49905;51604;52860;14343;14437.35031923545;14451.78322325166;14459;14532.200728959797;11450.141239407078;11499.885001150049;11519.596814111466;11540.961313481826;11546.960773530254;14123.505677301246;14168.85831141696;14208.431662733581;14213.928930355281;14250.572482825532;6262.1546091278005;6302.905456418157;6307.653079080695;6316.526260530479;6320.399562041599;955.4744890310302;991.5686676295834;992.7964767222702;1041.2815157541272;1042.6559235452282;19766.802331976498;22783;22810.7718922812;22988.310350689506;23886.402839928793;1021.2902033087082;1060.522764755863;1086.559943222996;1134.5348407153028;1136.4261048170786;9975.793664600618;10083.798324033478;10151.289409741246;11686.532538698528;12234.792100823694;270169;278683;295859;298151;301582;45954.62136135921;54870.72564637231;54927;55570.16644750332;55609.72195138998;1472.6244807574215;1499.7600383938736;1503.639126609606;1514.5153550863838;1524.618845288678;15724.29240684149;22423.32730018102;22450.663240051086;23517.64723529114;23772.286831395086;37386;37552.81223593865;37580.06049848776;37742.62257377391;37823.43264851085;239119;239527;239792.8010359937;239950;240201;29997.250068747584;36499;36637.63362366342;36662.8166859164;36746;44267.33598996082;44290.335644966;45600.85997849947;45818;45899.852503687674;24880.25359239226;28484.287892802018;29109.272268193465;29553.704462955095;30477;24168.63747043831;26448.8677556611;27838.443231134857;30253.394932100793;31004.68995310018;35768;36835.447468287464;37029;37092.70175543843;37175.442368363954;27645.235476453;31162;31205.687943121186;31928.5210721844;32196;22117.668234976165;22234.444138896655;24272.1504747333;24354.634680479452;24792.876035619705 10:00:35 INFO - PID 1995 | |2;jquery.html;451.28921947931997;504.4425909370146;511.6086194061542;523.552362729866;532.5579768791903;21648.2423115191;22479.438014049658;23418.765812341873;23491.942862571184;24872;24251.514969700595;24772.50454990899;24811.883465244064;24820.758962051914;24912.252632421012;6427.710753016114;6538.8692226155445;6553.54125211233;6572.244191917943;6580.967095164518;6294.307626161119;6460.672753531935;6498.155239818817;6522.641254730988;6550.934490655104;6308;6327.715252813611;6371.904421433682;6377.744890204386;6378.4578310843635;6322.652254126021;6357.205349331333;6369.808905732789;6374.235091788969;6386.265579458375;5807.941920580824;5812;5817.592768506206;5819.650820950714;5826.504747096502;29505.852470737722;30309.24226894323;30380;30499.23751906198;30732;2104.873707577535;2141.1863491872987;2141.9464513387134;2144.699742036101;2147.1840700533853;19023.524411889674;19353.9032304839;19425.902870485555;19572.510687232792;19583.706244406345;2181.4001149683804;2213.900374483152;2250.628646273363;2258.8531745436494;2260.4461906832767;164282;170522;193148;199443.0027849866;203150.9842450793;12803;14614.926925365306;15012.47456339023;15106.320215590325;15644.608884777972;20204;20363.79636203619;20404.387868363974;20414.08136633855;20476.078576464093;9798.804023919482;9870.654527091518;9911.504424778876;9948.40309581413;9973.700788976343;9157.633694652273;9166.083391660914;9171.082891710776;9176.72469825893;9228.123328283802;7437.219091995263;9163.908360916439;9202.447853128833;9214.401063930824;9250.259979201648;2589.805764567665;2596.350912271932;2598.8180827341903;2612.137994461823;2624;893.8257039877292;939.4222553129775;952.3904700991371;957.712686194139;979.0747743382577;16890.493285201464;17657.117144142743;21470.463238419165;21640.89894803929;21783.019764110653;962.855571664256;974.6296407365156;1011.4639241202175;1013.4578000769553;1033.7777377863795;8358.498490090615;9900.752481188028;10906.618268360728;11173.944130279297;11422.31466112036;522510;524329;526661;534926;537056;157135;158589;158700;158775;160177;993.7267251505893;1031.566741968375;1054.625607909181;1128.3737525673166;1173.730042090324;22025.898705065003;22543.549129017654;23800.4049898747;23814.16650417227;23822.523549529258;63729.72540549256;64385;64490;64543;66381.33618663752;88709.55645221732;89015;89596;90081.0991890099;90729.54635226782;11530.192886497865;11785.469653865768;11882.82175767347;11903.464344104363;11944.044476442037;15494.922525387301;18476.445706628823;18482.260709572005;18672.439826805217;18903.338383157075;14925.402983880958;15169.848301517284;15186.544403667907;15256.923715381352;15268.312925918583;13384.330783460982;13495.39270732839;13576.1854288743;13747.725045499234;13938.094023888414;14317.140971541741;15364.692706145592;15739.291731872323;15744.448944286894;15853.52439426819;11739.88260117422;11824.994875435685;11843.70390740204;11976.58081967127;11985.22096063753;15016.924915375354;15041.247937603295;15065.322060507084;15066.246687665793;15232.543023709306 10:00:35 INFO - PID 1995 | |3;mootools.html;1321.6761893336134;1490.4261859184223;1492.9402823887044;1498.3257534109653;1501.429456806414;13095.934520327397;13503.729925401487;13641.863581364183;13651.112677675945;13900.235487048209;13148.605541833736;13385;13388.933055334734;13405.592412796663;13512.189268643888;2564.6665933428626;2744.0807329544627;2746.958795618057;2751.17464760571;2753.600727894454;2681.5173268811604;2708.2416923261517;2726.795490338223;2726.9501242021865;2733.043434797825;2685.9194224173307;2710;2723.9046633367834;2726.2230264374657;2735.9452810943767;2652.1778248742853;2674.7993900457445;2682.5841994490884;2686.1404350607813;2690.2736261209366;2545.669063021814;2635.117235726034;2671.719469455718;2709.7290270972744;2747.6428064351708;13297.468101275937;13395.531156409479;13421.328933553283;13425.865741342559;13429.261390623511;7339.706411743524;7560.9621951889885;7615.46691731579;7627.4279429043045;7642.197569255204;4615.492295847488;4711.4346278446465;4749.121412538657;4801.135795556798;4838.85483435494;1398.3078376203744;1422.1538184780143;1444.4005737618875;1444.5016469318068;1447.044950332786;19641.901790490956;19820.900895495575;19829.206831726857;19853.602927941505;19856.900715496475;6389.488840892717;6510.381513756186;6524.771632992823;6653.301403352578;6656.8668626627195;13993.860061399459;14070.648233794032;14077.788833167511;14085.929570352082;14131.081479703786;7048.682809273595;7179.389751871149;7180.820479488055;7200.207977122513;7220;5371.435999220025;5527.419620939824;6388.105665206875;6545.345465453416;6557.409833115043;4995.225740010304;5058.696478211319;5094.7962081516325;5095.643304968691;5140;1486.6283429142713;1491.8284397294233;1497.4160077569757;1507.6080219143103;1517.233796932542;827.2265431821266;829.9709510167236;856.7044369692446;860.8321377331486;889.2707979456912;6788.898166527506;6791.151106111736;7185.353318201387;7266.200717921024;7421.777346679608;628.5882746800761;630.6058713304185;705.0939542687622;707.1655446573093;709.2517394149119;4110.198511290329;4843.927341089887;4964;5356.330458692663;5440.455954404528;18195.545111372056;18222.63554728898;18552.44342669722;18595.907020464812;18620.627587448176;17856.9107154466;18243;18349.89900605968;18521.907390462962;18558.628827423378;9448.716538503855;9669.613215471443;9671.419714816993;9685.806283874283;9710.320277580502;7517.323440890346;7993.640286187019;8042.235987581171;8295.211954864279;8473.364497662576;16378.344866205218;16437.342506300094;16593.253303601126;16634.584135396228;16735.163241837618;16605.66788664244;16950.830491694924;17093.145342732565;17316;17346.392876249272;10603.992620701021;10857.022867941923;10948.288361256493;11015.72460688457;11033.337999720043;12292.938535307267;12352.814707779571;12534.56129035516;12546.498140074294;12549.811752823898;8703.260222881127;9832.459214743258;9890.307678462439;10396.792064158826;10448.791024179625;9675.274354423587;9817.410955342702;9962.252831037846;10086.596536138766;10183;8955.552222388724;8984.472639651405;9109.498977556297;9115.225205857576;9116;6677.332266773284;6711.530192886451;7024.613646249505;7048.682809273492;7206.243344448757;6755.324467553205;6756.459483241427;6930.203026652;6965.124512570989;7254.310840470147 10:00:35 INFO - PID 1995 | |4;prototype.html;432.6776551469156;454.7726136931534;460.6959406791517;463.80520181523775;480.35152544065517;5830;6104.938950610493;6558.475321974242;6673;6726.966365168179;5362.6514276572;5641.576881733876;6522.673866306677;6532.379423954717;6590.835229119262;4029.1135950090934;4852.490488498701;4874.122657921573;4887.755612219375;4909.607231421495;4827.61379089672;4833.3716616839765;4843.66094373394;4854.417469903617;4889.29105279736;4151.916961660765;4760.738159401231;4822.61419086474;4823.155947709154;4827.106985207748;4837.371141751567;4839.443464001614;4845;4846.418429788413;4864.7324397158145;3892.2410130024655;4237.512686041114;4237.745735255864;4250.808713607864;4262.957370426287;5232.241325007891;5882.23530940975;5899.0266606009955;6255.718492667841;6493;4811.278308253755;5020.7740651670765;5069.580517455119;5293.3647962244395;5344.278522399465;6450.774222902222;6639.634820084893;7029.156501219835;7050.5769653820425;7062.894056589155;4561.29299958507;4837.95162048382;4852.9757351213375;4858.514148585148;4860.100881336929;6459.128017717595;7039.472039597051;7218.025566548554;7391.704331826774;7469.290417410337;4104.42538044674;4702.247640377524;4874.19575770001;4927.260910863399;4934.679245849008;3794.9430758538642;4167.541570427251;5010.624203184776;5011.448740638527;5027.245913113063;5031.496850314939;5535.197396377503;5704.486596206361;5709.685967271756;5718.228039214736;6966.164060312693;6979.080752793023;7006.859862802716;7017.578945263199;7026.156861176692;1367.9863201368057;1516.8407317231752;1696.3892998520523;1717.8453939145286;1718.2525601363448;935.5976929920199;943.9811203775886;1135.5628083188033;1156.785375355873;1166.1720178673238;5856.443637854397;6487.318831522716;6490.221173459215;6499.675016249169;6548.934510654928;6063.696815159225;6904.275051119661;7185.425165986709;7203.459740519482;7225.747098851515;6212.409821066992;6469.967650161719;6503.6097834130105;6507.739690412331;6530.8040758777315;3670.4861319415304;3727.981360093182;3788.753731007476;3829.4830197922925;3834.750741201813;6446.290908000116;6964.512484126164;7421;7428.554286742705;7519.699212031457;6257.937420625826;7473.177950425557;7506.211847756016;7508.43686723487;7522.8871566925445;6475.579087359307;7063.57618542889;7357.668904899292;7437.3969110810785;7455.776326710207;7246.5289756165685;7261.49169558147;7270.002248145195;7275.272472752682;7294.416446684147;6504.15445992018;7075.610841403773;7105.2894710528535;7113.928860711534;7196.676149573386;6556.3443655634055;6607.471402287902;6721.714617030233;6788.422984046411;7097.893531596927;4281.828726851016;4443.977780111079;4799.3041009052795;4932.136876046606;4970.4840023793095;7096.53872498286;7229.060222171294;7252.782416527512;7311;7319.780406587811;6627.502937279627;6861.382475577222;7058.482426278375;7066.929330706627;7081.973113898559;6934.549254298455;6941.403477200171;6951.131108611424;6993.020977063317;6997.405220556309;5166.922496162636;6814.2163651180745;6863.117490653837;6867.175938887365;6868;6620.966895165493;6964.582125072512;6981.476389270723;6988;7014.333638304353;6731.600541726397;6746.679852360244;6761.6138691569095;6792;6809.399791049001 10:00:35 INFO - PID 1995 | |5;yui.html;778.7546922719343;808.7614153824628;808.9838203235931;837.9287760540356;846.8179341441589;14396.424143034292;14905.180215088165;16511.75232371514;17045.659086818257;17066.488005359828;15591.986520876135;15869.841301586981;16731;17459.82540174598;17684;964.8504481805314;965.6764983730459;986.4525188520369;997.3965750720819;1007.1439276614892;984.1683777208248;1010.7725761703631;1014.4065721552901;1015.1523477895965;1016.2885979814123;926.3145272498336;1032.3960483117385;1034.6740776655367;1044.5404022230232;1047.9528421221025;1012.8987101289849;1031.7111208861493;1042.8592140061107;1045.6497073480393;1047.7799662070975;770.9653065612005;787.7124849429974;788.5032429569392;789.9091604465445;790.1821614628889;13863.792043119362;16859.831401685948;16908.577285567837;17076.65846683072;17220.82779172217;17310.82689173105;17383.47849564515;17663.11684415787;17903.462896113007;17921.193546290448;14687.485937992118;14838.554843354717;14883.33025013877;14939.103653780809;14982;3589.94615080774;3594.11944073701;3607.332643460968;3632.9273414531826;3633.6911362534215;142852;144393.5560644401;146590;147439;148526.25736871245;13671.316434178252;13694.383752731152;13837.654058648613;13902.374393152333;13909.095908765898;26610.866945665533;26926.865365673046;27483.587746183824;27555.586666200023;27567;7318.487705860645;7703.8844417333785;7753.806154846061;7885.172056934054;7967.083785364642;3238.498032804872;3535.4873543336585;3811.7903515306925;3961.8811435656976;3962.088719594452;4532.410786597721;4591.609713174417;4649.790759415834;4650.162970665313;4681.6020638245445;948.7201275623707;981.3915372468972;982.4792859784326;984;987.8172538080478;587.512364737267;658.344946777959;675.2875716119443;677.366662170862;687.3985262894967;9888.159506442033;9955.50222488887;11608.071354291636;11873.940630296793;12606.55877044299;620.172070286164;639.0605809460086;643.7457204404234;665.8135721997849;667.8029981155571;5240.4497527759795;5642.463965923231;5780.4508571686465;5878.617889837233;5896.793612223637;135420;150475;151978;152809;154973;110167;110981;117786.41106794582;117966;122368;96189.5190524057;97700;99401.50299248457;101145;103365.96634033714;10167.949160254151;11833.940830295793;11974.341411222467;12091.81862272049;12718.554850580185;39273;44023.77988110039;44057.339139913565;46176;48152.555423337355;62609;63002.36997629965;63234.051489226775;63461.36538634555;63491.682541587;16760;16814.991100534007;17018.914905425394;17152.228149733543;17244;17066.48800535986;18477.630447390708;18727.344542940933;18857.057147142863;18946.810531894505;17159.828401715822;17781.644367112844;17836.375726850016;17880.821191787916;17965.281388744304;16984;17074.914625426794;17405.390811321544;17814.732779008584;17828.732569011736;16974.49076527706;17119.058451785273;17160.742588861427;17336.87310324825;17360;11179.217454778087;11531.538738450368;11533.308001519936;11942.582009629969;12348.259104453762;13001.804972925225;14142.22217777991;14228.14631122136;14656.706865862836;14658.853411465749 10:00:35 INFO - PID 1995 | __end_tp_report 10:00:35 INFO - PID 1995 | __start_cc_report 10:00:35 INFO - PID 1995 | _x_x_mozilla_cycle_collect,454 10:00:35 INFO - PID 1995 | __end_cc_report 10:00:35 INFO - PID 1995 | __startTimestamp1497114035882__endTimestamp 10:00:35 INFO - PID 1995 | 10:00:35 INFO - PID 1995 | ------- Summary: start ------- 10:00:35 INFO - PID 1995 | Number of tests: 6 10:00:35 INFO - PID 1995 | 10:00:35 INFO - PID 1995 | [#0] dojo.html Cycles:165 Average:6246.47 Median:6527.12 stddev:820.86 (12.6%) stddev-sans-first:779.42 10:00:35 INFO - PID 1995 | Values: 2868.2 3587.0 3630.1 3640.2 3648.1 4806.4 5356.3 5701.7 6242.2 6375.5 6257.5 6265.9 6300.6 6334.8 6355.8 4821.8 5244.5 5506.9 5550.2 5620.5 5232.8 5514.3 5660.5 5675.2 5689.8 5469.8 5510.6 5526.3 5603.7 5608.1 5555.1 5562.2 5564.1 5604.3 5653.0 5530.7 5538.6 5584.9 5588.2 5600.3 4728.4 6174.0 6298.7 6336.8 6341.8 6561.5 6675.3 6694.2 6696.6 6710.7 6968.9 6973.1 6975.5 6985.4 6987.8 6332.0 6402.4 6435.7 6442.0 6451.9 6994.3 7000.1 7083.9 7096.5 7134.6 6572.6 6643.6 6682.3 6685.2 6770.9 6853.9 6964.8 6969.7 6976.0 6980.2 5323.7 6836.6 6848.8 6859.0 6877.8 6658.1 6734.7 6784.4 6813.5 6857.0 5431.8 5481.2 5495.4 5496.3 5497.7 4824.4 4838.5 4843.9 4859.0 4884.4 5214.5 5277.1 6119.6 6299.3 6381.3 6990.3 6997.8 7007.4 7009.6 7032.2 6419.8 6449.0 6467.6 6468.0 6487.5 4776.9 5810.9 5902.6 6003.1 6074.0 7031.8 7088.8 7095.4 7118.9 7132.8 6939.6 6964.1 6981.8 7007.2 7022.0 6878.1 6954.8 7078.2 7082.3 7120.4 6713.4 6720.2 6820.7 6827.4 6866.4 6977.7 6981.2 6987.0 6988.3 7000.1 6581.8 6635.5 6648.7 6665.4 6698.1 6078.7 6415.6 6747.2 6749.9 6774.6 5561.9 6413.1 6603.3 6636.3 6641.2 6496.1 6601.7 6638.7 6756.9 6757.9 6501.7 6527.1 6574.0 6579.6 6601.5 10:00:35 INFO - PID 1995 | 10:00:35 INFO - PID 1995 | [#1] ext.html Cycles:180 Average:41640.65 Median:19682.15 stddev:68458.60 (347.8%) stddev-sans-first:68581.94 10:00:35 INFO - PID 1995 | Values: 790.2 820.8 841.4 1259.0 1317.3 37516.8 38840.6 38854.0 38997.2 39018.6 39270.4 39530.8 39545.4 39564.2 39633.0 7900.3 8056.3 8184.3 8407.0 8408.7 8355.5 8458.2 8467.8 8470.4 8478.2 8440.8 8494.1 8498.4 8504.5 8511.7 8444.5 8463.7 8476.9 8485.2 8518.9 8044.3 8160.3 8281.4 8362.7 8384.9 36801.9 36840.0 36878.0 37070.0 37126.3 3560.7 3596.3 3597.5 3600.3 3603.1 30384.7 30493.2 30502.8 30520.7 30572.1 3043.4 3049.6 3064.7 3096.6 3099.9 217811.0 218375.0 251835.0 286440.0 288850.6 47532.0 48025.8 48093.8 48094.3 48158.8 49328.0 49855.3 49905.0 51604.0 52860.0 14343.0 14437.4 14451.8 14459.0 14532.2 11450.1 11499.9 11519.6 11541.0 11547.0 14123.5 14168.9 14208.4 14213.9 14250.6 6262.2 6302.9 6307.7 6316.5 6320.4 955.5 991.6 992.8 1041.3 1042.7 19766.8 22783.0 22810.8 22988.3 23886.4 1021.3 1060.5 1086.6 1134.5 1136.4 9975.8 10083.8 10151.3 11686.5 12234.8 270169.0 278683.0 295859.0 298151.0 301582.0 45954.6 54870.7 54927.0 55570.2 55609.7 1472.6 1499.8 1503.6 1514.5 1524.6 15724.3 22423.3 22450.7 23517.6 23772.3 37386.0 37552.8 37580.1 37742.6 37823.4 239119.0 239527.0 239792.8 239950.0 240201.0 29997.3 36499.0 36637.6 36662.8 36746.0 44267.3 44290.3 45600.9 45818.0 45899.9 24880.3 28484.3 29109.3 29553.7 30477.0 24168.6 26448.9 27838.4 30253.4 31004.7 35768.0 36835.4 37029.0 37092.7 37175.4 27645.2 31162.0 31205.7 31928.5 32196.0 22117.7 22234.4 24272.2 24354.6 24792.9 10:00:35 INFO - PID 1995 | 10:00:35 INFO - PID 1995 | [#2] jquery.html Cycles:180 Average:38476.88 Median:23646.17 stddev:92309.21 (390.4%) stddev-sans-first:92524.01 10:00:35 INFO - PID 1995 | Values: 451.3 504.4 511.6 523.6 532.6 21648.2 22479.4 23418.8 23491.9 24872.0 24251.5 24772.5 24811.9 24820.8 24912.3 6427.7 6538.9 6553.5 6572.2 6581.0 6294.3 6460.7 6498.2 6522.6 6550.9 6308.0 6327.7 6371.9 6377.7 6378.5 6322.7 6357.2 6369.8 6374.2 6386.3 5807.9 5812.0 5817.6 5819.7 5826.5 29505.9 30309.2 30380.0 30499.2 30732.0 2104.9 2141.2 2141.9 2144.7 2147.2 19023.5 19353.9 19425.9 19572.5 19583.7 2181.4 2213.9 2250.6 2258.9 2260.4 164282.0 170522.0 193148.0 199443.0 203151.0 12803.0 14614.9 15012.5 15106.3 15644.6 20204.0 20363.8 20404.4 20414.1 20476.1 9798.8 9870.7 9911.5 9948.4 9973.7 9157.6 9166.1 9171.1 9176.7 9228.1 7437.2 9163.9 9202.4 9214.4 9250.3 2589.8 2596.4 2598.8 2612.1 2624.0 893.8 939.4 952.4 957.7 979.1 16890.5 17657.1 21470.5 21640.9 21783.0 962.9 974.6 1011.5 1013.5 1033.8 8358.5 9900.8 10906.6 11173.9 11422.3 522510.0 524329.0 526661.0 534926.0 537056.0 157135.0 158589.0 158700.0 158775.0 160177.0 993.7 1031.6 1054.6 1128.4 1173.7 22025.9 22543.5 23800.4 23814.2 23822.5 63729.7 64385.0 64490.0 64543.0 66381.3 88709.6 89015.0 89596.0 90081.1 90729.5 11530.2 11785.5 11882.8 11903.5 11944.0 15494.9 18476.4 18482.3 18672.4 18903.3 14925.4 15169.8 15186.5 15256.9 15268.3 13384.3 13495.4 13576.2 13747.7 13938.1 14317.1 15364.7 15739.3 15744.4 15853.5 11739.9 11825.0 11843.7 11976.6 11985.2 15016.9 15041.2 15065.3 15066.2 15232.5 10:00:35 INFO - PID 1995 | 10:00:35 INFO - PID 1995 | [#3] mootools.html Cycles:180 Average:8248.06 Median:2726.87 stddev:5507.21 (202.0%) stddev-sans-first:5498.07 10:00:35 INFO - PID 1995 | Values: 1321.7 1490.4 1492.9 1498.3 1501.4 13095.9 13503.7 13641.9 13651.1 13900.2 13148.6 13385.0 13388.9 13405.6 13512.2 2564.7 2744.1 2747.0 2751.2 2753.6 2681.5 2708.2 2726.8 2727.0 2733.0 2685.9 2710.0 2723.9 2726.2 2735.9 2652.2 2674.8 2682.6 2686.1 2690.3 2545.7 2635.1 2671.7 2709.7 2747.6 13297.5 13395.5 13421.3 13425.9 13429.3 7339.7 7561.0 7615.5 7627.4 7642.2 4615.5 4711.4 4749.1 4801.1 4838.9 1398.3 1422.2 1444.4 1444.5 1447.0 19641.9 19820.9 19829.2 19853.6 19856.9 6389.5 6510.4 6524.8 6653.3 6656.9 13993.9 14070.6 14077.8 14085.9 14131.1 7048.7 7179.4 7180.8 7200.2 7220.0 5371.4 5527.4 6388.1 6545.3 6557.4 4995.2 5058.7 5094.8 5095.6 5140.0 1486.6 1491.8 1497.4 1507.6 1517.2 827.2 830.0 856.7 860.8 889.3 6788.9 6791.2 7185.4 7266.2 7421.8 628.6 630.6 705.1 707.2 709.3 4110.2 4843.9 4964.0 5356.3 5440.5 18195.5 18222.6 18552.4 18595.9 18620.6 17856.9 18243.0 18349.9 18521.9 18558.6 9448.7 9669.6 9671.4 9685.8 9710.3 7517.3 7993.6 8042.2 8295.2 8473.4 16378.3 16437.3 16593.3 16634.6 16735.2 16605.7 16950.8 17093.1 17316.0 17346.4 10604.0 10857.0 10948.3 11015.7 11033.3 12292.9 12352.8 12534.6 12546.5 12549.8 8703.3 9832.5 9890.3 10396.8 10448.8 9675.3 9817.4 9962.3 10086.6 10183.0 8955.6 8984.5 9109.5 9115.2 9116.0 6677.3 6711.5 7024.6 7048.7 7206.2 6755.3 6756.5 6930.2 6965.1 7254.3 10:00:35 INFO - PID 1995 | 10:00:35 INFO - PID 1995 | [#4] prototype.html Cycles:180 Average:5630.34 Median:6481.45 stddev:1764.12 (27.2%) stddev-sans-first:1725.39 10:00:35 INFO - PID 1995 | Values: 432.7 454.8 460.7 463.8 480.4 5830.0 6104.9 6558.5 6673.0 6727.0 5362.7 5641.6 6522.7 6532.4 6590.8 4029.1 4852.5 4874.1 4887.8 4909.6 4827.6 4833.4 4843.7 4854.4 4889.3 4151.9 4760.7 4822.6 4823.2 4827.1 4837.4 4839.4 4845.0 4846.4 4864.7 3892.2 4237.5 4237.7 4250.8 4263.0 5232.2 5882.2 5899.0 6255.7 6493.0 4811.3 5020.8 5069.6 5293.4 5344.3 6450.8 6639.6 7029.2 7050.6 7062.9 4561.3 4838.0 4853.0 4858.5 4860.1 6459.1 7039.5 7218.0 7391.7 7469.3 4104.4 4702.2 4874.2 4927.3 4934.7 3794.9 4167.5 5010.6 5011.4 5027.2 5031.5 5535.2 5704.5 5709.7 5718.2 6966.2 6979.1 7006.9 7017.6 7026.2 1368.0 1516.8 1696.4 1717.8 1718.3 935.6 944.0 1135.6 1156.8 1166.2 5856.4 6487.3 6490.2 6499.7 6548.9 6063.7 6904.3 7185.4 7203.5 7225.7 6212.4 6470.0 6503.6 6507.7 6530.8 3670.5 3728.0 3788.8 3829.5 3834.8 6446.3 6964.5 7421.0 7428.6 7519.7 6257.9 7473.2 7506.2 7508.4 7522.9 6475.6 7063.6 7357.7 7437.4 7455.8 7246.5 7261.5 7270.0 7275.3 7294.4 6504.2 7075.6 7105.3 7113.9 7196.7 6556.3 6607.5 6721.7 6788.4 7097.9 4281.8 4444.0 4799.3 4932.1 4970.5 7096.5 7229.1 7252.8 7311.0 7319.8 6627.5 6861.4 7058.5 7066.9 7082.0 6934.5 6941.4 6951.1 6993.0 6997.4 5166.9 6814.2 6863.1 6867.2 6868.0 6621.0 6964.6 6981.5 6988.0 7014.3 6731.6 6746.7 6761.6 6792.0 6809.4 10:00:35 INFO - PID 1995 | 10:00:35 INFO - PID 1995 | [#5] yui.html Cycles:180 Average:25288.59 Median:17673.56 stddev:39003.99 (220.7%) stddev-sans-first:39069.99 10:00:35 INFO - PID 1995 | Values: 778.8 808.8 809.0 837.9 846.8 14396.4 14905.2 16511.8 17045.7 17066.5 15592.0 15869.8 16731.0 17459.8 17684.0 964.9 965.7 986.5 997.4 1007.1 984.2 1010.8 1014.4 1015.2 1016.3 926.3 1032.4 1034.7 1044.5 1048.0 1012.9 1031.7 1042.9 1045.6 1047.8 771.0 787.7 788.5 789.9 790.2 13863.8 16859.8 16908.6 17076.7 17220.8 17310.8 17383.5 17663.1 17903.5 17921.2 14687.5 14838.6 14883.3 14939.1 14982.0 3589.9 3594.1 3607.3 3632.9 3633.7 142852.0 144393.6 146590.0 147439.0 148526.3 13671.3 13694.4 13837.7 13902.4 13909.1 26610.9 26926.9 27483.6 27555.6 27567.0 7318.5 7703.9 7753.8 7885.2 7967.1 3238.5 3535.5 3811.8 3961.9 3962.1 4532.4 4591.6 4649.8 4650.2 4681.6 948.7 981.4 982.5 984.0 987.8 587.5 658.3 675.3 677.4 687.4 9888.2 9955.5 11608.1 11873.9 12606.6 620.2 639.1 643.7 665.8 667.8 5240.4 5642.5 5780.5 5878.6 5896.8 135420.0 150475.0 151978.0 152809.0 154973.0 110167.0 110981.0 117786.4 117966.0 122368.0 96189.5 97700.0 99401.5 101145.0 103366.0 10167.9 11833.9 11974.3 12091.8 12718.6 39273.0 44023.8 44057.3 46176.0 48152.6 62609.0 63002.4 63234.1 63461.4 63491.7 16760.0 16815.0 17018.9 17152.2 17244.0 17066.5 18477.6 18727.3 18857.1 18946.8 17159.8 17781.6 17836.4 17880.8 17965.3 16984.0 17074.9 17405.4 17814.7 17828.7 16974.5 17119.1 17160.7 17336.9 17360.0 11179.2 11531.5 11533.3 11942.6 12348.3 13001.8 14142.2 14228.1 14656.7 14658.9 10:00:35 INFO - PID 1995 | -------- Summary: end -------- 10:00:35 INFO - PID 1995 | 10:00:36 INFO - TEST-INFO | 1995: exit 0 10:00:36 INFO - TEST-OK | dromaeo_css | took 1143490ms 10:00:36 INFO - TEST-START | kraken 10:00:36 INFO - operating with platform_type : mac_ 10:00:36 INFO - Initialising browser for kraken test... 10:00:36 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49283/getInfo.html -foreground -profile /var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/tmpSp0w5T/profile 10:00:36 INFO - TEST-INFO | started process 2005 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49283/getInfo.html -foreground) 10:01:10 INFO - TEST-INFO | 2005: killed by SIGTERM 10:01:10 INFO - Browser initialized. 10:01:10 INFO - Running cycle 1/1 for kraken test... 10:01:10 INFO - TEST-INFO | started process 2009 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/tmpSp0w5T/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:01:12 INFO - PID 2009 | 2017-06-10 10:01:12.443 plugin-container[2010:286009] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x994b, name = 'com.apple.tsm.portname' 10:01:12 INFO - PID 2009 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:01:12 INFO - PID 2009 | 2017-06-10 10:01:12.443 plugin-container[2010:286009] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0f, name = 'com.apple.CFPasteboardClient' 10:01:12 INFO - PID 2009 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:01:12 INFO - PID 2009 | 2017-06-10 10:01:12.443 plugin-container[2010:286009] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:01:13 INFO - PID 2009 | 2017-06-10 10:01:13.001 plugin-container[2011:286055] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 10:01:13 INFO - PID 2009 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:01:13 INFO - PID 2009 | 2017-06-10 10:01:13.001 plugin-container[2011:286055] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 10:01:13 INFO - PID 2009 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:01:13 INFO - PID 2009 | 2017-06-10 10:01:13.001 plugin-container[2011:286055] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:01:22 INFO - PID 2009 | RSS: Main: 254939136 10:01:22 INFO - PID 2009 | 10:01:25 INFO - PID 2009 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-beat-detection) 10:01:26 INFO - PID 2009 | RSS: Main: 267177984 10:01:26 INFO - PID 2009 | 10:01:28 INFO - PID 2009 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-dft) 10:01:29 INFO - PID 2009 | RSS: Main: 276119552 10:01:29 INFO - PID 2009 | 10:01:32 INFO - PID 2009 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-fft) 10:01:33 INFO - PID 2009 | RSS: Main: 278421504 10:01:33 INFO - PID 2009 | 10:01:35 INFO - PID 2009 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-oscillator) 10:01:36 INFO - PID 2009 | RSS: Main: 279252992 10:01:36 INFO - PID 2009 | 10:01:38 INFO - PID 2009 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:01:38 INFO - PID 2009 | RSS: Main: 281878528 10:01:38 INFO - PID 2009 | 10:01:41 INFO - PID 2009 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-darkroom) 10:01:42 INFO - PID 2009 | RSS: Main: 286253056 10:01:42 INFO - PID 2009 | 10:01:45 INFO - PID 2009 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-desaturate) 10:01:45 INFO - PID 2009 | RSS: Main: 288485376 10:01:45 INFO - PID 2009 | 10:01:48 INFO - PID 2009 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49283/tests/kraken/driver.html?testName=json-parse-financial) 10:01:49 INFO - PID 2009 | RSS: Main: 287653888 10:01:49 INFO - PID 2009 | 10:01:51 INFO - PID 2009 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49283/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:01:51 INFO - PID 2009 | RSS: Main: 287195136 10:01:51 INFO - PID 2009 | 10:01:53 INFO - PID 2009 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:01:54 INFO - PID 2009 | RSS: Main: 287072256 10:01:54 INFO - PID 2009 | 10:01:56 INFO - PID 2009 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:01:57 INFO - PID 2009 | RSS: Main: 286773248 10:01:57 INFO - PID 2009 | 10:01:59 INFO - PID 2009 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:02:00 INFO - PID 2009 | RSS: Main: 286810112 10:02:00 INFO - PID 2009 | 10:02:03 INFO - PID 2009 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:02:04 INFO - PID 2009 | RSS: Main: 286568448 10:02:04 INFO - PID 2009 | 10:02:06 INFO - PID 2009 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49283/tests/kraken/driver.html?testName=ai-astar) 10:02:06 INFO - PID 2009 | RSS: Main: 287932416 10:02:06 INFO - PID 2009 | 10:02:06 INFO - PID 2009 | __start_tp_report 10:02:06 INFO - PID 2009 | _x_x_mozilla_page_load 10:02:06 INFO - PID 2009 | _x_x_mozilla_page_load_details 10:02:06 INFO - PID 2009 | |i|pagename|runs| 10:02:06 INFO - PID 2009 | |0;ai-astar;112;117;97;150;174;151;116;114;114;116 10:02:06 INFO - PID 2009 | |1;audio-beat-detection;131;112;138;188;127;129;117;156;147;113 10:02:06 INFO - PID 2009 | |2;audio-dft;203;200;198;220;219;266;173;239;266;206 10:02:06 INFO - PID 2009 | |3;audio-fft;70;82;74;150;59;58;59;64;121;55 10:02:06 INFO - PID 2009 | |4;audio-oscillator;69;72;110;71;71;83;114;107;101;113 10:02:06 INFO - PID 2009 | |5;imaging-gaussian-blur;88;88;88;88;88;88;88;87;89;88 10:02:06 INFO - PID 2009 | |6;imaging-darkroom;96;96;96;95;99;96;95;95;99;95 10:02:06 INFO - PID 2009 | |7;imaging-desaturate;150;80;143;153;91;88;91;92;94;91 10:02:06 INFO - PID 2009 | |8;json-parse-financial;50;51;55;57;67;54;52;52;53;52 10:02:06 INFO - PID 2009 | |9;json-stringify-tinderbox;55;65;70;59;56;60;59;57;57;56 10:02:06 INFO - PID 2009 | |10;stanford-crypto-aes;85;77;88;106;106;108;87;90;110;84 10:02:06 INFO - PID 2009 | |11;stanford-crypto-ccm;134;156;155;151;134;134;135;134;149;136 10:02:06 INFO - PID 2009 | |12;stanford-crypto-pbkdf2;178;181;188;155;179;228;161;200;229;235 10:02:06 INFO - PID 2009 | |13;stanford-crypto-sha256-iterative;67;73;88;64;67;93;98;95;90;66 10:02:06 INFO - PID 2009 | __end_tp_report 10:02:06 INFO - PID 2009 | __start_cc_report 10:02:06 INFO - PID 2009 | _x_x_mozilla_cycle_collect,1162 10:02:06 INFO - PID 2009 | __end_cc_report 10:02:06 INFO - PID 2009 | __startTimestamp1497114126428__endTimestamp 10:02:06 INFO - PID 2009 | 10:02:06 INFO - PID 2009 | ------- Summary: start ------- 10:02:06 INFO - PID 2009 | Number of tests: 14 10:02:06 INFO - PID 2009 | 10:02:06 INFO - PID 2009 | [#0] ai-astar Cycles:10 Average:126.10 Median:133.50 stddev:23.83 (17.9%) stddev-sans-first:24.72 10:02:06 INFO - PID 2009 | Values: 112.0 117.0 97.0 150.0 174.0 151.0 116.0 114.0 114.0 116.0 10:02:06 INFO - PID 2009 | 10:02:06 INFO - PID 2009 | [#1] audio-beat-detection Cycles:10 Average:135.80 Median:134.50 stddev:23.21 (17.3%) stddev-sans-first:24.56 10:02:06 INFO - PID 2009 | Values: 131.0 112.0 138.0 188.0 127.0 129.0 117.0 156.0 147.0 113.0 10:02:06 INFO - PID 2009 | 10:02:06 INFO - PID 2009 | [#2] audio-dft Cycles:10 Average:219.00 Median:219.50 stddev:30.11 (13.7%) stddev-sans-first:31.38 10:02:06 INFO - PID 2009 | Values: 203.0 200.0 198.0 220.0 219.0 266.0 173.0 239.0 266.0 206.0 10:02:06 INFO - PID 2009 | 10:02:06 INFO - PID 2009 | [#3] audio-fft Cycles:10 Average:79.20 Median:61.50 stddev:31.56 (51.3%) stddev-sans-first:33.29 10:02:06 INFO - PID 2009 | Values: 70.0 82.0 74.0 150.0 59.0 58.0 59.0 64.0 121.0 55.0 10:02:06 INFO - PID 2009 | 10:02:06 INFO - PID 2009 | [#4] audio-oscillator Cycles:10 Average:91.10 Median:70.00 stddev:19.55 (27.9%) stddev-sans-first:19.03 10:02:06 INFO - PID 2009 | Values: 69.0 72.0 110.0 71.0 71.0 83.0 114.0 107.0 101.0 113.0 10:02:06 INFO - PID 2009 | 10:02:06 INFO - PID 2009 | [#5] imaging-gaussian-blur Cycles:10 Average:88.00 Median:88.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 10:02:06 INFO - PID 2009 | Values: 88.0 88.0 88.0 88.0 88.0 88.0 88.0 87.0 89.0 88.0 10:02:06 INFO - PID 2009 | 10:02:06 INFO - PID 2009 | [#6] imaging-darkroom Cycles:10 Average:96.20 Median:96.00 stddev:1.55 (1.6%) stddev-sans-first:1.64 10:02:06 INFO - PID 2009 | Values: 96.0 96.0 96.0 95.0 99.0 96.0 95.0 95.0 99.0 95.0 10:02:06 INFO - PID 2009 | 10:02:06 INFO - PID 2009 | [#7] imaging-desaturate Cycles:10 Average:107.30 Median:91.00 stddev:28.89 (31.7%) stddev-sans-first:26.19 10:02:06 INFO - PID 2009 | Values: 150.0 80.0 143.0 153.0 91.0 88.0 91.0 92.0 94.0 91.0 10:02:06 INFO - PID 2009 | 10:02:06 INFO - PID 2009 | [#8] json-parse-financial Cycles:10 Average:54.30 Median:53.50 stddev:4.90 (9.2%) stddev-sans-first:4.94 10:02:06 INFO - PID 2009 | Values: 50.0 51.0 55.0 57.0 67.0 54.0 52.0 52.0 53.0 52.0 10:02:06 INFO - PID 2009 | 10:02:06 INFO - PID 2009 | [#9] json-stringify-tinderbox Cycles:10 Average:59.40 Median:59.00 stddev:4.70 (8.0%) stddev-sans-first:4.70 10:02:06 INFO - PID 2009 | Values: 55.0 65.0 70.0 59.0 56.0 60.0 59.0 57.0 57.0 56.0 10:02:06 INFO - PID 2009 | 10:02:06 INFO - PID 2009 | [#10] stanford-crypto-aes Cycles:10 Average:94.10 Median:84.50 stddev:12.07 (14.3%) stddev-sans-first:12.34 10:02:06 INFO - PID 2009 | Values: 85.0 77.0 88.0 106.0 106.0 108.0 87.0 90.0 110.0 84.0 10:02:06 INFO - PID 2009 | 10:02:06 INFO - PID 2009 | [#11] stanford-crypto-ccm Cycles:10 Average:141.80 Median:142.50 stddev:9.64 (6.8%) stddev-sans-first:9.80 10:02:06 INFO - PID 2009 | Values: 134.0 156.0 155.0 151.0 134.0 134.0 135.0 134.0 149.0 136.0 10:02:06 INFO - PID 2009 | 10:02:06 INFO - PID 2009 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.40 Median:194.00 stddev:28.66 (14.8%) stddev-sans-first:29.85 10:02:06 INFO - PID 2009 | Values: 178.0 181.0 188.0 155.0 179.0 228.0 161.0 200.0 229.0 235.0 10:02:06 INFO - PID 2009 | 10:02:06 INFO - PID 2009 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:80.10 Median:89.00 stddev:13.83 (15.5%) stddev-sans-first:13.83 10:02:06 INFO - PID 2009 | Values: 67.0 73.0 88.0 64.0 67.0 93.0 98.0 95.0 90.0 66.0 10:02:06 INFO - PID 2009 | -------- Summary: end -------- 10:02:06 INFO - PID 2009 | 10:02:07 INFO - TEST-INFO | 2009: exit 0 10:02:07 INFO - TEST-OK | kraken | took 90830ms 10:02:07 INFO - SUITE-END | took 1234s 10:02:07 INFO - Completed test suite (00:20:34) 10:02:07 INFO - javascript benchmark 10:02:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8332.414139860042, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2868.211241908475, 3587.0494319005466, 3630.1287690954164, 3640.1991561856385, 3648.1426864686796, 4806.399200099988, 5356.330458692663, 5701.657900525971, 6242.157308763316, 6375.5218358623115, 6257.468115210201, 6265.906011409821, 6300.55896087273, 6334.841628959279, 6355.8411039723915, 4821.807127714887, 5244.531531171278, 5506.862328441781, 5550.222968784364, 5620.494155526002, 5232.822614911653, 5514.283143191379, 5660.5471562274915, 5675.177099320596, 5689.800856970007, 5469.835904922858, 5510.559155267569, 5526.336839579276, 5603.663780173202, 5608.130739735348, 5555.083411237141, 5562.2212890195, 5564.081926482126, 5604.299462567179, 5653.0, 5530.695811730353, 5538.556915446755, 5584.916226256569, 5588.161775733632, 5600.327960644708, 4728.40894888139, 6173.950428427178, 6298.716557754912, 6336.778212762531, 6341.778037768656, 6561.540692151551, 6675.332466753286, 6694.163229596301, 6696.5647232929705, 6710.7315707371645, 6968.930310696929, 6973.05863708403, 6975.546589471668, 6985.406240469566, 6987.755428560027, 6332.018537126661, 6402.356818295264, 6435.742570297136, 6441.967790161066, 6451.903221451682, 6994.33553812387, 7000.124984376953, 7083.92916070843, 7096.50324477292, 7134.607596582134, 6572.572782769104, 6643.60138391698, 6682.298358672367, 6685.197776266879, 6770.932290677129, 6853.931460685429, 6964.825879353057, 6969.686364113627, 6976.023356730062, 6980.197277313073, 5323.653962492425, 6836.623985680835, 6848.794536163923, 6858.971154326891, 6877.828054298582, 6658.1344425224415, 6734.730610775612, 6784.3894049535775, 6813.4889883257965, 6856.965715171392, 5431.755570999315, 5481.177823326533, 5495.368032676213, 5496.340439147248, 5497.725113744297, 4824.372831531879, 4838.491958344393, 4843.927341089887, 4858.975705121523, 4884.438289596671, 5214.478552144831, 5277.102892508283, 6119.602225855394, 6299.275583307886, 6381.266154392211, 6990.335918087773, 6997.755078572225, 7007.4394048476, 7009.649517524103, 7032.156141263081, 6419.807405777834, 6448.967755161194, 6467.611943283418, 6468.0, 6487.481001519867, 4776.880577985508, 5810.94189058104, 5902.61632993854, 6003.099535069775, 6074.0281554951, 7031.824204394931, 7088.751893683696, 7095.3968912643, 7118.857622847618, 7132.786016419515, 6939.618320992394, 6964.094667693167, 6981.847995080755, 7007.194172670208, 7022.016917631434, 6878.105846239957, 6954.826129346605, 7078.1506219254015, 7082.256363082009, 7120.394766444911, 6713.362230588086, 6720.227173874871, 6820.658967051529, 6827.385535301847, 6866.416354609714, 6977.720891164499, 6981.197162326194, 6987.02181694553, 6988.266232045562, 7000.054992575937, 6581.769638062645, 6635.535512514078, 6648.667566621746, 6665.433438157617, 6698.129243198548, 6078.696065196811, 6415.550911436155, 6747.156605424322, 6749.920012797928, 6774.593524388552, 5561.916571251515, 6413.134226879311, 6603.339666033358, 6636.303188165173, 6641.236257830411, 6496.090547323473, 6601.669916504252, 6638.701258443478, 6756.932430675631, 6757.932420675927, 6501.707423166064, 6527.11883895687, 6574.013897915351, 6579.572327798678, 6601.471882249504], "value": 6189.194353093913, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [790.1663744749291, 820.7619790260825, 841.4488510025933, 1258.9559365422206, 1317.3215793866154, 37516.812415937915, 38840.61159388405, 38854.028649283784, 38997.22005559887, 39018.60981390185, 39270.410943835865, 39530.802345988304, 39545.40681889774, 39564.21961011754, 39633.0, 7900.32847207988, 8056.274935255826, 8184.345252379795, 8407.0, 8408.747737567883, 8355.498670079814, 8458.154184581554, 8467.788305292355, 8470.44942078766, 8478.152184781504, 8440.78898027551, 8494.065652778221, 8498.405111642156, 8504.532250726208, 8511.74464766058, 8444.535550544717, 8463.66145354185, 8476.915230847675, 8485.193906578865, 8518.914810851875, 8044.316233120192, 8160.30637395822, 8281.37889658278, 8362.707305244348, 8384.916150838475, 36801.89594312175, 36840.0, 36878.0, 37070.0, 37126.25747485062, 3560.6795388414907, 3596.2987217492337, 3597.478365636968, 3600.261946301002, 3603.0812142903656, 30384.696153038407, 30493.237669058228, 30502.84748576265, 30520.694793052007, 30572.08283751491, 3043.3913217356394, 3049.6340439147443, 3064.6935306469177, 3096.612923384577, 3099.876004959799, 217811.0, 218375.0, 251835.0, 286440.0, 288850.55574722204, 47532.0, 48025.75987120042, 48093.75953120212, 48094.27858582124, 48158.759206203744, 49328.0, 49855.252171217464, 49905.0, 51604.0, 52860.0, 14343.0, 14437.35031923545, 14451.78322325166, 14459.0, 14532.200728959797, 11450.141239407078, 11499.885001150049, 11519.596814111466, 11540.961313481826, 11546.960773530254, 14123.505677301246, 14168.85831141696, 14208.431662733581, 14213.928930355281, 14250.572482825532, 6262.1546091278005, 6302.905456418157, 6307.653079080695, 6316.526260530479, 6320.399562041599, 955.4744890310302, 991.5686676295834, 992.7964767222702, 1041.2815157541272, 1042.6559235452282, 19766.802331976498, 22783.0, 22810.7718922812, 22988.310350689506, 23886.402839928793, 1021.2902033087082, 1060.522764755863, 1086.559943222996, 1134.5348407153028, 1136.4261048170786, 9975.793664600618, 10083.798324033478, 10151.289409741246, 11686.532538698528, 12234.792100823694, 270169.0, 278683.0, 295859.0, 298151.0, 301582.0, 45954.62136135921, 54870.72564637231, 54927.0, 55570.16644750332, 55609.72195138998, 1472.6244807574215, 1499.7600383938736, 1503.639126609606, 1514.5153550863838, 1524.618845288678, 15724.29240684149, 22423.32730018102, 22450.663240051086, 23517.64723529114, 23772.286831395086, 37386.0, 37552.81223593865, 37580.06049848776, 37742.62257377391, 37823.43264851085, 239119.0, 239527.0, 239792.8010359937, 239950.0, 240201.0, 29997.250068747584, 36499.0, 36637.63362366342, 36662.8166859164, 36746.0, 44267.33598996082, 44290.335644966, 45600.85997849947, 45818.0, 45899.852503687674, 24880.25359239226, 28484.287892802018, 29109.272268193465, 29553.704462955095, 30477.0, 24168.63747043831, 26448.8677556611, 27838.443231134857, 30253.394932100793, 31004.68995310018, 35768.0, 36835.447468287464, 37029.0, 37092.70175543843, 37175.442368363954, 27645.235476453, 31162.0, 31205.687943121186, 31928.5210721844, 32196.0, 22117.668234976165, 22234.444138896655, 24272.1504747333, 24354.634680479452, 24792.876035619705], "value": 17185.430544119572, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [451.28921947931997, 504.4425909370146, 511.6086194061542, 523.552362729866, 532.5579768791903, 21648.2423115191, 22479.438014049658, 23418.765812341873, 23491.942862571184, 24872.0, 24251.514969700595, 24772.50454990899, 24811.883465244064, 24820.758962051914, 24912.252632421012, 6427.710753016114, 6538.8692226155445, 6553.54125211233, 6572.244191917943, 6580.967095164518, 6294.307626161119, 6460.672753531935, 6498.155239818817, 6522.641254730988, 6550.934490655104, 6308.0, 6327.715252813611, 6371.904421433682, 6377.744890204386, 6378.4578310843635, 6322.652254126021, 6357.205349331333, 6369.808905732789, 6374.235091788969, 6386.265579458375, 5807.941920580824, 5812.0, 5817.592768506206, 5819.650820950714, 5826.504747096502, 29505.852470737722, 30309.24226894323, 30380.0, 30499.23751906198, 30732.0, 2104.873707577535, 2141.1863491872987, 2141.9464513387134, 2144.699742036101, 2147.1840700533853, 19023.524411889674, 19353.9032304839, 19425.902870485555, 19572.510687232792, 19583.706244406345, 2181.4001149683804, 2213.900374483152, 2250.628646273363, 2258.8531745436494, 2260.4461906832767, 164282.0, 170522.0, 193148.0, 199443.0027849866, 203150.9842450793, 12803.0, 14614.926925365306, 15012.47456339023, 15106.320215590325, 15644.608884777972, 20204.0, 20363.79636203619, 20404.387868363974, 20414.08136633855, 20476.078576464093, 9798.804023919482, 9870.654527091518, 9911.504424778876, 9948.40309581413, 9973.700788976343, 9157.633694652273, 9166.083391660914, 9171.082891710776, 9176.72469825893, 9228.123328283802, 7437.219091995263, 9163.908360916439, 9202.447853128833, 9214.401063930824, 9250.259979201648, 2589.805764567665, 2596.350912271932, 2598.8180827341903, 2612.137994461823, 2624.0, 893.8257039877292, 939.4222553129775, 952.3904700991371, 957.712686194139, 979.0747743382577, 16890.493285201464, 17657.117144142743, 21470.463238419165, 21640.89894803929, 21783.019764110653, 962.855571664256, 974.6296407365156, 1011.4639241202175, 1013.4578000769553, 1033.7777377863795, 8358.498490090615, 9900.752481188028, 10906.618268360728, 11173.944130279297, 11422.31466112036, 522510.0, 524329.0, 526661.0, 534926.0, 537056.0, 157135.0, 158589.0, 158700.0, 158775.0, 160177.0, 993.7267251505893, 1031.566741968375, 1054.625607909181, 1128.3737525673166, 1173.730042090324, 22025.898705065003, 22543.549129017654, 23800.4049898747, 23814.16650417227, 23822.523549529258, 63729.72540549256, 64385.0, 64490.0, 64543.0, 66381.33618663752, 88709.55645221732, 89015.0, 89596.0, 90081.0991890099, 90729.54635226782, 11530.192886497865, 11785.469653865768, 11882.82175767347, 11903.464344104363, 11944.044476442037, 15494.922525387301, 18476.445706628823, 18482.260709572005, 18672.439826805217, 18903.338383157075, 14925.402983880958, 15169.848301517284, 15186.544403667907, 15256.923715381352, 15268.312925918583, 13384.330783460982, 13495.39270732839, 13576.1854288743, 13747.725045499234, 13938.094023888414, 14317.140971541741, 15364.692706145592, 15739.291731872323, 15744.448944286894, 15853.52439426819, 11739.88260117422, 11824.994875435685, 11843.70390740204, 11976.58081967127, 11985.22096063753, 15016.924915375354, 15041.247937603295, 15065.322060507084, 15066.246687665793, 15232.543023709306], "value": 11681.804736584445, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1321.6761893336134, 1490.4261859184223, 1492.9402823887044, 1498.3257534109653, 1501.429456806414, 13095.934520327397, 13503.729925401487, 13641.863581364183, 13651.112677675945, 13900.235487048209, 13148.605541833736, 13385.0, 13388.933055334734, 13405.592412796663, 13512.189268643888, 2564.6665933428626, 2744.0807329544627, 2746.958795618057, 2751.17464760571, 2753.600727894454, 2681.5173268811604, 2708.2416923261517, 2726.795490338223, 2726.9501242021865, 2733.043434797825, 2685.9194224173307, 2710.0, 2723.9046633367834, 2726.2230264374657, 2735.9452810943767, 2652.1778248742853, 2674.7993900457445, 2682.5841994490884, 2686.1404350607813, 2690.2736261209366, 2545.669063021814, 2635.117235726034, 2671.719469455718, 2709.7290270972744, 2747.6428064351708, 13297.468101275937, 13395.531156409479, 13421.328933553283, 13425.865741342559, 13429.261390623511, 7339.706411743524, 7560.9621951889885, 7615.46691731579, 7627.4279429043045, 7642.197569255204, 4615.492295847488, 4711.4346278446465, 4749.121412538657, 4801.135795556798, 4838.85483435494, 1398.3078376203744, 1422.1538184780143, 1444.4005737618875, 1444.5016469318068, 1447.044950332786, 19641.901790490956, 19820.900895495575, 19829.206831726857, 19853.602927941505, 19856.900715496475, 6389.488840892717, 6510.381513756186, 6524.771632992823, 6653.301403352578, 6656.8668626627195, 13993.860061399459, 14070.648233794032, 14077.788833167511, 14085.929570352082, 14131.081479703786, 7048.682809273595, 7179.389751871149, 7180.820479488055, 7200.207977122513, 7220.0, 5371.435999220025, 5527.419620939824, 6388.105665206875, 6545.345465453416, 6557.409833115043, 4995.225740010304, 5058.696478211319, 5094.7962081516325, 5095.643304968691, 5140.0, 1486.6283429142713, 1491.8284397294233, 1497.4160077569757, 1507.6080219143103, 1517.233796932542, 827.2265431821266, 829.9709510167236, 856.7044369692446, 860.8321377331486, 889.2707979456912, 6788.898166527506, 6791.151106111736, 7185.353318201387, 7266.200717921024, 7421.777346679608, 628.5882746800761, 630.6058713304185, 705.0939542687622, 707.1655446573093, 709.2517394149119, 4110.198511290329, 4843.927341089887, 4964.0, 5356.330458692663, 5440.455954404528, 18195.545111372056, 18222.63554728898, 18552.44342669722, 18595.907020464812, 18620.627587448176, 17856.9107154466, 18243.0, 18349.89900605968, 18521.907390462962, 18558.628827423378, 9448.716538503855, 9669.613215471443, 9671.419714816993, 9685.806283874283, 9710.320277580502, 7517.323440890346, 7993.640286187019, 8042.235987581171, 8295.211954864279, 8473.364497662576, 16378.344866205218, 16437.342506300094, 16593.253303601126, 16634.584135396228, 16735.163241837618, 16605.66788664244, 16950.830491694924, 17093.145342732565, 17316.0, 17346.392876249272, 10603.992620701021, 10857.022867941923, 10948.288361256493, 11015.72460688457, 11033.337999720043, 12292.938535307267, 12352.814707779571, 12534.56129035516, 12546.498140074294, 12549.811752823898, 8703.260222881127, 9832.459214743258, 9890.307678462439, 10396.792064158826, 10448.791024179625, 9675.274354423587, 9817.410955342702, 9962.252831037846, 10086.596536138766, 10183.0, 8955.552222388724, 8984.472639651405, 9109.498977556297, 9115.225205857576, 9116.0, 6677.332266773284, 6711.530192886451, 7024.613646249505, 7048.682809273492, 7206.243344448757, 6755.324467553205, 6756.459483241427, 6930.203026652, 6965.124512570989, 7254.310840470147], "value": 6046.890723646317, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [432.6776551469156, 454.7726136931534, 460.6959406791517, 463.80520181523775, 480.35152544065517, 5830.0, 6104.938950610493, 6558.475321974242, 6673.0, 6726.966365168179, 5362.6514276572, 5641.576881733876, 6522.673866306677, 6532.379423954717, 6590.835229119262, 4029.1135950090934, 4852.490488498701, 4874.122657921573, 4887.755612219375, 4909.607231421495, 4827.61379089672, 4833.3716616839765, 4843.66094373394, 4854.417469903617, 4889.29105279736, 4151.916961660765, 4760.738159401231, 4822.61419086474, 4823.155947709154, 4827.106985207748, 4837.371141751567, 4839.443464001614, 4845.0, 4846.418429788413, 4864.7324397158145, 3892.2410130024655, 4237.512686041114, 4237.745735255864, 4250.808713607864, 4262.957370426287, 5232.241325007891, 5882.23530940975, 5899.0266606009955, 6255.718492667841, 6493.0, 4811.278308253755, 5020.7740651670765, 5069.580517455119, 5293.3647962244395, 5344.278522399465, 6450.774222902222, 6639.634820084893, 7029.156501219835, 7050.5769653820425, 7062.894056589155, 4561.29299958507, 4837.95162048382, 4852.9757351213375, 4858.514148585148, 4860.100881336929, 6459.128017717595, 7039.472039597051, 7218.025566548554, 7391.704331826774, 7469.290417410337, 4104.42538044674, 4702.247640377524, 4874.19575770001, 4927.260910863399, 4934.679245849008, 3794.9430758538642, 4167.541570427251, 5010.624203184776, 5011.448740638527, 5027.245913113063, 5031.496850314939, 5535.197396377503, 5704.486596206361, 5709.685967271756, 5718.228039214736, 6966.164060312693, 6979.080752793023, 7006.859862802716, 7017.578945263199, 7026.156861176692, 1367.9863201368057, 1516.8407317231752, 1696.3892998520523, 1717.8453939145286, 1718.2525601363448, 935.5976929920199, 943.9811203775886, 1135.5628083188033, 1156.785375355873, 1166.1720178673238, 5856.443637854397, 6487.318831522716, 6490.221173459215, 6499.675016249169, 6548.934510654928, 6063.696815159225, 6904.275051119661, 7185.425165986709, 7203.459740519482, 7225.747098851515, 6212.409821066992, 6469.967650161719, 6503.6097834130105, 6507.739690412331, 6530.8040758777315, 3670.4861319415304, 3727.981360093182, 3788.753731007476, 3829.4830197922925, 3834.750741201813, 6446.290908000116, 6964.512484126164, 7421.0, 7428.554286742705, 7519.699212031457, 6257.937420625826, 7473.177950425557, 7506.211847756016, 7508.43686723487, 7522.8871566925445, 6475.579087359307, 7063.57618542889, 7357.668904899292, 7437.3969110810785, 7455.776326710207, 7246.5289756165685, 7261.49169558147, 7270.002248145195, 7275.272472752682, 7294.416446684147, 6504.15445992018, 7075.610841403773, 7105.2894710528535, 7113.928860711534, 7196.676149573386, 6556.3443655634055, 6607.471402287902, 6721.714617030233, 6788.422984046411, 7097.893531596927, 4281.828726851016, 4443.977780111079, 4799.3041009052795, 4932.136876046606, 4970.4840023793095, 7096.53872498286, 7229.060222171294, 7252.782416527512, 7311.0, 7319.780406587811, 6627.502937279627, 6861.382475577222, 7058.482426278375, 7066.929330706627, 7081.973113898559, 6934.549254298455, 6941.403477200171, 6951.131108611424, 6993.020977063317, 6997.405220556309, 5166.922496162636, 6814.2163651180745, 6863.117490653837, 6867.175938887365, 6868.0, 6620.966895165493, 6964.582125072512, 6981.476389270723, 6988.0, 7014.333638304353, 6731.600541726397, 6746.679852360244, 6761.6138691569095, 6792.0, 6809.399791049001], "value": 5087.401013455118, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [778.7546922719343, 808.7614153824628, 808.9838203235931, 837.9287760540356, 846.8179341441589, 14396.424143034292, 14905.180215088165, 16511.75232371514, 17045.659086818257, 17066.488005359828, 15591.986520876135, 15869.841301586981, 16731.0, 17459.82540174598, 17684.0, 964.8504481805314, 965.6764983730459, 986.4525188520369, 997.3965750720819, 1007.1439276614892, 984.1683777208248, 1010.7725761703631, 1014.4065721552901, 1015.1523477895965, 1016.2885979814123, 926.3145272498336, 1032.3960483117385, 1034.6740776655367, 1044.5404022230232, 1047.9528421221025, 1012.8987101289849, 1031.7111208861493, 1042.8592140061107, 1045.6497073480393, 1047.7799662070975, 770.9653065612005, 787.7124849429974, 788.5032429569392, 789.9091604465445, 790.1821614628889, 13863.792043119362, 16859.831401685948, 16908.577285567837, 17076.65846683072, 17220.82779172217, 17310.82689173105, 17383.47849564515, 17663.11684415787, 17903.462896113007, 17921.193546290448, 14687.485937992118, 14838.554843354717, 14883.33025013877, 14939.103653780809, 14982.0, 3589.94615080774, 3594.11944073701, 3607.332643460968, 3632.9273414531826, 3633.6911362534215, 142852.0, 144393.5560644401, 146590.0, 147439.0, 148526.25736871245, 13671.316434178252, 13694.383752731152, 13837.654058648613, 13902.374393152333, 13909.095908765898, 26610.866945665533, 26926.865365673046, 27483.587746183824, 27555.586666200023, 27567.0, 7318.487705860645, 7703.8844417333785, 7753.806154846061, 7885.172056934054, 7967.083785364642, 3238.498032804872, 3535.4873543336585, 3811.7903515306925, 3961.8811435656976, 3962.088719594452, 4532.410786597721, 4591.609713174417, 4649.790759415834, 4650.162970665313, 4681.6020638245445, 948.7201275623707, 981.3915372468972, 982.4792859784326, 984.0, 987.8172538080478, 587.512364737267, 658.344946777959, 675.2875716119443, 677.366662170862, 687.3985262894967, 9888.159506442033, 9955.50222488887, 11608.071354291636, 11873.940630296793, 12606.55877044299, 620.172070286164, 639.0605809460086, 643.7457204404234, 665.8135721997849, 667.8029981155571, 5240.4497527759795, 5642.463965923231, 5780.4508571686465, 5878.617889837233, 5896.793612223637, 135420.0, 150475.0, 151978.0, 152809.0, 154973.0, 110167.0, 110981.0, 117786.41106794582, 117966.0, 122368.0, 96189.5190524057, 97700.0, 99401.50299248457, 101145.0, 103365.96634033714, 10167.949160254151, 11833.940830295793, 11974.341411222467, 12091.81862272049, 12718.554850580185, 39273.0, 44023.77988110039, 44057.339139913565, 46176.0, 48152.555423337355, 62609.0, 63002.36997629965, 63234.051489226775, 63461.36538634555, 63491.682541587, 16760.0, 16814.991100534007, 17018.914905425394, 17152.228149733543, 17244.0, 17066.48800535986, 18477.630447390708, 18727.344542940933, 18857.057147142863, 18946.810531894505, 17159.828401715822, 17781.644367112844, 17836.375726850016, 17880.821191787916, 17965.281388744304, 16984.0, 17074.914625426794, 17405.390811321544, 17814.732779008584, 17828.732569011736, 16974.49076527706, 17119.058451785273, 17160.742588861427, 17336.87310324825, 17360.0, 11179.217454778087, 11531.538738450368, 11533.308001519936, 11942.582009629969, 12348.259104453762, 13001.804972925225, 14142.22217777991, 14228.14631122136, 14656.706865862836, 14658.853411465749], "value": 8755.195209330976, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1565.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 117.0, 97.0, 150.0, 174.0, 151.0, 116.0, 114.0, 114.0, 116.0], "value": 126.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 112.0, 138.0, 188.0, 127.0, 129.0, 117.0, 156.0, 147.0, 113.0], "value": 135.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [203.0, 200.0, 198.0, 220.0, 219.0, 266.0, 173.0, 239.0, 266.0, 206.0], "value": 219.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 82.0, 74.0, 150.0, 59.0, 58.0, 59.0, 64.0, 121.0, 55.0], "value": 79.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 72.0, 110.0, 71.0, 71.0, 83.0, 114.0, 107.0, 101.0, 113.0], "value": 91.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 88.0, 88.0, 88.0, 88.0, 88.0, 87.0, 89.0, 88.0], "value": 88.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 95.0, 99.0, 96.0, 95.0, 95.0, 99.0, 95.0], "value": 96.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 80.0, 143.0, 153.0, 91.0, 88.0, 91.0, 92.0, 94.0, 91.0], "value": 107.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 51.0, 55.0, 57.0, 67.0, 54.0, 52.0, 52.0, 53.0, 52.0], "value": 54.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 65.0, 70.0, 59.0, 56.0, 60.0, 59.0, 57.0, 57.0, 56.0], "value": 59.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 77.0, 88.0, 106.0, 106.0, 108.0, 87.0, 90.0, 110.0, 84.0], "value": 94.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 156.0, 155.0, 151.0, 134.0, 134.0, 135.0, 134.0, 149.0, 136.0], "value": 141.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 181.0, 188.0, 155.0, 179.0, 228.0, 161.0, 200.0, 229.0, 235.0], "value": 193.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 73.0, 88.0, 64.0, 67.0, 93.0, 98.0, 95.0, 90.0, 66.0], "value": 80.1, "unit": "score"}]}]} 10:02:07 INFO - Return code: 0 10:02:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:02:07 INFO - # TBPL SUCCESS # 10:02:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:02:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:02:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:02:07 INFO - 2017-06-10 10:02:07.824 screenresolution[2017:286447] starting screenresolution argv=screenresolution get 10:02:07 INFO - 2017-06-10 10:02:07.827 screenresolution[2017:286447] Display 0: 1600x1200x32@60 10:02:07 INFO - 2017-06-10 10:02:07.833 screenresolution[2018:286450] starting screenresolution argv=screenresolution list 10:02:07 INFO - Available Modes on Display 0 10:02:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:02:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:02:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:02:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:02:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:02:08 INFO - Intel Iris: 10:02:08 INFO - Chipset Model: Intel Iris 10:02:08 INFO - Type: GPU 10:02:08 INFO - Bus: Built-In 10:02:08 INFO - VRAM (Dynamic, Max): 1536 MB 10:02:08 INFO - Vendor: Intel (0x8086) 10:02:08 INFO - Device ID: 0x0a2e 10:02:08 INFO - Revision ID: 0x0009 10:02:08 INFO - Displays: 10:02:08 INFO - Display: 10:02:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:02:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:02:08 INFO - Display Serial Number: 1600x1200 60 10:02:08 INFO - Main Display: Yes 10:02:08 INFO - Mirror: Off 10:02:08 INFO - Online: Yes 10:02:08 INFO - Rotation: Supported 10:02:08 INFO - Return code: 0 10:02:08 INFO - Running post-action listener: _resource_record_post_action 10:02:08 INFO - [mozharness: 2017-06-10 17:02:08.074976Z] Finished run-tests step (success) 10:02:08 INFO - Running post-run listener: _resource_record_post_run 10:02:08 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 3453952; Write bytes: 577323520; Read time: 41; Write time: 1767 10:02:08 INFO - TinderboxPrint: CPU usage
53.3% 10:02:08 INFO - TinderboxPrint: I/O read bytes / time
3,453,952 / 41 10:02:08 INFO - TinderboxPrint: I/O write bytes / time
577,323,520 / 1,767 10:02:08 INFO - TinderboxPrint: CPU idle
2,351.3 (46.7%) 10:02:08 INFO - TinderboxPrint: CPU system
1,018.6 (20.3%) 10:02:08 INFO - TinderboxPrint: CPU user
1,659.8 (33.0%) 10:02:08 INFO - TinderboxPrint: Swap in / out
270,336,000 / 0 10:02:08 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 252877312; Write bytes: 254789632; Read time: 18139; Write time: 543 10:02:08 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 10:02:08 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 3355648; Write bytes: 320948736; Read time: 40; Write time: 1214 10:02:08 INFO - Running post-run listener: _upload_blobber_files 10:02:08 INFO - Blob upload gear active. 10:02:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:02:08 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 10:02:08 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'] 10:02:08 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 10:02:08 INFO - (blobuploader) - INFO - Open directory for files ... 10:02:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:02:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:02:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:02:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:02:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:02:10 INFO - (blobuploader) - INFO - Done attempting. 10:02:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:02:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:02:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:02:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:02:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:02:11 INFO - (blobuploader) - INFO - Done attempting. 10:02:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:02:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:02:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:02:13 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:02:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:02:13 INFO - (blobuploader) - INFO - Done attempting. 10:02:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:02:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:02:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:02:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:02:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:02:15 INFO - (blobuploader) - INFO - Done attempting. 10:02:15 INFO - (blobuploader) - INFO - Iteration through files over. 10:02:15 INFO - Return code: 0 10:02:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:02:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:02:15 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c6cfd626062c4d917166526b9849cbcc7ceb657fae89425d8882abb53fc5a27dd8a26dd37f4325d4eaf5df74282227dc9ce135b3a8882749bd7411cf960a702a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a95b11432283444de5c2668ecdd67989fd48bcef30a681ac82d84d1f6d1ed42c4a5faa5c1fd5990a397b4eb3afd2af9382e1772d247d5e35a7a53cb213e04a46", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1b459e00825168c077199578f97ffa8f49e5e00acd8615d271facf1abe0a940654754539c81cdc13792500a9556bdab5b85a490ad9d938bfd744e8d768e42d28", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5b7069bc5c0e0722a30c921f997a804e4c6b7b41a839b59d0d5066a82cd638314bc0eb6c00ebca3f602aa4777039387debc10a62078d2de78fdb485855a2e4fc"} 10:02:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:02:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:02:15 INFO - Contents: 10:02:15 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c6cfd626062c4d917166526b9849cbcc7ceb657fae89425d8882abb53fc5a27dd8a26dd37f4325d4eaf5df74282227dc9ce135b3a8882749bd7411cf960a702a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a95b11432283444de5c2668ecdd67989fd48bcef30a681ac82d84d1f6d1ed42c4a5faa5c1fd5990a397b4eb3afd2af9382e1772d247d5e35a7a53cb213e04a46", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1b459e00825168c077199578f97ffa8f49e5e00acd8615d271facf1abe0a940654754539c81cdc13792500a9556bdab5b85a490ad9d938bfd744e8d768e42d28", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5b7069bc5c0e0722a30c921f997a804e4c6b7b41a839b59d0d5066a82cd638314bc0eb6c00ebca3f602aa4777039387debc10a62078d2de78fdb485855a2e4fc"} 10:02:15 INFO - Running post-run listener: copy_logs_to_upload_dir 10:02:15 INFO - Copying logs to upload dir... 10:02:15 INFO - mkdir: /builds/slave/test/build/upload/logs 10:02:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1337.389343 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-06-10 10:02:15.151665) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-10 10:02:15.155560) ========= 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.1YYtlPNFA7/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.Udnp68UKr5/Listeners TMPDIR=/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c6cfd626062c4d917166526b9849cbcc7ceb657fae89425d8882abb53fc5a27dd8a26dd37f4325d4eaf5df74282227dc9ce135b3a8882749bd7411cf960a702a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a95b11432283444de5c2668ecdd67989fd48bcef30a681ac82d84d1f6d1ed42c4a5faa5c1fd5990a397b4eb3afd2af9382e1772d247d5e35a7a53cb213e04a46", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1b459e00825168c077199578f97ffa8f49e5e00acd8615d271facf1abe0a940654754539c81cdc13792500a9556bdab5b85a490ad9d938bfd744e8d768e42d28", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5b7069bc5c0e0722a30c921f997a804e4c6b7b41a839b59d0d5066a82cd638314bc0eb6c00ebca3f602aa4777039387debc10a62078d2de78fdb485855a2e4fc"} build_url:https://queue.taskcluster.net/v1/task/ObLsJjpsRtqAbwQE4rK4iA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011089 build_url: 'https://queue.taskcluster.net/v1/task/ObLsJjpsRtqAbwQE4rK4iA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c6cfd626062c4d917166526b9849cbcc7ceb657fae89425d8882abb53fc5a27dd8a26dd37f4325d4eaf5df74282227dc9ce135b3a8882749bd7411cf960a702a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a95b11432283444de5c2668ecdd67989fd48bcef30a681ac82d84d1f6d1ed42c4a5faa5c1fd5990a397b4eb3afd2af9382e1772d247d5e35a7a53cb213e04a46", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1b459e00825168c077199578f97ffa8f49e5e00acd8615d271facf1abe0a940654754539c81cdc13792500a9556bdab5b85a490ad9d938bfd744e8d768e42d28", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5b7069bc5c0e0722a30c921f997a804e4c6b7b41a839b59d0d5066a82cd638314bc0eb6c00ebca3f602aa4777039387debc10a62078d2de78fdb485855a2e4fc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-10 10:02:15.188895) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-10 10:02:15.189255) ========= 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.1YYtlPNFA7/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.Udnp68UKr5/Listeners TMPDIR=/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/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.005343 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-10 10:02:15.237956) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-10 10:02:15.238279) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-10 10:02:15.238603) ========= ========= Total master_lag: 0.17 =========