builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0001 starttime: 1497319805.87 results: success (0) buildid: 20170612173634 builduid: b91cc7b300044cb1989d38cbbf03f58a revision: 496fd4521a947593a9e46daca2bc42bd73ea49af ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-12 19:10:05.871787) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-12 19:10:05.872174) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-12 19:10:05.872439) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-12 19:10:06.345425) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 19:10:06.345718) ========= 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.6lsYYTdvWw/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.RzRUTHqgOB/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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-12 19:10:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 181M=0s 2017-06-12 19:10:06 (181 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.169767 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 19:10:06.554947) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 19:10:06.555681) ========= 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.6lsYYTdvWw/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.RzRUTHqgOB/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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.028296 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 19:10:06.608717) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-12 19:10:06.609499) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 496fd4521a947593a9e46daca2bc42bd73ea49af --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 496fd4521a947593a9e46daca2bc42bd73ea49af --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6lsYYTdvWw/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.RzRUTHqgOB/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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-12 19:10:06,706 truncating revision to first 12 chars 2017-06-12 19:10:06,706 Setting DEBUG logging. 2017-06-12 19:10:06,706 attempt 1/10 2017-06-12 19:10:06,706 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/496fd4521a94?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-12 19:10:07,869 unpacking tar archive at: mozilla-beta-496fd4521a94/testing/mozharness/ program finished with exit code 0 elapsedTime=1.434114 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-12 19:10:08.067091) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-12 19:10:08.067419) ========= script_repo_revision: 496fd4521a947593a9e46daca2bc42bd73ea49af ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-12 19:10:08.067778) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-12 19:10:08.068034) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-12 19:10:08.089271) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-06-12 19:10:08.089578) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6lsYYTdvWw/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.RzRUTHqgOB/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 19:10:08 INFO - MultiFileLogger online at 20170612 19:10:08 in /builds/slave/test 19:10:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 19:10:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:10:08 INFO - {'append_to_log': False, 19:10:08 INFO - 'base_work_dir': '/builds/slave/test', 19:10:08 INFO - 'blob_upload_branch': 'Mozilla-Beta', 19:10:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:10:08 INFO - 'branch': 'Mozilla-Beta', 19:10:08 INFO - 'buildbot_json_path': 'buildprops.json', 19:10:08 INFO - 'config_files': ('talos/mac_config.py',), 19:10:08 INFO - 'default_actions': ('clobber', 19:10:08 INFO - 'read-buildbot-config', 19:10:08 INFO - 'download-and-extract', 19:10:08 INFO - 'populate-webroot', 19:10:08 INFO - 'create-virtualenv', 19:10:08 INFO - 'install', 19:10:08 INFO - 'setup-mitmproxy', 19:10:08 INFO - 'run-tests'), 19:10:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:10:08 INFO - 'download_minidump_stackwalk': True, 19:10:08 INFO - 'download_symbols': 'ondemand', 19:10:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:10:08 INFO - 'tooltool.py': '/tools/tooltool.py', 19:10:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:10:08 INFO - '/tools/misc-python/virtualenv.py')}, 19:10:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:10:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:10:08 INFO - 'gecko_profile': False, 19:10:08 INFO - 'gecko_profile_interval': 0, 19:10:08 INFO - 'installer_path': 'installer.exe', 19:10:08 INFO - 'log_level': 'info', 19:10:08 INFO - 'log_name': 'talos', 19:10:08 INFO - 'log_to_console': True, 19:10:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:10:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:10:08 INFO - 'opt_config_files': (), 19:10:08 INFO - 'pip_index': False, 19:10:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:10:08 INFO - 'cmd': ('bash', 19:10:08 INFO - '-c', 19:10:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:10:08 INFO - 'enabled': True, 19:10:08 INFO - 'halt_on_failure': False, 19:10:08 INFO - 'name': 'check_screen_resolution'},), 19:10:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:10:08 INFO - 'cmd': ('bash', 19:10:08 INFO - '-c', 19:10:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:10:08 INFO - 'enabled': True, 19:10:08 INFO - 'halt_on_failure': False, 19:10:08 INFO - 'name': 'check_screen_resolution'},), 19:10:08 INFO - 'run_cmd_checks_enabled': True, 19:10:08 INFO - 'suite': 'dromaeojs-e10s', 19:10:08 INFO - 'system_bits': '32', 19:10:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:10:08 INFO - 'title': 't-yosemite-r7-0001', 19:10:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:10:08 INFO - 'use_talos_json': True, 19:10:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:10:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:10:08 INFO - 'work_dir': 'build'} 19:10:08 INFO - [mozharness: 2017-06-13 02:10:08.303193Z] Running clobber step. 19:10:08 INFO - Running pre-action listener: _resource_record_pre_action 19:10:08 INFO - Running main action method: clobber 19:10:08 INFO - rmtree: /builds/slave/test/build 19:10:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:10:10 INFO - Running post-action listener: _resource_record_post_action 19:10:10 INFO - [mozharness: 2017-06-13 02:10:10.814991Z] Finished clobber step (success) 19:10:10 INFO - [mozharness: 2017-06-13 02:10:10.815119Z] Running read-buildbot-config step. 19:10:10 INFO - Running pre-action listener: _resource_record_pre_action 19:10:10 INFO - Running main action method: read_buildbot_config 19:10:10 INFO - Using buildbot properties: 19:10:10 INFO - { 19:10:10 INFO - "project": "", 19:10:10 INFO - "product": "firefox", 19:10:10 INFO - "script_repo_revision": "production", 19:10:10 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 19:10:10 INFO - "repository": "", 19:10:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 19:10:10 INFO - "buildid": "20170612173634", 19:10:10 INFO - "pgo_build": "False", 19:10:10 INFO - "basedir": "/builds/slave/test", 19:10:10 INFO - "buildnumber": 0, 19:10:10 INFO - "slavename": "t-yosemite-r7-0001", 19:10:10 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 19:10:10 INFO - "platform": "yosemite_r7_devedition", 19:10:10 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 19:10:10 INFO - "branch": "mozilla-beta", 19:10:10 INFO - "repo_path": "releases/mozilla-beta", 19:10:10 INFO - "revision": "496fd4521a947593a9e46daca2bc42bd73ea49af", 19:10:10 INFO - "stage_platform": "macosx64-devedition", 19:10:10 INFO - "builduid": "b91cc7b300044cb1989d38cbbf03f58a", 19:10:10 INFO - "slavebuilddir": "test" 19:10:10 INFO - } 19:10:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/VbFe07bERzWfuxK3-eOZiQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg. 19:10:10 INFO - Running post-action listener: _resource_record_post_action 19:10:10 INFO - [mozharness: 2017-06-13 02:10:10.818506Z] Finished read-buildbot-config step (success) 19:10:10 INFO - [mozharness: 2017-06-13 02:10:10.818640Z] Running download-and-extract step. 19:10:10 INFO - Running pre-action listener: _resource_record_pre_action 19:10:10 INFO - Running main action method: download_and_extract 19:10:10 INFO - mkdir: /builds/slave/test/build/tests 19:10:10 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')]} 19:10:10 INFO - trying https://queue.taskcluster.net/v1/task/VbFe07bERzWfuxK3-eOZiQ/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json 19:10:10 INFO - Downloading https://queue.taskcluster.net/v1/task/VbFe07bERzWfuxK3-eOZiQ/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 19:10:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VbFe07bERzWfuxK3-eOZiQ/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json'}, attempt #1 19:10:12 INFO - Downloaded 1290 bytes. 19:10:12 INFO - Reading from file /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 19:10:12 INFO - Using the following test package requirements: 19:10:12 INFO - {u'awsy': [u'firefox-55.0.en-US.mac.common.tests.zip', 19:10:12 INFO - u'firefox-55.0.en-US.mac.awsy.tests.zip'], 19:10:12 INFO - u'common': [u'firefox-55.0.en-US.mac.common.tests.zip'], 19:10:12 INFO - u'cppunittest': [u'firefox-55.0.en-US.mac.common.tests.zip', 19:10:12 INFO - u'firefox-55.0.en-US.mac.cppunittest.tests.zip'], 19:10:12 INFO - u'gtest': [u'firefox-55.0.en-US.mac.common.tests.zip', 19:10:12 INFO - u'firefox-55.0.en-US.mac.gtest.tests.zip'], 19:10:12 INFO - u'jittest': [u'firefox-55.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 19:10:12 INFO - u'mochitest': [u'firefox-55.0.en-US.mac.common.tests.zip', 19:10:12 INFO - u'firefox-55.0.en-US.mac.mochitest.tests.zip'], 19:10:12 INFO - u'mozbase': [u'firefox-55.0.en-US.mac.common.tests.zip'], 19:10:12 INFO - u'reftest': [u'firefox-55.0.en-US.mac.common.tests.zip', 19:10:12 INFO - u'firefox-55.0.en-US.mac.reftest.tests.zip'], 19:10:12 INFO - u'talos': [u'firefox-55.0.en-US.mac.common.tests.zip', 19:10:12 INFO - u'firefox-55.0.en-US.mac.talos.tests.zip'], 19:10:12 INFO - u'web-platform': [u'firefox-55.0.en-US.mac.common.tests.zip', 19:10:12 INFO - u'firefox-55.0.en-US.mac.web-platform.tests.tar.gz'], 19:10:12 INFO - u'xpcshell': [u'firefox-55.0.en-US.mac.common.tests.zip', 19:10:12 INFO - u'firefox-55.0.en-US.mac.xpcshell.tests.zip']} 19:10:12 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip'] for test suite category: common 19:10:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VbFe07bERzWfuxK3-eOZiQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 19:10:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VbFe07bERzWfuxK3-eOZiQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 19:10:12 INFO - Fetch https://queue.taskcluster.net/v1/task/VbFe07bERzWfuxK3-eOZiQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 19:10:15 INFO - Content-Length response header: 59545504 19:10:15 INFO - Bytes received: 59545504 19:10:33 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip', u'firefox-55.0.en-US.mac.talos.tests.zip'] for test suite category: talos 19:10:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VbFe07bERzWfuxK3-eOZiQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 19:10:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VbFe07bERzWfuxK3-eOZiQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 19:10:33 INFO - Fetch https://queue.taskcluster.net/v1/task/VbFe07bERzWfuxK3-eOZiQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 19:10:35 INFO - Content-Length response header: 59545504 19:10:35 INFO - Bytes received: 59545504 19:10:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VbFe07bERzWfuxK3-eOZiQ/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip 19:10:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VbFe07bERzWfuxK3-eOZiQ/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip'}, attempt #1 19:10:53 INFO - Fetch https://queue.taskcluster.net/v1/task/VbFe07bERzWfuxK3-eOZiQ/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip into memory 19:10:54 INFO - Content-Length response header: 14450018 19:10:54 INFO - Bytes received: 14450018 19:10: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')]} 19:10:54 INFO - trying https://queue.taskcluster.net/v1/task/VbFe07bERzWfuxK3-eOZiQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg 19:10:54 INFO - Downloading https://queue.taskcluster.net/v1/task/VbFe07bERzWfuxK3-eOZiQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0.en-US.mac.dmg 19:10:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VbFe07bERzWfuxK3-eOZiQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg'}, attempt #1 19:10:56 INFO - Downloaded 66877372 bytes. 19:10:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VbFe07bERzWfuxK3-eOZiQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg 19:10:56 INFO - mkdir: /builds/slave/test/properties 19:10:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:10:56 INFO - Writing to file /builds/slave/test/properties/build_url 19:10:56 INFO - Contents: 19:10:56 INFO - build_url:https://queue.taskcluster.net/v1/task/VbFe07bERzWfuxK3-eOZiQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg 19:10:57 INFO - Running post-action listener: _resource_record_post_action 19:10:57 INFO - Running post-action listener: set_extra_try_arguments 19:10:57 INFO - [mozharness: 2017-06-13 02:10:57.190745Z] Finished download-and-extract step (success) 19:10:57 INFO - [mozharness: 2017-06-13 02:10:57.190883Z] Running populate-webroot step. 19:10:57 INFO - Running pre-action listener: _resource_record_pre_action 19:10:57 INFO - Running main action method: populate_webroot 19:10:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:10:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:10:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:10:57 INFO - '--tppagecycles', 19:10:57 INFO - '7'], 19:10:57 INFO - 'tests': ['tsvgm']}}, 19:10:57 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:10:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:10:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:10:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:10:57 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:10:57 INFO - 'talos_options': ['--disable-e10s'], 19:10:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:10:57 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:10:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:10:57 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:10:57 INFO - 'talos_options': ['--disable-e10s'], 19:10:57 INFO - 'tests': ['damp', 'tps']}, 19:10:57 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:10:57 INFO - 'tests': ['damp', 'tps']}, 19:10:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:10:57 INFO - 'tests': ['dromaeo_dom']}, 19:10:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:10:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:10:57 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 19:10:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 19:10:57 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 19:10:57 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 19:10:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:10:57 INFO - 'tests': ['a11yr', 19:10:57 INFO - 'ts_paint', 19:10:57 INFO - 'tpaint', 19:10:57 INFO - 'sessionrestore', 19:10:57 INFO - 'sessionrestore_no_auto_restore', 19:10:57 INFO - 'tabpaint']}, 19:10:57 INFO - 'other-e10s': {'tests': ['a11yr', 19:10:57 INFO - 'ts_paint', 19:10:57 INFO - 'tpaint', 19:10:57 INFO - 'sessionrestore', 19:10:57 INFO - 'sessionrestore_no_auto_restore', 19:10:57 INFO - 'tabpaint']}, 19:10:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:10:57 INFO - 'ts_paint', 19:10:57 INFO - 'tpaint', 19:10:57 INFO - 'sessionrestore', 19:10:57 INFO - 'sessionrestore_no_auto_restore', 19:10:57 INFO - 'tabpaint']}, 19:10:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:10:57 INFO - 'ts_paint', 19:10:57 INFO - 'tpaint', 19:10:57 INFO - 'sessionrestore', 19:10:57 INFO - 'sessionrestore_no_auto_restore', 19:10:57 INFO - 'tabpaint']}, 19:10:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:10:57 INFO - 'tests': ['a11yr', 19:10:57 INFO - 'ts_paint', 19:10:57 INFO - 'tpaint', 19:10:57 INFO - 'sessionrestore', 19:10:57 INFO - 'sessionrestore_no_auto_restore', 19:10:57 INFO - 'tabpaint']}, 19:10:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:10:57 INFO - 'tests': ['a11yr', 19:10:57 INFO - 'ts_paint', 19:10:57 INFO - 'tpaint', 19:10:57 INFO - 'sessionrestore', 19:10:57 INFO - 'sessionrestore_no_auto_restore', 19:10:57 INFO - 'tabpaint']}, 19:10:57 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 19:10:57 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 19:10:57 INFO - 'bloom_basic_ref']}, 19:10:57 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 19:10:57 INFO - 'talos_options': ['--mitmproxy', 19:10:57 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 19:10:57 INFO - '--firstNonBlankPaint'], 19:10:57 INFO - 'tests': ['quantum_pageload_google', 19:10:57 INFO - 'quantum_pageload_youtube', 19:10:57 INFO - 'quantum_pageload_amazon', 19:10:57 INFO - 'quantum_pageload_facebook']}, 19:10:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:10:57 INFO - 'tests': ['tsvgx', 19:10:57 INFO - 'tsvgr_opacity', 19:10:57 INFO - 'tart', 19:10:57 INFO - 'tscrollx', 19:10:57 INFO - 'cart', 19:10:57 INFO - 'tsvg_static']}, 19:10:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:10:57 INFO - 'tsvgr_opacity', 19:10:57 INFO - 'tart', 19:10:57 INFO - 'tscrollx', 19:10:57 INFO - 'cart', 19:10:57 INFO - 'tsvg_static']}, 19:10:57 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:10:57 INFO - 'talos_options': ['--disable-e10s'], 19:10:57 INFO - 'tests': ['tp5o']}, 19:10:57 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:10:57 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:10:57 INFO - 'talos_options': ['--disable-e10s', 19:10:57 INFO - '--xperf_path', 19:10:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:10:57 INFO - 'tests': ['tp5n']}, 19:10:57 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:10:57 INFO - 'talos_options': ['--xperf_path', 19:10:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:10:57 INFO - 'tests': ['tp5n']}}, 19:10:57 INFO - 'talos.zip': {'path': '', 19:10:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:10:57 INFO - Running post-action listener: _resource_record_post_action 19:10:57 INFO - [mozharness: 2017-06-13 02:10:57.211620Z] Finished populate-webroot step (success) 19:10:57 INFO - [mozharness: 2017-06-13 02:10:57.211706Z] Running create-virtualenv step. 19:10:57 INFO - Running pre-action listener: _resource_record_pre_action 19:10:57 INFO - Running main action method: create_virtualenv 19:10:57 INFO - Creating virtualenv /builds/slave/test/build/venv 19:10:57 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 19:10:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:10:57 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 19:10:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:10:57 INFO - Using real prefix '/tools/python27' 19:10:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:10:58 INFO - Installing distribute.............................................................................................................................................................................................done. 19:11:00 INFO - Installing pip.................done. 19:11:00 INFO - Return code: 0 19:11:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:11:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:11:01 INFO - Reading from file tmpfile_stdout 19:11:01 INFO - Output received: 19:11:01 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) 19:11:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:11:01 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')]} 19:11:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11:01 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')]} 19:11:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11:01 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 0x103b32c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b36768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1105791c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103888d30>, '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 0x103852cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103879be8>, '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.6lsYYTdvWw/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.RzRUTHqgOB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 19:11:01 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 19:11:01 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 19:11:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6lsYYTdvWw/Render', 19:11:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:11:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:11:01 INFO - 'HOME': '/Users/cltbld', 19:11:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:11:01 INFO - 'LOGNAME': 'cltbld', 19:11:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:01 INFO - 'MOZ_NO_REMOTE': '1', 19:11:01 INFO - 'NO_EM_RESTART': '1', 19:11:01 INFO - 'PAGER': '/bin/cat', 19:11:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:11:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:11:01 INFO - 'PWD': '/builds/slave/test', 19:11:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:11:01 INFO - 'SHELL': '/bin/bash', 19:11:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RzRUTHqgOB/Listeners', 19:11:01 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 19:11:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:11:01 INFO - 'USER': 'cltbld', 19:11:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:11:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:11:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:11:01 INFO - 'XPC_FLAGS': '0x0', 19:11:01 INFO - 'XPC_SERVICE_NAME': '0', 19:11:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:11:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:01 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 19:11:01 INFO - Cleaning up... 19:11:01 INFO - Return code: 0 19:11:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:11:01 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')]} 19:11:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11:01 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')]} 19:11:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11:01 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 0x103b32c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b36768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1105791c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103888d30>, '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 0x103852cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103879be8>, '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.6lsYYTdvWw/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.RzRUTHqgOB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 19:11:01 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 19:11:01 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 19:11:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6lsYYTdvWw/Render', 19:11:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:11:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:11:01 INFO - 'HOME': '/Users/cltbld', 19:11:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:11:01 INFO - 'LOGNAME': 'cltbld', 19:11:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:01 INFO - 'MOZ_NO_REMOTE': '1', 19:11:01 INFO - 'NO_EM_RESTART': '1', 19:11:01 INFO - 'PAGER': '/bin/cat', 19:11:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:11:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:11:01 INFO - 'PWD': '/builds/slave/test', 19:11:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:11:01 INFO - 'SHELL': '/bin/bash', 19:11:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RzRUTHqgOB/Listeners', 19:11:01 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 19:11:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:11:01 INFO - 'USER': 'cltbld', 19:11:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:11:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:11:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:11:01 INFO - 'XPC_FLAGS': '0x0', 19:11:01 INFO - 'XPC_SERVICE_NAME': '0', 19:11:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:11:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:01 INFO - Downloading/unpacking psutil>=3.1.1 19:11:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:11:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:11:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:11:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:11:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:11:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:11:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:11:03 INFO - Installing collected packages: psutil 19:11:03 INFO - Running setup.py install for psutil 19:11:04 INFO - building 'psutil._psutil_osx' extension 19:11:04 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 19:11:04 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 19:11:04 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 19:11:04 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 19:11:04 INFO - building 'psutil._psutil_posix' extension 19:11:04 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 19:11:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:11:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:11:04 INFO - ^ 19:11:05 INFO - 1 warning generated. 19:11:05 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 19:11:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:11:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:11:05 INFO - Successfully installed psutil 19:11:05 INFO - Cleaning up... 19:11:05 INFO - Return code: 0 19:11:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:11:05 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')]} 19:11:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11:05 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')]} 19:11:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11:05 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 0x103b32c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b36768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1105791c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103888d30>, '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 0x103852cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103879be8>, '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.6lsYYTdvWw/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.RzRUTHqgOB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 19:11:05 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 19:11:05 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 19:11:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6lsYYTdvWw/Render', 19:11:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:11:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:11:05 INFO - 'HOME': '/Users/cltbld', 19:11:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:11:05 INFO - 'LOGNAME': 'cltbld', 19:11:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:05 INFO - 'MOZ_NO_REMOTE': '1', 19:11:05 INFO - 'NO_EM_RESTART': '1', 19:11:05 INFO - 'PAGER': '/bin/cat', 19:11:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:11:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:11:05 INFO - 'PWD': '/builds/slave/test', 19:11:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:11:05 INFO - 'SHELL': '/bin/bash', 19:11:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RzRUTHqgOB/Listeners', 19:11:05 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 19:11:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:11:05 INFO - 'USER': 'cltbld', 19:11:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:11:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:11:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:11:05 INFO - 'XPC_FLAGS': '0x0', 19:11:05 INFO - 'XPC_SERVICE_NAME': '0', 19:11:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:11:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:11: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 19:11: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 19:11: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 19:11: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 19:11:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:11:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:11:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:11:06 INFO - Installing collected packages: mozsystemmonitor 19:11:06 INFO - Running setup.py install for mozsystemmonitor 19:11:07 INFO - Successfully installed mozsystemmonitor 19:11:07 INFO - Cleaning up... 19:11:07 INFO - Return code: 0 19:11:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:11: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')]} 19:11:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11: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')]} 19:11:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11:07 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 0x103b32c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b36768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1105791c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103888d30>, '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 0x103852cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103879be8>, '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.6lsYYTdvWw/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.RzRUTHqgOB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 19:11:07 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 19:11:07 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 19:11:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6lsYYTdvWw/Render', 19:11:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:11:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:11:07 INFO - 'HOME': '/Users/cltbld', 19:11:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:11:07 INFO - 'LOGNAME': 'cltbld', 19:11:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:07 INFO - 'MOZ_NO_REMOTE': '1', 19:11:07 INFO - 'NO_EM_RESTART': '1', 19:11:07 INFO - 'PAGER': '/bin/cat', 19:11:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:11:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:11:07 INFO - 'PWD': '/builds/slave/test', 19:11:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:11:07 INFO - 'SHELL': '/bin/bash', 19:11:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RzRUTHqgOB/Listeners', 19:11:07 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 19:11:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:11:07 INFO - 'USER': 'cltbld', 19:11:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:11:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:11:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:11:07 INFO - 'XPC_FLAGS': '0x0', 19:11:07 INFO - 'XPC_SERVICE_NAME': '0', 19:11:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:11:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:07 INFO - Downloading/unpacking jsonschema==2.5.1 19:11:07 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 19:11:07 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 19:11:07 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 19:11:07 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 19:11:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:11:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:11: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 19:11: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 19:11: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 19:11: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 19:11:09 INFO - Downloading functools32-3.2.3-2.tar.gz 19:11:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:11:09 INFO - warning: no files found matching '*.txt' 19:11:09 INFO - no previously-included directories found matching 'build' 19:11:09 INFO - no previously-included directories found matching 'dist' 19:11:09 INFO - no previously-included directories found matching '.git*' 19:11:09 INFO - Installing collected packages: jsonschema, functools32 19:11:09 INFO - Running setup.py install for functools32 19:11:09 INFO - warning: no files found matching '*.txt' 19:11:09 INFO - no previously-included directories found matching 'build' 19:11:09 INFO - no previously-included directories found matching 'dist' 19:11:09 INFO - no previously-included directories found matching '.git*' 19:11:09 INFO - Successfully installed jsonschema functools32 19:11:09 INFO - Cleaning up... 19:11:09 INFO - Return code: 0 19:11:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:11: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')]} 19:11:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11: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')]} 19:11:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11: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', '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 0x103b32c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b36768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1105791c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103888d30>, '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 0x103852cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103879be8>, '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.6lsYYTdvWw/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.RzRUTHqgOB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 19:11: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:11: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 functools32==3.2.3-2 19:11:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6lsYYTdvWw/Render', 19:11:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:11:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:11:09 INFO - 'HOME': '/Users/cltbld', 19:11:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:11:09 INFO - 'LOGNAME': 'cltbld', 19:11:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:09 INFO - 'MOZ_NO_REMOTE': '1', 19:11:09 INFO - 'NO_EM_RESTART': '1', 19:11:09 INFO - 'PAGER': '/bin/cat', 19:11:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:11:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:11:09 INFO - 'PWD': '/builds/slave/test', 19:11:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:11:09 INFO - 'SHELL': '/bin/bash', 19:11:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RzRUTHqgOB/Listeners', 19:11:09 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 19:11:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:11:09 INFO - 'USER': 'cltbld', 19:11:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:11:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:11:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:11:09 INFO - 'XPC_FLAGS': '0x0', 19:11:09 INFO - 'XPC_SERVICE_NAME': '0', 19:11:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:11:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:11:09 INFO - Cleaning up... 19:11:09 INFO - Return code: 0 19:11:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:11: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')]} 19:11:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11: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')]} 19:11:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11: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', '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 0x103b32c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b36768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1105791c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103888d30>, '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 0x103852cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103879be8>, '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.6lsYYTdvWw/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.RzRUTHqgOB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 19:11: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:11: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 blobuploader==1.2.4 19:11:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6lsYYTdvWw/Render', 19:11:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:11:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:11:09 INFO - 'HOME': '/Users/cltbld', 19:11:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:11:09 INFO - 'LOGNAME': 'cltbld', 19:11:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:09 INFO - 'MOZ_NO_REMOTE': '1', 19:11:09 INFO - 'NO_EM_RESTART': '1', 19:11:09 INFO - 'PAGER': '/bin/cat', 19:11:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:11:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:11:09 INFO - 'PWD': '/builds/slave/test', 19:11:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:11:09 INFO - 'SHELL': '/bin/bash', 19:11:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RzRUTHqgOB/Listeners', 19:11:09 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 19:11:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:11:09 INFO - 'USER': 'cltbld', 19:11:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:11:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:11:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:11:09 INFO - 'XPC_FLAGS': '0x0', 19:11:09 INFO - 'XPC_SERVICE_NAME': '0', 19:11:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:11:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:09 INFO - Downloading/unpacking blobuploader==1.2.4 19:11:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:11:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:11:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:11:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:11:11 INFO - Downloading blobuploader-1.2.4.tar.gz 19:11:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:11:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:11:11 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 19:11:11 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 19:11:11 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 19:11:11 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 19:11:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:11:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:11:11 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 19:11:11 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 19:11:11 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 19:11:11 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 19:11:12 INFO - Downloading docopt-0.6.1.tar.gz 19:11:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:11:12 INFO - Installing collected packages: blobuploader, requests, docopt 19:11:12 INFO - Running setup.py install for blobuploader 19:11:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:11:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:11:12 INFO - Running setup.py install for requests 19:11:12 INFO - Running setup.py install for docopt 19:11:13 INFO - Successfully installed blobuploader requests docopt 19:11:13 INFO - Cleaning up... 19:11:13 INFO - Return code: 0 19:11:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:11:13 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')]} 19:11:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11:13 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')]} 19:11:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11:13 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 0x103b32c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b36768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1105791c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103888d30>, '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 0x103852cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103879be8>, '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.6lsYYTdvWw/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.RzRUTHqgOB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 19:11:13 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 19:11:13 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 19:11:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6lsYYTdvWw/Render', 19:11:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:11:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:11:13 INFO - 'HOME': '/Users/cltbld', 19:11:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:11:13 INFO - 'LOGNAME': 'cltbld', 19:11:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:13 INFO - 'MOZ_NO_REMOTE': '1', 19:11:13 INFO - 'NO_EM_RESTART': '1', 19:11:13 INFO - 'PAGER': '/bin/cat', 19:11:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:11:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:11:13 INFO - 'PWD': '/builds/slave/test', 19:11:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:11:13 INFO - 'SHELL': '/bin/bash', 19:11:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RzRUTHqgOB/Listeners', 19:11:13 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 19:11:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:11:13 INFO - 'USER': 'cltbld', 19:11:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:11:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:11:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:11:13 INFO - 'XPC_FLAGS': '0x0', 19:11:13 INFO - 'XPC_SERVICE_NAME': '0', 19:11:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:11:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:11:13 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-HH6Fey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:11:13 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-ox07qt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:11:13 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-kMQbbE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:11:13 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-GQnQW2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:11:13 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-Or0soB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:11:13 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-l6Dvks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:11:14 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-5lwJfP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:11:14 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-p1mFT0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:11:14 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-GiKYbJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:11:14 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-qt0coC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:11:14 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-EbXMsQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:11:14 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-XT_62K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:11:14 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-OSreuW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:11:14 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-M67eay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:11:14 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-60YFMq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:11:14 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-AsBK2F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:11:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:11:15 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-8I0_Qr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:11:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:11:15 INFO - Running setup.py install for manifestparser 19:11:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:11:15 INFO - Running setup.py install for mozcrash 19:11:15 INFO - Running setup.py install for mozdebug 19:11:15 INFO - Running setup.py install for mozdevice 19:11:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:11:15 INFO - Running setup.py install for mozfile 19:11:15 INFO - Running setup.py install for mozhttpd 19:11:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:11:16 INFO - Running setup.py install for mozinfo 19:11:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:11:16 INFO - Running setup.py install for mozInstall 19:11:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:11:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:11:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:11:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:11:16 INFO - Running setup.py install for mozleak 19:11:16 INFO - Running setup.py install for mozlog 19:11:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:11:16 INFO - Running setup.py install for moznetwork 19:11:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:11:16 INFO - Running setup.py install for mozprocess 19:11:16 INFO - Running setup.py install for mozprofile 19:11:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:11:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:11:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:11:17 INFO - Running setup.py install for mozrunner 19:11:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:11:17 INFO - Running setup.py install for mozscreenshot 19:11:17 INFO - Running setup.py install for moztest 19:11:17 INFO - Running setup.py install for mozversion 19:11:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:11:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:11:17 INFO - Cleaning up... 19:11:17 INFO - Return code: 0 19:11:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:11:17 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')]} 19:11:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11:17 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')]} 19:11:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11:17 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 0x103b32c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b36768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1105791c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103888d30>, '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 0x103852cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103879be8>, '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.6lsYYTdvWw/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.RzRUTHqgOB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 19:11:17 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 19:11:17 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 19:11:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6lsYYTdvWw/Render', 19:11:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:11:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:11:17 INFO - 'HOME': '/Users/cltbld', 19:11:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:11:17 INFO - 'LOGNAME': 'cltbld', 19:11:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:17 INFO - 'MOZ_NO_REMOTE': '1', 19:11:17 INFO - 'NO_EM_RESTART': '1', 19:11:17 INFO - 'PAGER': '/bin/cat', 19:11:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:11:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:11:17 INFO - 'PWD': '/builds/slave/test', 19:11:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:11:17 INFO - 'SHELL': '/bin/bash', 19:11:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RzRUTHqgOB/Listeners', 19:11:17 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 19:11:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:11:17 INFO - 'USER': 'cltbld', 19:11:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:11:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:11:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:11:17 INFO - 'XPC_FLAGS': '0x0', 19:11:17 INFO - 'XPC_SERVICE_NAME': '0', 19:11:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:11:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:11:18 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-dt512w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:11:18 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)) 19:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:11:18 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-qb5jyz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:11:18 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)) 19:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:11:18 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-n5Hf3g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:11:18 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)) 19:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:11:18 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-MNqxbe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:11:18 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)) 19:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:11:18 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-hJjlpJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:11:18 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)) 19:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:11:18 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-DwY3pu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:11:18 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)) 19:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:11:18 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-qCd1kQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:11:18 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)) 19:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:11:18 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-0R9oFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:11:18 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)) 19:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:11:18 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-oKRE3g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:11:18 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)) 19:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:11:18 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-__xNQ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:11:19 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)) 19:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:11:19 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-fLEL9Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:11:19 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)) 19:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:11:19 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-O5fMJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:11:19 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)) 19:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:11:19 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-gaH_ku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:11:19 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)) 19:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:11:19 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-wi8az9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:11:19 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)) 19:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:11:19 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-rIrdrk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:11:19 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)) 19:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:11:19 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-FwWNQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:11:19 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)) 19:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:11:19 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-fJfDp2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:11:19 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)) 19:11:19 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)) 19:11:19 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)) 19:11:19 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)) 19:11:19 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)) 19:11:19 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)) 19:11:19 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)) 19:11:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:11:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:11:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:11:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:11:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:11:21 INFO - Downloading blessings-1.6.tar.gz 19:11:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:11:21 INFO - Installing collected packages: blessings 19:11:21 INFO - Running setup.py install for blessings 19:11:21 INFO - Successfully installed blessings 19:11:21 INFO - Cleaning up... 19:11:21 INFO - Return code: 0 19:11:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:11:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:11:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:11:21 INFO - Reading from file tmpfile_stdout 19:11:21 INFO - Current package versions: 19:11:21 INFO - blessings == 1.6 19:11:21 INFO - blobuploader == 1.2.4 19:11:21 INFO - docopt == 0.6.1 19:11:21 INFO - functools32 == 3.2.3-2 19:11:21 INFO - jsonschema == 2.5.1 19:11:21 INFO - manifestparser == 1.1 19:11:21 INFO - mozInstall == 1.13 19:11:21 INFO - mozcrash == 1.0 19:11:21 INFO - mozdebug == 0.1 19:11:21 INFO - mozdevice == 0.51 19:11:21 INFO - mozfile == 1.2 19:11:21 INFO - mozhttpd == 0.7 19:11:21 INFO - mozinfo == 0.10 19:11:21 INFO - mozleak == 0.1 19:11:21 INFO - mozlog == 3.4 19:11:21 INFO - moznetwork == 0.27 19:11:21 INFO - mozprocess == 0.25 19:11:21 INFO - mozprofile == 0.28 19:11:21 INFO - mozrunner == 6.13 19:11:21 INFO - mozscreenshot == 0.1 19:11:21 INFO - mozsystemmonitor == 0.3 19:11:21 INFO - moztest == 0.8 19:11:21 INFO - mozversion == 1.4 19:11:21 INFO - psutil == 3.1.1 19:11:21 INFO - requests == 1.2.3 19:11:21 INFO - wsgiref == 0.1.2 19:11:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:11:21 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')]} 19:11:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11:21 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')]} 19:11:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x103b32c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b36768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1105791c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103888d30>, '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 0x103852cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103879be8>, '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.6lsYYTdvWw/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.RzRUTHqgOB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 19:11:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 19:11:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:11:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6lsYYTdvWw/Render', 19:11:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:11:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:11:21 INFO - 'HOME': '/Users/cltbld', 19:11:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:11:21 INFO - 'LOGNAME': 'cltbld', 19:11:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:21 INFO - 'MOZ_NO_REMOTE': '1', 19:11:21 INFO - 'NO_EM_RESTART': '1', 19:11:21 INFO - 'PAGER': '/bin/cat', 19:11:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:11:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:11:21 INFO - 'PWD': '/builds/slave/test', 19:11:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:11:21 INFO - 'SHELL': '/bin/bash', 19:11:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RzRUTHqgOB/Listeners', 19:11:21 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 19:11:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:11:21 INFO - 'USER': 'cltbld', 19:11:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:11:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:11:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:11:21 INFO - 'XPC_FLAGS': '0x0', 19:11:21 INFO - 'XPC_SERVICE_NAME': '0', 19:11:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:11:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:22 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)) 19:11:22 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)) 19:11:22 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)) 19:11:22 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)) 19:11:22 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)) 19:11:22 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)) 19:11:22 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)) 19:11:22 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)) 19:11:22 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)) 19:11:22 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 19:11:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:11:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:11:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:11:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:11:23 INFO - Downloading marionette_driver-1.4.0.tar.gz 19:11:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 19:11:23 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)) 19:11:23 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)) 19:11:23 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)) 19:11:23 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)) 19:11:23 INFO - Installing collected packages: marionette-driver 19:11:23 INFO - Running setup.py install for marionette-driver 19:11:23 INFO - Successfully installed marionette-driver 19:11:23 INFO - Cleaning up... 19:11:23 INFO - Return code: 0 19:11:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:11:23 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')]} 19:11:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11:23 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')]} 19:11:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11:23 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 0x103b32c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b36768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1105791c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103888d30>, '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 0x103852cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103879be8>, '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.6lsYYTdvWw/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.RzRUTHqgOB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 19:11:23 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 19:11:23 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 19:11:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6lsYYTdvWw/Render', 19:11:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:11:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:11:23 INFO - 'HOME': '/Users/cltbld', 19:11:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:11:23 INFO - 'LOGNAME': 'cltbld', 19:11:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:23 INFO - 'MOZ_NO_REMOTE': '1', 19:11:23 INFO - 'NO_EM_RESTART': '1', 19:11:23 INFO - 'PAGER': '/bin/cat', 19:11:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:11:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:11:23 INFO - 'PWD': '/builds/slave/test', 19:11:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:11:23 INFO - 'SHELL': '/bin/bash', 19:11:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RzRUTHqgOB/Listeners', 19:11:23 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 19:11:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:11:23 INFO - 'USER': 'cltbld', 19:11:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:11:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:11:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:11:23 INFO - 'XPC_FLAGS': '0x0', 19:11:23 INFO - 'XPC_SERVICE_NAME': '0', 19:11:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:11:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:11:24 INFO - Cleaning up... 19:11:24 INFO - Return code: 0 19:11:24 INFO - Running post-action listener: _resource_record_post_action 19:11:24 INFO - Running post-action listener: _start_resource_monitoring 19:11:24 INFO - Starting resource monitoring. 19:11:24 INFO - [mozharness: 2017-06-13 02:11:24.234030Z] Finished create-virtualenv step (success) 19:11:24 INFO - [mozharness: 2017-06-13 02:11:24.235004Z] Running install step. 19:11:24 INFO - Running pre-action listener: _resource_record_pre_action 19:11:24 INFO - Running main action method: install 19:11:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:11:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:11:24 INFO - Reading from file tmpfile_stdout 19:11:24 INFO - Detecting whether we're running mozinstall >=1.0... 19:11:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:11:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:11:24 INFO - Reading from file tmpfile_stdout 19:11:24 INFO - Output received: 19:11:24 INFO - Usage: mozinstall [options] installer 19:11:24 INFO - Options: 19:11:24 INFO - -h, --help show this help message and exit 19:11:24 INFO - -d DEST, --destination=DEST 19:11:24 INFO - Directory to install application into. [default: 19:11:24 INFO - "/builds/slave/test"] 19:11:24 INFO - --app=APP Application being installed. [default: firefox] 19:11:24 INFO - mkdir: /builds/slave/test/build/application 19:11:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:11:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0.en-US.mac.dmg --destination /builds/slave/test/build/application 19:11:46 INFO - Reading from file tmpfile_stdout 19:11:46 INFO - Output received: 19:11:46 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 19:11:46 INFO - Running post-action listener: _resource_record_post_action 19:11:46 INFO - [mozharness: 2017-06-13 02:11:46.289964Z] Finished install step (success) 19:11:46 INFO - [mozharness: 2017-06-13 02:11:46.290111Z] Running setup-mitmproxy step. 19:11:46 INFO - Running pre-action listener: _resource_record_pre_action 19:11:46 INFO - Running main action method: setup_mitmproxy 19:11:46 INFO - Skipping: mitmproxy is not required 19:11:46 INFO - Running post-action listener: _resource_record_post_action 19:11:46 INFO - [mozharness: 2017-06-13 02:11:46.290623Z] Finished setup-mitmproxy step (success) 19:11:46 INFO - [mozharness: 2017-06-13 02:11:46.290742Z] Running run-tests step. 19:11:46 INFO - Running pre-action listener: _resource_record_pre_action 19:11:46 INFO - Running main action method: run_tests 19:11:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:11:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:11:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:11:46 INFO - 2017-06-12 19:11:46.305 screenresolution[1630:8331] starting screenresolution argv=screenresolution get 19:11:46 INFO - 2017-06-12 19:11:46.307 screenresolution[1630:8331] Display 0: 1600x1200x32@60 19:11:46 INFO - 2017-06-12 19:11:46.313 screenresolution[1631:8335] starting screenresolution argv=screenresolution list 19:11:46 INFO - Available Modes on Display 0 19:11:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:11:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:11:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:11:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:11:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:11:46 INFO - Intel Iris: 19:11:46 INFO - Chipset Model: Intel Iris 19:11:46 INFO - Type: GPU 19:11:46 INFO - Bus: Built-In 19:11:46 INFO - VRAM (Dynamic, Max): 1536 MB 19:11:46 INFO - Vendor: Intel (0x8086) 19:11:46 INFO - Device ID: 0x0a2e 19:11:46 INFO - Revision ID: 0x0009 19:11:46 INFO - Displays: 19:11:46 INFO - Display: 19:11:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:11:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:11:46 INFO - Display Serial Number: 1600x1200 60 19:11:46 INFO - Main Display: Yes 19:11:46 INFO - Mirror: Off 19:11:46 INFO - Online: Yes 19:11:46 INFO - Rotation: Supported 19:11:46 INFO - Return code: 0 19:11:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:11:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:11:46 INFO - Python 2.7.3 19:11:46 INFO - Return code: 0 19:11:46 INFO - grabbing minidump binary from tooltool 19:11: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')]} 19:11:46 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 0x103888d30>, '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 0x103852cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103879be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:11:46 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 19:11:46 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 19:11:46 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 19:11:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:11:46 INFO - Return code: 0 19:11:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:11:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:11:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:11:46 INFO - ENV: RUST_BACKTRACE is now 1 19:11:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:11:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:11:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VbFe07bERzWfuxK3-eOZiQ/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0001', '--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 19:11:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VbFe07bERzWfuxK3-eOZiQ/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0001 --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 19:11:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6lsYYTdvWw/Render', 19:11:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:11:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:11:46 INFO - 'HOME': '/Users/cltbld', 19:11:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:11:46 INFO - 'LOGNAME': 'cltbld', 19:11:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:11:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:11:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:46 INFO - 'MOZ_NO_REMOTE': '1', 19:11:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:11:46 INFO - 'NO_EM_RESTART': '1', 19:11:46 INFO - 'PAGER': '/bin/cat', 19:11:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:11:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:11:46 INFO - 'PWD': '/builds/slave/test', 19:11:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:11:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:11:46 INFO - 'RUST_BACKTRACE': '1', 19:11:46 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 19:11:46 INFO - 'SHELL': '/bin/bash', 19:11:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RzRUTHqgOB/Listeners', 19:11:46 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 19:11:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:11:46 INFO - 'USER': 'cltbld', 19:11:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:11:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:11:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:11:46 INFO - 'XPC_FLAGS': '0x0', 19:11:46 INFO - 'XPC_SERVICE_NAME': '0', 19:11:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:11:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VbFe07bERzWfuxK3-eOZiQ/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0001', '--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 19:11:46 INFO - mozversion application_buildid: 20170612173634 19:11:46 INFO - mozversion application_changeset: 496fd4521a947593a9e46daca2bc42bd73ea49af 19:11:46 INFO - mozversion application_display_name: FirefoxDeveloperEdition 19:11:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:11:46 INFO - mozversion application_name: Firefox 19:11:46 INFO - mozversion application_remotingname: firefox-dev 19:11:46 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 19:11:46 INFO - mozversion application_vendor: Mozilla 19:11:46 INFO - mozversion application_version: 55.0 19:11:46 INFO - mozversion platform_buildid: 20170612173634 19:11:46 INFO - mozversion platform_changeset: 496fd4521a947593a9e46daca2bc42bd73ea49af 19:11:46 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 19:11:46 INFO - mozversion platform_version: 55.0 19:11:46 INFO - using testdate: 1497319906 19:11:46 INFO - actual date: 1497319906 19:11:46 INFO - starting webserver on 'localhost:49221' 19:11:46 INFO - SUITE-START | Running 2 tests 19:11:46 INFO - TEST-START | dromaeo_css 19:11:46 INFO - operating with platform_type : mac_ 19:11:46 INFO - Initialising browser for dromaeo_css test... 19:11:46 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49221/getInfo.html -foreground -profile /var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/tmpuGqKFI/profile 19:11:47 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49221/getInfo.html -foreground) 19:12:20 INFO - TEST-INFO | 1641: killed by SIGTERM 19:12:20 INFO - Browser initialized. 19:12:20 INFO - Running cycle 1/1 for dromaeo_css test... 19:12:20 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/tmpuGqKFI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:12:22 INFO - PID 1650 | 2017-06-12 19:12:22.245 plugin-container[1651:8817] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 19:12:22 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:12:22 INFO - PID 1650 | 2017-06-12 19:12:22.245 plugin-container[1651:8817] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 19:12:22 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:12:22 INFO - PID 1650 | 2017-06-12 19:12:22.245 plugin-container[1651:8817] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:12:22 INFO - PID 1650 | 2017-06-12 19:12:22.983 plugin-container[1652:8861] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 19:12:22 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:12:22 INFO - PID 1650 | 2017-06-12 19:12:22.983 plugin-container[1652:8861] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 19:12:22 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:12:22 INFO - PID 1650 | 2017-06-12 19:12:22.983 plugin-container[1652:8861] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:12:32 INFO - PID 1650 | RSS: Main: 214794240 19:12:32 INFO - PID 1650 | 19:15:34 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49221/tests/dromaeo/cssquery-ext.html) 19:15:37 INFO - PID 1650 | RSS: Main: 232501248 19:15:37 INFO - PID 1650 | 19:18:39 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-ext.html (next: http://localhost:49221/tests/dromaeo/cssquery-jquery.html) 19:18:39 INFO - PID 1650 | RSS: Main: 239722496 19:18:39 INFO - PID 1650 | 19:21:41 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49221/tests/dromaeo/cssquery-mootools.html) 19:21:42 INFO - PID 1650 | RSS: Main: 242548736 19:21:42 INFO - PID 1650 | 19:24:43 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49221/tests/dromaeo/cssquery-prototype.html) 19:24:44 INFO - PID 1650 | RSS: Main: 247406592 19:24:44 INFO - PID 1650 | 19:27:47 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49221/tests/dromaeo/cssquery-yui.html) 19:27:47 INFO - PID 1650 | RSS: Main: 250109952 19:27:47 INFO - PID 1650 | 19:30:49 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-yui.html (next: http://localhost:49221/tests/dromaeo/cssquery-dojo.html) 19:30:49 INFO - PID 1650 | RSS: Main: 246501376 19:30:49 INFO - PID 1650 | 19:30:49 INFO - PID 1650 | __start_tp_report 19:30:49 INFO - PID 1650 | _x_x_mozilla_page_load 19:30:49 INFO - PID 1650 | _x_x_mozilla_page_load_details 19:30:49 INFO - PID 1650 | |i|pagename|runs| 19:30:49 INFO - PID 1650 | |0;dojo.html;3129.4523458394774;3513.3324668313003;3591.820408979552;3629.5644522657285;3650.3794354959655;6208.689565521723;6219.346968568292;6252.187215661969;6255.249370075591;6262.37376262373;5484.972575137119;6146.801373732124;6318.368163183679;6324.2727086385075;6325.082862984865;5507.531859791922;5513.8345849624575;5530.419305972875;5566.3598686151;5615.129654903497;5503.559715222793;5521.420250873661;5525.613207075507;5569;5592.5805564582615;5390.407055223942;5406.59450541207;5459.181122831607;5461.69960652164;5472.179173124044;4392.846250381253;5379.542738867201;5467.17992301154;5503.532199763025;5527;5359.330083739533;5452.754626041838;5462.262594549765;5468.234447177399;5468.835934921959;4741.928871066937;6083.574149809515;6235.781747638811;6253.312135665074;6281.87436251277;6564.638944858031;6666.699998500079;6669.799906002828;6756.729730810762;6762.357576030269;5489.39616642169;6453.257875344301;6806.829829254259;6897.586144831326;6914.204866440323;6349.0476428536085;6367.4906007519285;6367.936320636827;6405.743770249231;6408.58344207625;7004.474664400191;7014.473914456436;7031.824204394931;7047.612381318974;7054.576725396492;6450.967745161338;6560.704768285439;6563.803085907431;6566.4418524425;6668.899631560836;6754.3921047106;6772.69522871472;6779.35596118368;6833.3166683331265;6903.75836845718;6649.3018233085795;6781.050652908597;6781.525293229525;6805.489588280899;6811.1145551079035;6814.557053791487;6824.897626535606;6832.521723479408;6839.863202736017;6853.383195512428;4411.4926783420315;5380.816220431499;5425.728713564306;5431.1853222016225;5438.592105592017;4082.3468245080644;4682.578567928903;4728.361671174347;4795.256735206049;4846;6204.37956204385;6265.27949285838;6333.68331583419;6342.651154186564;6343.238811342669;5682.516986056232;6854.143232095988;6877.1747390312485;6886.552374095668;6889.483288753364;6189.907151392733;6356.364363563606;6372.522060845455;6402.167718196604;6413.6793160341795;6008.128821320884;6073.392660733891;6074.483668888106;6086.391360863879;6091;5856.707164641836;6847.965760171167;6927.480438967198;7084;7093;5718.857028574319;6787.694553744995;6787.694553744995;6836.589804611739;6931.064306318583;7015.333543313377;7068.646567671699;7098.254683258184;7098.503104782822;7099.893501597583;5676.460736230051;6777.915533514614;6813.216480104653;6822.727090916308;6839.487038472035;6934.4452443805385;6934.687939042856;6939.687714052779;6949.478789090939;6981.441484681111;6500.382463665944;6555.80332590023;6559.0817285579105;6734.696938637675;6738.7304507819135;6543.509236807354;6626.503012274003;6639.402453779183;6712.731490740316;6745.898811517733;6508.349165083454;6582.703778329891;6591.307912669101;6601.570897891622;6621.768238111643;6720.8319792005595;6730.966345168243;6743.2582415935185;6746.6626668665385;6753.763618273533;6324.494040476843;6371.076193951943;6371.87256254863;6398.776042838478;6413.230412350548 19:30:49 INFO - PID 1650 | |1;ext.html;747.5477336211592;810.7770363150137;827.6234313387409;844.923956843884;856.3363393370138;40315.395269070985;40753.184936301295;40757;40801.183976320455;40833.59166408339;40754.79622601883;40846.978825529375;41073.17853642925;41083.17833643325;41195.794021029855;7601.277878601538;7699.846003079935;7708.498947568418;7708.845823083535;7713.6914523419;7579.772606821776;7666.425018123635;7690.2694244046725;7718.729844455445;7723.884141737878;8245.587720613976;8462.78843028923;8493.108223636522;8494.74515764525;8496.405251632388;8472.78818029551;8530.104339044436;8533.14668533148;8533.317334613246;8546.188112129368;8225.341972642174;8267.793305167419;8295.419320647557;8296.585170741499;8316.41785075045;37878.86363409102;37896.431553526716;37908.43137352942;37980.24039519194;38133.23733525342;3584.874529391485;3598.064503229153;3614.114541937216;3614.4578313253223;3623.655752703489;30661;30935.535966960513;30943.535846962088;31115;31122.844385778153;2997.4005198960294;3086.4752991991413;3107.6115485564305;3142.025971948703;3173.8730450782173;216116;227942.7205727938;262013.68993154913;311137.44431277923;311775;39999.40000899989;40170.79914600467;49466.75266623644;49520.51438456852;50033.9993200134;52792;53032.204516932274;53039;53190.468095319324;53399.466005340226;15612.985155964825;15739.370425182864;15743.921280393524;15960.441384551485;16123.677526449404;12857.1000029997;12921.03092268065;12936.417861196269;12941.352932353344;12960.157589756636;14020.509282175075;14147.080439771382;14216.573502794932;14220.573382798533;14342.21117838508;5049.772760225798;5141.820036298712;5993.071073983452;6016.127661489059;6170.753169873245;1011.4234886114843;1016.9593216271414;1028.3932479836933;1076.8976947190004;1077.8329358949375;19602.803971960097;23503.14932366962;23531;24045.27864164043;24978.500429991298;843.3244266089254;917.2707697380598;1069.588208539718;1118.1613789657756;1175.6061719323952;13160.934195328962;13554.728905422033;15210;16747.078910659788;16937.745933811002;357444.63833042525;358652;361191;361711;362068;60809.695951519956;60981.08528372078;61150.69424652848;61168.69415652893;61450.38549614536;1484.4136566056347;1553.9766903496457;1569.3408768317333;1570.701566702323;1571.2143928035753;26495.867520662272;28984.275393115342;29664.851675741484;30964;31402.685973139975;41025;41169.794151029055;41353.379699303936;41463.7926810364;41677;312168.43915781187;312570;313073;313135;313382;30016.699833002265;30545.389092218476;30563.694363056973;30597.847010764803;36756.44865327076;37138.814305928296;42638.78680606577;43548;44530.777346113064;45737.77131114323;28885.711142888304;29025.12924612265;29692.703072968994;29852.253693657833;30973.69026309708;21282.148714051265;30592.388152236384;30644.693553064182;30933.535996959614;30948.69051309458;35648.465273021444;36816;37355.6264437363;37473.87578372653;37526.43710344292;28865.85567072151;29885.5517167247;30425.32660703594;30509.389812204077;30737.538936915516;23868.40328991789;24199.879000604884;24481.265562033168;24524.50950981006;24546.01815927343 19:30:49 INFO - PID 1650 | |2;jquery.html;522.8888611700137;527.8231792349562;530.2735252703797;534.3961323704215;545.4463719324884;19350.516237094078;22904.88547557262;23502.764972350273;23646.52706945862;23685.5262894742;22846.428839279026;23740;25966.350841228978;25977.610335844976;25985.610215846777;6436.098946147543;6557.147570815811;6569.047488114221;6577.934220657804;6578.243501997284;6518.380753828379;6592.472602191837;6592.736290548372;6602.933970660304;6603.504737144709;5088.974555127219;5853.941460585404;6247;6377.266614339341;6545;6210.037444196134;6214.720337584774;6222.595531290474;6269.655168965728;6269.780557680459;5730.82807515771;5742.081266997302;5745.195672605839;5748.16651585522;5783.681897495636;30186;30566.694333056606;30569;31144;31145.532817007763;2294.4263934016494;2302.26327575176;2304.9423764406024;2326.3951372643232;2329.6272596384497;19414.902925485425;19644.901775491173;20011.399658010283;20013.1994720211;20061.79938200614;1927.6433859735994;2203.2398822406244;2249.5950728868797;2278.4303924018996;2283.6003699352545;205307;209691;210556;210942.8358574638;211818;12908.935455322664;15438.614034649;15442.838002679633;15482.30329635169;15559.14424706652;20787;20929.89535052315;21072;21074.157033718788;21113.472163195736;10069;10358.223133264884;10552.366857988545;10586.417747023988;10692.305000174963;8166.714165004315;9935.254855885838;9952.900470995197;9978.501074946224;10041.995800420045;7905.802354941057;8949.149830766068;9314.86027709585;9371.297152713574;9390.671326503676;2611.8824652890667;2635.3543381871564;2650.787936965038;2655.2963464681875;2777.5694767311097;924.8578006162384;946.4841661294611;961.6153538584622;971.5482300730054;1002.6290272599185;19255;21315.040823162995;21327.253546125812;21594.352169434944;22183.11267549312;1007.7329507680471;1026.1175389165312;1048.7220886465093;1055.18223376882;1061.2518174686827;9755.609775609038;10850;10992.340459572451;11072.667819965414;11299.209055366046;568159;588963;604875;606622;607989;154392.22803886133;155639;156355.21822390816;157366;158954;1186.2763714134371;1188.7800756860008;1197.4910662968377;1209.1596340543238;1217.2331431198465;18822.52943676419;19213.903930480257;19544.24101830842;20051;20150.720023876675;67479.98780018206;67954;69354;71368.64315678595;73955.63022184855;87371;87638.56180719056;89346.55326723325;90003;91461;11849.703757406134;12063.276203427822;12170.878291217328;12183.695407614881;12212.450439730055;18609.720854186926;18628;18646;18679.719804202676;18700.438986830417;11080.722981925517;15133.319000645217;15180.468683596022;15308.540743777705;15534.533963981099;13231;13409.195448273134;13695;13978.650533736738;14104.365303561159;15703.450379236672;15828.28772705208;15846.92076539656;15872.682546348777;15939.963902346773;9903.504824758877;11969;11980.700482487659;12000.63998080059;12070.637880863587;14264.144151350953;14901.552953411416;14974.95175337716;15012.849871501581;15110.773338400153 19:30:49 INFO - PID 1650 | |3;mootools.html;1275.8724127587238;1458.329168582452;1461.5030889497568;1465.9560213193602;1477.5271912987844;13510.932445337772;14021.789673154884;14022.929885350584;14092.295385230736;14094.577162685111;13483.932580337108;13905.513307034256;13919.02566820323;13926.025178237527;13947.16317020977;2779.722027797214;2816.084772448962;2826.6749323827717;2827.7879159063154;2829.6604407471027;2769.5014897318474;2794.147784925597;2802.6636803583497;2808.5506318988964;2815.0147448393104;2613.751693589106;2706.323419145214;2707.241972247764;2715.0768738628863;2728.3315587681045;2324.59319619066;2739.260399692082;2761.130243973142;2792.0507027610506;2801.5097357962277;2783.7494625483805;2801.859907004662;2803.088996076263;2805.989843656282;2817.7323154300516;13371.933140334237;13686.794698079537;13686.931565342209;13709.588712338646;13721.931390343085;7524.473286869921;7769.339606133485;7821;7838.843223135563;7898.170692077364;4073.8981525461804;4389.758563279019;4696.365990591261;5032.119379108642;5033.597312215014;1410.4358256697403;1416.1644629668544;1416.2635429576665;1430.5922811998635;1440.5102265229768;19162.520936976547;19628.901855490774;20967.16131354744;21018.5796284075;21035.94820258981;6035.637861728223;6283.2460104787715;6724.6301453419555;6944.583325000516;7033.718651253893;14548.41806327735;14563.344649490798;14573.41706331756;14611.78082328766;14639.560813175622;6028.246469191351;7585.051868516435;7602.391808655294;7608.429367797437;7621.504602200839;6949.583025018515;7040.577565346096;7093.858122837514;7123.82190445243;7166.749163779243;5224.738763061831;5226.059309324358;5254.737263136828;5316.494932981361;5398.082326004589;1528.4726769264587;1552;1554.5491807375734;1570.7957965464416;1573.8504842039988;753.7060546386914;775.162824149928;779.1662920674937;783.8079670480769;807.9838403231903;5920.348761636216;6290.716917738713;6483.3192514786215;6606.372394622503;6875.140607424072;650.187265917603;682.5120039172016;712.5368510468134;715.9833037087349;721.6319676964786;4455.796934827578;4899.5590396863745;4938.481459446778;4954.752262386866;5231.293775340357;18261.178246978918;18623.161957711935;18679.906600467184;18855.340063097727;18891.14989825461;17920.641587168182;18530.980796056345;18773.1552080154;18805.435836924913;18805.717914231296;9610.279229057849;9634.421934683942;9805.705828825146;9858.753531161778;9970.999999999854;7809.3362064225175;7833.49082309648;7883.921160788319;7884.329831964347;8110.746914010891;16889.32442702278;17192;17532;17603.735943961106;17626.20682069284;14967.251637418303;16902.23939922682;17665.558361041076;18183.18175682124;18216.089195539906;9465.47939863314;10559.63041293551;11162.944185279022;11265.493052812806;11318.26431281964;12623.11638185355;12669.556565520163;12704.555340563036;12758;12807.743845123232;9960.452175130438;10139.138173255356;10250.077493025663;10526.947365263124;10556.524956377136;9854.753631159278;9950;10058.547365368722;10303.896961030594;10332.328398654063;6989.720411183495;7852.568108754073;9307.953460232655;9394.624215031323;9410;6804.82987925286;6978.302169782981;6981.127359080115;7212.2427145151105;7275.1997280298065;5599.272094627672;6981.476389270723;7144.857102858018;7191.3887319578425;7263.237360077116 19:30:49 INFO - PID 1650 | |4;prototype.html;385.86880460643374;433.48415385691044;447.0767864360096;474.49229324622644;474.75312837324594;5240.816571419996;5548.833534993946;6507.739690412378;6532.902006469901;6543.312952140022;5285.877015927583;5393.568514518839;5862.88274234515;6284.937150628492;6286.308506064341;3953.861614843481;4260.957390426102;4677.047894529117;4768.117898188838;4768.809247630091;4005.479287692596;4148.958510414903;4417.845375411861;4762.904741905159;4783.856484305476;4523.869032741814;4550.998780268353;4737.099951009314;4743.217369134089;4781.306710526971;4726.858194254178;4737.620990320766;4752.239641657318;4758.619310455155;4814.927776083362;4150.460440142792;4166.500019997589;4172.061286210609;4174.749515029108;4177.039280965395;4766;5773.191753154561;6176.598521096114;6217.626942383471;6223.595466294676;5146.922796158061;5641.435856414367;5657.547396208293;5667.931205110105;5673.432656734335;5554.611177217596;6374.024052543727;6395.848747225497;6442.93557064428;6866.690998905061;3962.5641179470235;4197.4753155855515;4388.517263101088;4810.13417584838;4838.588719958808;5885.205497257858;6371.076193951851;6647.468202543785;6859.691313890887;7264.891026634605;4276.2516559601945;4387.3857659927635;4800.66395352329;4840.34655321527;4909.828156014522;4377.621049369439;4960.627952903546;4974.27872958419;5000.424951130593;5008.398992120969;5401.297831281908;5692.14617807324;5699.344575373802;5701.173329867229;5722.54219662426;6299.338569450233;6779.423748981392;6896.275931017246;6897.862042759117;6922.550034247758;1571.2772124822482;1697.516207880748;1698.4989428118488;1708.3081352052438;1712.1952682239328;1025.7845852370936;1053.3258714422775;1114.1421105748527;1152.2222499812592;1156.306216270248;5747;6417.647029413333;6425.614463132227;6438.742450302029;6477.967610161919;5983.461488465972;6167;6919.6540172991145;7083.787486375417;7116.288371162946;5370.91943620846;5598.992181407386;5858.602010833706;6406.903896441557;6441.935580644134;2857.271395794101;3676.47426318075;3680.2823449427106;3683.134463401152;3686.3548878946613;5618.831435056954;6303.621782693053;7243.066558044001;7300.269973002763;7326.96336518314;6525.543211975116;6641.601503909781;6696.928491441346;7237.819054523574;7315.561686007568;7184.856302874018;7229.09636295463;7256.419486441072;7271.418286537064;7295.598742069131;5889.852753681193;6733.831654208684;7024.578525288499;7079.929200708133;7089.7873063808165;5528.944710552843;5886.911696324473;6861.176658800976;6892.931070689229;6896.586179848795;5746.511546518593;6650.634215118215;7262.3826974707745;7275;7293.671784769805;4569.794359253775;4781.282807578891;4795.304680821331;4798.5441383068555;4805.927911081267;5643.71781410936;6754.966225168842;6989.440844732511;7048.894266586108;7129.287071293037;5160.039185109635;6175;6639.203295604559;6785.491088168308;6821.727130914708;6784.151981002375;6796.96601517009;6817.147856517935;6836.658167091725;6851.2121106071445;6034.517238620812;6084.75262571163;6359.268684101203;6629.734810607714;6678.432333251728;6831.531220787555;6833;6876.449884009367;6880;6901.689423976033;5724.341700704473;5924.733386997509;6145.262568491809;6622.635755033327;6652.600843949185 19:30:49 INFO - PID 1650 | |5;yui.html;820.3478234803331;833.6498670558365;834.8330333933211;845.9890430935031;855.9101294364093;16770.496885093453;17109.059001754915;17253.13734313284;17327.960322380633;17371.565710857205;13567.525136620221;16807.655387568997;16894;18168.45494635163;18594.256229750794;869.7823047733161;873.8558137907238;961.6634178037701;964.3346091197069;976.4531862157214;941.9434833909987;988;1007.2948935744971;1008.2740426892625;1009.99000999001;921.0513171433435;953.6900507335109;1025.5538840604324;1031.9793604127951;1032.8140934631763;1024.0169437340162;1025.646152077532;1028.4754775064696;1030.9381437113723;1031.494567661848;742.3430264216207;743.1453828097677;749.3518106837546;749.7001199520181;751.8421131562378;16933.153342332833;16995.49013529596;17064.744028839577;17129.14354282281;17177.484675459757;17212.56968575783;17432.564185895328;17452.476425707122;17660.205290761947;17841.286348546044;13361.86424153948;14083.859161408358;14691.926540367229;14734.70530589393;14765.630859228499;3591.4792355108625;3608.9097772555897;3610.530631017977;3612.9638703613155;3619.927601447983;121244.39377803143;138967;144748;145070.27464862715;148902;13847.446102155802;13974.790378144337;14105.36525856339;14150.575482735534;14227.217503037433;25992.740072599034;27396.863015684794;27405;27651;27701.584476232874;8201.58992050407;8291.253787159185;8341.290990265896;8355.206255405727;8366.832663346699;4078.163976384834;4112.732672376286;4151.3772934059525;4154.023804405962;4155.563665815106;4057.4928133983253;4757.167495688241;4831.323614693945;4853.12643724126;4858.684185527929;1013.366645846346;1033.039273475675;1042.3329069395593;1047.8533005379254;1048.7745134796055;635.6059243269202;650.2619526836979;660.2506155513527;669.6618207805027;681.3697329969857;11182.496787644575;12016.459259333351;12112.39438028095;12131.5147394105;12234.265944043385;635.8696467224208;646.7606985415426;649.376598465469;677.8203775999364;685.5509641185032;5475.698836563947;6064.575479716377;6207.34822843604;6310.936890631035;6475.4819614430735;97092;136642;142252;148655;151505;107664;118954.21568676477;119481.80518194707;134900;136763;109642;111948;114407;118871;118945.40527297481;12281.263124212219;12537.310447925453;12669.74660506803;12715.872841271468;13119.606411807661;40042.39936400898;46064.76967615275;48982.510174899224;49226.01547969092;49406;82503.17496824954;83347.5832620833;87616.68574971508;90832.63751043871;92635.61046584172;15443.382264709286;16749.665006699553;17551.912240438716;17836;18043.81956180474;18840.152193151065;19127.33054343091;19220.231190752213;19508.31720889762;19654.21383144658;17590.208440619946;17590.38433654816;18269.908650456662;18379.356722514647;18575.978321192466;17335.56661083483;17820.732689009936;18023.639527209645;18235.088245587933;18370.81629183691;16786.58033549171;16897.070661113758;16961.745573816155;17360;17372.739408908623;11336.94331528337;11849.274666826506;12175.086868485076;12370.62888113358;12418.37908104573;11844.881551184379;12998.350082495648;13239.801402979156;13691.110077844909;14138.50515231962 19:30:49 INFO - PID 1650 | __end_tp_report 19:30:49 INFO - PID 1650 | __start_cc_report 19:30:49 INFO - PID 1650 | _x_x_mozilla_cycle_collect,402 19:30:49 INFO - PID 1650 | __end_cc_report 19:30:49 INFO - PID 1650 | __startTimestamp1497321049560__endTimestamp 19:30:49 INFO - PID 1650 | 19:30:49 INFO - PID 1650 | ------- Summary: start ------- 19:30:49 INFO - PID 1650 | Number of tests: 6 19:30:49 INFO - PID 1650 | 19:30:49 INFO - PID 1650 | [#0] dojo.html Cycles:165 Average:6203.85 Median:6408.58 stddev:803.02 (12.5%) stddev-sans-first:768.41 19:30:49 INFO - PID 1650 | Values: 3129.5 3513.3 3591.8 3629.6 3650.4 6208.7 6219.3 6252.2 6255.2 6262.4 5485.0 6146.8 6318.4 6324.3 6325.1 5507.5 5513.8 5530.4 5566.4 5615.1 5503.6 5521.4 5525.6 5569.0 5592.6 5390.4 5406.6 5459.2 5461.7 5472.2 4392.8 5379.5 5467.2 5503.5 5527.0 5359.3 5452.8 5462.3 5468.2 5468.8 4741.9 6083.6 6235.8 6253.3 6281.9 6564.6 6666.7 6669.8 6756.7 6762.4 5489.4 6453.3 6806.8 6897.6 6914.2 6349.0 6367.5 6367.9 6405.7 6408.6 7004.5 7014.5 7031.8 7047.6 7054.6 6451.0 6560.7 6563.8 6566.4 6668.9 6754.4 6772.7 6779.4 6833.3 6903.8 6649.3 6781.1 6781.5 6805.5 6811.1 6814.6 6824.9 6832.5 6839.9 6853.4 4411.5 5380.8 5425.7 5431.2 5438.6 4082.3 4682.6 4728.4 4795.3 4846.0 6204.4 6265.3 6333.7 6342.7 6343.2 5682.5 6854.1 6877.2 6886.6 6889.5 6189.9 6356.4 6372.5 6402.2 6413.7 6008.1 6073.4 6074.5 6086.4 6091.0 5856.7 6848.0 6927.5 7084.0 7093.0 5718.9 6787.7 6787.7 6836.6 6931.1 7015.3 7068.6 7098.3 7098.5 7099.9 5676.5 6777.9 6813.2 6822.7 6839.5 6934.4 6934.7 6939.7 6949.5 6981.4 6500.4 6555.8 6559.1 6734.7 6738.7 6543.5 6626.5 6639.4 6712.7 6745.9 6508.3 6582.7 6591.3 6601.6 6621.8 6720.8 6731.0 6743.3 6746.7 6753.8 6324.5 6371.1 6371.9 6398.8 6413.2 19:30:49 INFO - PID 1650 | 19:30:49 INFO - PID 1650 | [#1] ext.html Cycles:180 Average:46759.26 Median:181125.03 stddev:83131.59 (45.9%) stddev-sans-first:83293.01 19:30:49 INFO - PID 1650 | Values: 747.5 810.8 827.6 844.9 856.3 40315.4 40753.2 40757.0 40801.2 40833.6 40754.8 40847.0 41073.2 41083.2 41195.8 7601.3 7699.8 7708.5 7708.8 7713.7 7579.8 7666.4 7690.3 7718.7 7723.9 8245.6 8462.8 8493.1 8494.7 8496.4 8472.8 8530.1 8533.1 8533.3 8546.2 8225.3 8267.8 8295.4 8296.6 8316.4 37878.9 37896.4 37908.4 37980.2 38133.2 3584.9 3598.1 3614.1 3614.5 3623.7 30661.0 30935.5 30943.5 31115.0 31122.8 2997.4 3086.5 3107.6 3142.0 3173.9 216116.0 227942.7 262013.7 311137.4 311775.0 39999.4 40170.8 49466.8 49520.5 50034.0 52792.0 53032.2 53039.0 53190.5 53399.5 15613.0 15739.4 15743.9 15960.4 16123.7 12857.1 12921.0 12936.4 12941.4 12960.2 14020.5 14147.1 14216.6 14220.6 14342.2 5049.8 5141.8 5993.1 6016.1 6170.8 1011.4 1017.0 1028.4 1076.9 1077.8 19602.8 23503.1 23531.0 24045.3 24978.5 843.3 917.3 1069.6 1118.2 1175.6 13160.9 13554.7 15210.0 16747.1 16937.7 357444.6 358652.0 361191.0 361711.0 362068.0 60809.7 60981.1 61150.7 61168.7 61450.4 1484.4 1554.0 1569.3 1570.7 1571.2 26495.9 28984.3 29664.9 30964.0 31402.7 41025.0 41169.8 41353.4 41463.8 41677.0 312168.4 312570.0 313073.0 313135.0 313382.0 30016.7 30545.4 30563.7 30597.8 36756.4 37138.8 42638.8 43548.0 44530.8 45737.8 28885.7 29025.1 29692.7 29852.3 30973.7 21282.1 30592.4 30644.7 30933.5 30948.7 35648.5 36816.0 37355.6 37473.9 37526.4 28865.9 29885.6 30425.3 30509.4 30737.5 23868.4 24199.9 24481.3 24524.5 24546.0 19:30:49 INFO - PID 1650 | 19:30:49 INFO - PID 1650 | [#2] jquery.html Cycles:180 Average:41068.78 Median:11898.80 stddev:103208.05 (867.4%) stddev-sans-first:103452.68 19:30:49 INFO - PID 1650 | Values: 522.9 527.8 530.3 534.4 545.4 19350.5 22904.9 23502.8 23646.5 23685.5 22846.4 23740.0 25966.4 25977.6 25985.6 6436.1 6557.1 6569.0 6577.9 6578.2 6518.4 6592.5 6592.7 6602.9 6603.5 5089.0 5853.9 6247.0 6377.3 6545.0 6210.0 6214.7 6222.6 6269.7 6269.8 5730.8 5742.1 5745.2 5748.2 5783.7 30186.0 30566.7 30569.0 31144.0 31145.5 2294.4 2302.3 2304.9 2326.4 2329.6 19414.9 19644.9 20011.4 20013.2 20061.8 1927.6 2203.2 2249.6 2278.4 2283.6 205307.0 209691.0 210556.0 210942.8 211818.0 12908.9 15438.6 15442.8 15482.3 15559.1 20787.0 20929.9 21072.0 21074.2 21113.5 10069.0 10358.2 10552.4 10586.4 10692.3 8166.7 9935.3 9952.9 9978.5 10042.0 7905.8 8949.1 9314.9 9371.3 9390.7 2611.9 2635.4 2650.8 2655.3 2777.6 924.9 946.5 961.6 971.5 1002.6 19255.0 21315.0 21327.3 21594.4 22183.1 1007.7 1026.1 1048.7 1055.2 1061.3 9755.6 10850.0 10992.3 11072.7 11299.2 568159.0 588963.0 604875.0 606622.0 607989.0 154392.2 155639.0 156355.2 157366.0 158954.0 1186.3 1188.8 1197.5 1209.2 1217.2 18822.5 19213.9 19544.2 20051.0 20150.7 67480.0 67954.0 69354.0 71368.6 73955.6 87371.0 87638.6 89346.6 90003.0 91461.0 11849.7 12063.3 12170.9 12183.7 12212.5 18609.7 18628.0 18646.0 18679.7 18700.4 11080.7 15133.3 15180.5 15308.5 15534.5 13231.0 13409.2 13695.0 13978.7 14104.4 15703.5 15828.3 15846.9 15872.7 15940.0 9903.5 11969.0 11980.7 12000.6 12070.6 14264.1 14901.6 14975.0 15012.8 15110.8 19:30:49 INFO - PID 1650 | 19:30:49 INFO - PID 1650 | [#3] mootools.html Cycles:180 Average:8384.52 Median:2802.26 stddev:5655.63 (201.8%) stddev-sans-first:5646.28 19:30:49 INFO - PID 1650 | Values: 1275.9 1458.3 1461.5 1466.0 1477.5 13510.9 14021.8 14022.9 14092.3 14094.6 13483.9 13905.5 13919.0 13926.0 13947.2 2779.7 2816.1 2826.7 2827.8 2829.7 2769.5 2794.1 2802.7 2808.6 2815.0 2613.8 2706.3 2707.2 2715.1 2728.3 2324.6 2739.3 2761.1 2792.1 2801.5 2783.7 2801.9 2803.1 2806.0 2817.7 13371.9 13686.8 13686.9 13709.6 13721.9 7524.5 7769.3 7821.0 7838.8 7898.2 4073.9 4389.8 4696.4 5032.1 5033.6 1410.4 1416.2 1416.3 1430.6 1440.5 19162.5 19628.9 20967.2 21018.6 21035.9 6035.6 6283.2 6724.6 6944.6 7033.7 14548.4 14563.3 14573.4 14611.8 14639.6 6028.2 7585.1 7602.4 7608.4 7621.5 6949.6 7040.6 7093.9 7123.8 7166.7 5224.7 5226.1 5254.7 5316.5 5398.1 1528.5 1552.0 1554.5 1570.8 1573.9 753.7 775.2 779.2 783.8 808.0 5920.3 6290.7 6483.3 6606.4 6875.1 650.2 682.5 712.5 716.0 721.6 4455.8 4899.6 4938.5 4954.8 5231.3 18261.2 18623.2 18679.9 18855.3 18891.1 17920.6 18531.0 18773.2 18805.4 18805.7 9610.3 9634.4 9805.7 9858.8 9971.0 7809.3 7833.5 7883.9 7884.3 8110.7 16889.3 17192.0 17532.0 17603.7 17626.2 14967.3 16902.2 17665.6 18183.2 18216.1 9465.5 10559.6 11162.9 11265.5 11318.3 12623.1 12669.6 12704.6 12758.0 12807.7 9960.5 10139.1 10250.1 10526.9 10556.5 9854.8 9950.0 10058.5 10303.9 10332.3 6989.7 7852.6 9308.0 9394.6 9410.0 6804.8 6978.3 6981.1 7212.2 7275.2 5599.3 6981.5 7144.9 7191.4 7263.2 19:30:49 INFO - PID 1650 | 19:30:49 INFO - PID 1650 | [#4] prototype.html Cycles:180 Average:5434.56 Median:5815.90 stddev:1672.43 (28.8%) stddev-sans-first:1633.63 19:30:49 INFO - PID 1650 | Values: 385.9 433.5 447.1 474.5 474.8 5240.8 5548.8 6507.7 6532.9 6543.3 5285.9 5393.6 5862.9 6284.9 6286.3 3953.9 4261.0 4677.0 4768.1 4768.8 4005.5 4149.0 4417.8 4762.9 4783.9 4523.9 4551.0 4737.1 4743.2 4781.3 4726.9 4737.6 4752.2 4758.6 4814.9 4150.5 4166.5 4172.1 4174.7 4177.0 4766.0 5773.2 6176.6 6217.6 6223.6 5146.9 5641.4 5657.5 5667.9 5673.4 5554.6 6374.0 6395.8 6442.9 6866.7 3962.6 4197.5 4388.5 4810.1 4838.6 5885.2 6371.1 6647.5 6859.7 7264.9 4276.3 4387.4 4800.7 4840.3 4909.8 4377.6 4960.6 4974.3 5000.4 5008.4 5401.3 5692.1 5699.3 5701.2 5722.5 6299.3 6779.4 6896.3 6897.9 6922.6 1571.3 1697.5 1698.5 1708.3 1712.2 1025.8 1053.3 1114.1 1152.2 1156.3 5747.0 6417.6 6425.6 6438.7 6478.0 5983.5 6167.0 6919.7 7083.8 7116.3 5370.9 5599.0 5858.6 6406.9 6441.9 2857.3 3676.5 3680.3 3683.1 3686.4 5618.8 6303.6 7243.1 7300.3 7327.0 6525.5 6641.6 6696.9 7237.8 7315.6 7184.9 7229.1 7256.4 7271.4 7295.6 5889.9 6733.8 7024.6 7079.9 7089.8 5528.9 5886.9 6861.2 6892.9 6896.6 5746.5 6650.6 7262.4 7275.0 7293.7 4569.8 4781.3 4795.3 4798.5 4805.9 5643.7 6755.0 6989.4 7048.9 7129.3 5160.0 6175.0 6639.2 6785.5 6821.7 6784.2 6797.0 6817.1 6836.7 6851.2 6034.5 6084.8 6359.3 6629.7 6678.4 6831.5 6833.0 6876.4 6880.0 6901.7 5724.3 5924.7 6145.3 6622.6 6652.6 19:30:49 INFO - PID 1650 | 19:30:49 INFO - PID 1650 | [#5] yui.html Cycles:180 Average:26268.50 Median:17590.30 stddev:39764.95 (226.1%) stddev-sans-first:39830.59 19:30:49 INFO - PID 1650 | Values: 820.3 833.6 834.8 846.0 855.9 16770.5 17109.1 17253.1 17328.0 17371.6 13567.5 16807.7 16894.0 18168.5 18594.3 869.8 873.9 961.7 964.3 976.5 941.9 988.0 1007.3 1008.3 1010.0 921.1 953.7 1025.6 1032.0 1032.8 1024.0 1025.6 1028.5 1030.9 1031.5 742.3 743.1 749.4 749.7 751.8 16933.2 16995.5 17064.7 17129.1 17177.5 17212.6 17432.6 17452.5 17660.2 17841.3 13361.9 14083.9 14691.9 14734.7 14765.6 3591.5 3608.9 3610.5 3613.0 3619.9 121244.4 138967.0 144748.0 145070.3 148902.0 13847.4 13974.8 14105.4 14150.6 14227.2 25992.7 27396.9 27405.0 27651.0 27701.6 8201.6 8291.3 8341.3 8355.2 8366.8 4078.2 4112.7 4151.4 4154.0 4155.6 4057.5 4757.2 4831.3 4853.1 4858.7 1013.4 1033.0 1042.3 1047.9 1048.8 635.6 650.3 660.3 669.7 681.4 11182.5 12016.5 12112.4 12131.5 12234.3 635.9 646.8 649.4 677.8 685.6 5475.7 6064.6 6207.3 6310.9 6475.5 97092.0 136642.0 142252.0 148655.0 151505.0 107664.0 118954.2 119481.8 134900.0 136763.0 109642.0 111948.0 114407.0 118871.0 118945.4 12281.3 12537.3 12669.7 12715.9 13119.6 40042.4 46064.8 48982.5 49226.0 49406.0 82503.2 83347.6 87616.7 90832.6 92635.6 15443.4 16749.7 17551.9 17836.0 18043.8 18840.2 19127.3 19220.2 19508.3 19654.2 17590.2 17590.4 18269.9 18379.4 18576.0 17335.6 17820.7 18023.6 18235.1 18370.8 16786.6 16897.1 16961.7 17360.0 17372.7 11336.9 11849.3 12175.1 12370.6 12418.4 11844.9 12998.4 13239.8 13691.1 14138.5 19:30:49 INFO - PID 1650 | -------- Summary: end -------- 19:30:49 INFO - PID 1650 | 19:30:50 INFO - TEST-INFO | 1650: exit 0 19:30:50 INFO - TEST-OK | dromaeo_css | took 1143233ms 19:30:50 INFO - TEST-START | kraken 19:30:50 INFO - operating with platform_type : mac_ 19:30:50 INFO - Initialising browser for kraken test... 19:30:50 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49221/getInfo.html -foreground -profile /var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/tmpc1KqFK/profile 19:30:50 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49221/getInfo.html -foreground) 19:31:23 INFO - TEST-INFO | 1663: killed by SIGTERM 19:31:23 INFO - Browser initialized. 19:31:23 INFO - Running cycle 1/1 for kraken test... 19:31:23 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/tmpc1KqFK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:31:25 INFO - PID 1667 | 2017-06-12 19:31:25.199 plugin-container[1668:11418] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4843, name = 'com.apple.tsm.portname' 19:31:25 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:31:25 INFO - PID 1667 | 2017-06-12 19:31:25.200 plugin-container[1668:11418] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 19:31:25 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:31:25 INFO - PID 1667 | 2017-06-12 19:31:25.200 plugin-container[1668:11418] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:31:25 INFO - PID 1667 | 2017-06-12 19:31:25.968 plugin-container[1669:11461] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 19:31:25 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:31:25 INFO - PID 1667 | 2017-06-12 19:31:25.969 plugin-container[1669:11461] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x961f, name = 'com.apple.CFPasteboardClient' 19:31:25 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:31:25 INFO - PID 1667 | 2017-06-12 19:31:25.969 plugin-container[1669:11461] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:31:35 INFO - PID 1667 | RSS: Main: 214822912 19:31:35 INFO - PID 1667 | 19:31:38 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49221/tests/kraken/driver.html?testName=audio-beat-detection) 19:31:39 INFO - PID 1667 | RSS: Main: 228831232 19:31:39 INFO - PID 1667 | 19:31:41 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49221/tests/kraken/driver.html?testName=audio-dft) 19:31:42 INFO - PID 1667 | RSS: Main: 240377856 19:31:42 INFO - PID 1667 | 19:31:45 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49221/tests/kraken/driver.html?testName=audio-fft) 19:31:46 INFO - PID 1667 | RSS: Main: 244146176 19:31:46 INFO - PID 1667 | 19:31:48 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49221/tests/kraken/driver.html?testName=audio-oscillator) 19:31:48 INFO - PID 1667 | RSS: Main: 245350400 19:31:48 INFO - PID 1667 | 19:31:50 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49221/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:31:51 INFO - PID 1667 | RSS: Main: 245600256 19:31:51 INFO - PID 1667 | 19:31:54 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49221/tests/kraken/driver.html?testName=imaging-darkroom) 19:31:54 INFO - PID 1667 | RSS: Main: 250040320 19:31:54 INFO - PID 1667 | 19:31:58 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49221/tests/kraken/driver.html?testName=imaging-desaturate) 19:31:58 INFO - PID 1667 | RSS: Main: 250146816 19:31:58 INFO - PID 1667 | 19:32:02 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49221/tests/kraken/driver.html?testName=json-parse-financial) 19:32:02 INFO - PID 1667 | RSS: Main: 249946112 19:32:02 INFO - PID 1667 | 19:32:04 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49221/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:32:04 INFO - PID 1667 | RSS: Main: 250691584 19:32:04 INFO - PID 1667 | 19:32:06 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:32:07 INFO - PID 1667 | RSS: Main: 250200064 19:32:07 INFO - PID 1667 | 19:32:09 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:32:09 INFO - PID 1667 | RSS: Main: 250073088 19:32:09 INFO - PID 1667 | 19:32:12 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:32:13 INFO - PID 1667 | RSS: Main: 250429440 19:32:13 INFO - PID 1667 | 19:32:16 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:32:17 INFO - PID 1667 | RSS: Main: 250355712 19:32:17 INFO - PID 1667 | 19:32:18 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49221/tests/kraken/driver.html?testName=ai-astar) 19:32:19 INFO - PID 1667 | RSS: Main: 252190720 19:32:19 INFO - PID 1667 | 19:32:19 INFO - PID 1667 | __start_tp_report 19:32:19 INFO - PID 1667 | _x_x_mozilla_page_load 19:32:19 INFO - PID 1667 | _x_x_mozilla_page_load_details 19:32:19 INFO - PID 1667 | |i|pagename|runs| 19:32:19 INFO - PID 1667 | |0;ai-astar;120;172;110;162;113;114;113;119;164;118 19:32:19 INFO - PID 1667 | |1;audio-beat-detection;128;112;173;128;135;156;149;137;153;133 19:32:19 INFO - PID 1667 | |2;audio-dft;199;233;277;205;196;198;198;196;199;193 19:32:19 INFO - PID 1667 | |3;audio-fft;64;86;78;147;57;58;62;70;120;57 19:32:19 INFO - PID 1667 | |4;audio-oscillator;71;80;115;70;70;73;104;69;72;105 19:32:19 INFO - PID 1667 | |5;imaging-gaussian-blur;94;102;115;93;95;112;94;94;95;91 19:32:19 INFO - PID 1667 | |6;imaging-darkroom;134;97;115;152;96;125;98;97;93;157 19:32:19 INFO - PID 1667 | |7;imaging-desaturate;91;96;93;91;94;89;121;148;126;92 19:32:19 INFO - PID 1667 | |8;json-parse-financial;48;48;48;54;69;46;46;56;71;76 19:32:19 INFO - PID 1667 | |9;json-stringify-tinderbox;53;54;57;72;78;89;64;56;53;56 19:32:19 INFO - PID 1667 | |10;stanford-crypto-aes;82;79;79;80;82;82;94;104;71;94 19:32:19 INFO - PID 1667 | |11;stanford-crypto-ccm;133;135;145;129;134;136;134;138;148;172 19:32:19 INFO - PID 1667 | |12;stanford-crypto-pbkdf2;184;180;183;180;181;180;213;174;180;181 19:32:19 INFO - PID 1667 | |13;stanford-crypto-sha256-iterative;65;68;89;95;94;93;88;67;63;76 19:32:19 INFO - PID 1667 | __end_tp_report 19:32:19 INFO - PID 1667 | __start_cc_report 19:32:19 INFO - PID 1667 | _x_x_mozilla_cycle_collect,1029 19:32:19 INFO - PID 1667 | __end_cc_report 19:32:19 INFO - PID 1667 | __startTimestamp1497321139204__endTimestamp 19:32:19 INFO - PID 1667 | 19:32:19 INFO - PID 1667 | ------- Summary: start ------- 19:32:19 INFO - PID 1667 | Number of tests: 14 19:32:19 INFO - PID 1667 | 19:32:19 INFO - PID 1667 | [#0] ai-astar Cycles:10 Average:130.50 Median:119.50 stddev:24.81 (20.8%) stddev-sans-first:26.02 19:32:19 INFO - PID 1667 | Values: 120.0 172.0 110.0 162.0 113.0 114.0 113.0 119.0 164.0 118.0 19:32:19 INFO - PID 1667 | 19:32:19 INFO - PID 1667 | [#1] audio-beat-detection Cycles:10 Average:140.40 Median:143.00 stddev:17.48 (12.2%) stddev-sans-first:17.95 19:32:19 INFO - PID 1667 | Values: 128.0 112.0 173.0 128.0 135.0 156.0 149.0 137.0 153.0 133.0 19:32:19 INFO - PID 1667 | 19:32:19 INFO - PID 1667 | [#2] audio-dft Cycles:10 Average:209.40 Median:199.00 stddev:26.35 (13.2%) stddev-sans-first:27.68 19:32:19 INFO - PID 1667 | Values: 199.0 233.0 277.0 205.0 196.0 198.0 198.0 196.0 199.0 193.0 19:32:19 INFO - PID 1667 | 19:32:19 INFO - PID 1667 | [#3] audio-fft Cycles:10 Average:79.90 Median:63.00 stddev:30.46 (48.4%) stddev-sans-first:31.76 19:32:19 INFO - PID 1667 | Values: 64.0 86.0 78.0 147.0 57.0 58.0 62.0 70.0 120.0 57.0 19:32:19 INFO - PID 1667 | 19:32:19 INFO - PID 1667 | [#4] audio-oscillator Cycles:10 Average:82.90 Median:70.50 stddev:17.82 (25.3%) stddev-sans-first:18.37 19:32:19 INFO - PID 1667 | Values: 71.0 80.0 115.0 70.0 70.0 73.0 104.0 69.0 72.0 105.0 19:32:19 INFO - PID 1667 | 19:32:19 INFO - PID 1667 | [#5] imaging-gaussian-blur Cycles:10 Average:98.50 Median:94.00 stddev:8.42 (9.0%) stddev-sans-first:8.77 19:32:19 INFO - PID 1667 | Values: 94.0 102.0 115.0 93.0 95.0 112.0 94.0 94.0 95.0 91.0 19:32:19 INFO - PID 1667 | 19:32:19 INFO - PID 1667 | [#6] imaging-darkroom Cycles:10 Average:116.40 Median:94.50 stddev:24.40 (25.8%) stddev-sans-first:25.03 19:32:19 INFO - PID 1667 | Values: 134.0 97.0 115.0 152.0 96.0 125.0 98.0 97.0 93.0 157.0 19:32:19 INFO - PID 1667 | 19:32:19 INFO - PID 1667 | [#7] imaging-desaturate Cycles:10 Average:104.10 Median:91.50 stddev:20.28 (22.2%) stddev-sans-first:20.95 19:32:19 INFO - PID 1667 | Values: 91.0 96.0 93.0 91.0 94.0 89.0 121.0 148.0 126.0 92.0 19:32:19 INFO - PID 1667 | 19:32:19 INFO - PID 1667 | [#8] json-parse-financial Cycles:10 Average:56.20 Median:55.00 stddev:11.50 (20.9%) stddev-sans-first:11.81 19:32:19 INFO - PID 1667 | Values: 48.0 48.0 48.0 54.0 69.0 46.0 46.0 56.0 71.0 76.0 19:32:19 INFO - PID 1667 | 19:32:19 INFO - PID 1667 | [#9] json-stringify-tinderbox Cycles:10 Average:63.20 Median:60.50 stddev:12.46 (20.6%) stddev-sans-first:12.66 19:32:19 INFO - PID 1667 | Values: 53.0 54.0 57.0 72.0 78.0 89.0 64.0 56.0 53.0 56.0 19:32:19 INFO - PID 1667 | 19:32:19 INFO - PID 1667 | [#10] stanford-crypto-aes Cycles:10 Average:84.70 Median:82.00 stddev:9.67 (11.8%) stddev-sans-first:10.21 19:32:19 INFO - PID 1667 | Values: 82.0 79.0 79.0 80.0 82.0 82.0 94.0 104.0 71.0 94.0 19:32:19 INFO - PID 1667 | 19:32:19 INFO - PID 1667 | [#11] stanford-crypto-ccm Cycles:10 Average:140.40 Median:137.00 stddev:12.47 (9.1%) stddev-sans-first:12.93 19:32:19 INFO - PID 1667 | Values: 133.0 135.0 145.0 129.0 134.0 136.0 134.0 138.0 148.0 172.0 19:32:19 INFO - PID 1667 | 19:32:19 INFO - PID 1667 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.60 Median:181.00 stddev:10.66 (5.9%) stddev-sans-first:11.30 19:32:19 INFO - PID 1667 | Values: 184.0 180.0 183.0 180.0 181.0 180.0 213.0 174.0 180.0 181.0 19:32:19 INFO - PID 1667 | 19:32:19 INFO - PID 1667 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.80 Median:88.50 stddev:13.24 (15.0%) stddev-sans-first:12.91 19:32:19 INFO - PID 1667 | Values: 65.0 68.0 89.0 95.0 94.0 93.0 88.0 67.0 63.0 76.0 19:32:19 INFO - PID 1667 | -------- Summary: end -------- 19:32:19 INFO - PID 1667 | 19:32:20 INFO - TEST-INFO | 1667: exit 0 19:32:20 INFO - TEST-OK | kraken | took 90001ms 19:32:20 INFO - SUITE-END | took 1233s 19:32:20 INFO - Completed test suite (00:20:33) 19:32:20 INFO - javascript benchmark 19:32:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8407.339874724437, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3129.4523458394774, 3513.3324668313003, 3591.820408979552, 3629.5644522657285, 3650.3794354959655, 6208.689565521723, 6219.346968568292, 6252.187215661969, 6255.249370075591, 6262.37376262373, 5484.972575137119, 6146.801373732124, 6318.368163183679, 6324.2727086385075, 6325.082862984865, 5507.531859791922, 5513.8345849624575, 5530.419305972875, 5566.3598686151, 5615.129654903497, 5503.559715222793, 5521.420250873661, 5525.613207075507, 5569.0, 5592.5805564582615, 5390.407055223942, 5406.59450541207, 5459.181122831607, 5461.69960652164, 5472.179173124044, 4392.846250381253, 5379.542738867201, 5467.17992301154, 5503.532199763025, 5527.0, 5359.330083739533, 5452.754626041838, 5462.262594549765, 5468.234447177399, 5468.835934921959, 4741.928871066937, 6083.574149809515, 6235.781747638811, 6253.312135665074, 6281.87436251277, 6564.638944858031, 6666.699998500079, 6669.799906002828, 6756.729730810762, 6762.357576030269, 5489.39616642169, 6453.257875344301, 6806.829829254259, 6897.586144831326, 6914.204866440323, 6349.0476428536085, 6367.4906007519285, 6367.936320636827, 6405.743770249231, 6408.58344207625, 7004.474664400191, 7014.473914456436, 7031.824204394931, 7047.612381318974, 7054.576725396492, 6450.967745161338, 6560.704768285439, 6563.803085907431, 6566.4418524425, 6668.899631560836, 6754.3921047106, 6772.69522871472, 6779.35596118368, 6833.3166683331265, 6903.75836845718, 6649.3018233085795, 6781.050652908597, 6781.525293229525, 6805.489588280899, 6811.1145551079035, 6814.557053791487, 6824.897626535606, 6832.521723479408, 6839.863202736017, 6853.383195512428, 4411.4926783420315, 5380.816220431499, 5425.728713564306, 5431.1853222016225, 5438.592105592017, 4082.3468245080644, 4682.578567928903, 4728.361671174347, 4795.256735206049, 4846.0, 6204.37956204385, 6265.27949285838, 6333.68331583419, 6342.651154186564, 6343.238811342669, 5682.516986056232, 6854.143232095988, 6877.1747390312485, 6886.552374095668, 6889.483288753364, 6189.907151392733, 6356.364363563606, 6372.522060845455, 6402.167718196604, 6413.6793160341795, 6008.128821320884, 6073.392660733891, 6074.483668888106, 6086.391360863879, 6091.0, 5856.707164641836, 6847.965760171167, 6927.480438967198, 7084.0, 7093.0, 5718.857028574319, 6787.694553744995, 6787.694553744995, 6836.589804611739, 6931.064306318583, 7015.333543313377, 7068.646567671699, 7098.254683258184, 7098.503104782822, 7099.893501597583, 5676.460736230051, 6777.915533514614, 6813.216480104653, 6822.727090916308, 6839.487038472035, 6934.4452443805385, 6934.687939042856, 6939.687714052779, 6949.478789090939, 6981.441484681111, 6500.382463665944, 6555.80332590023, 6559.0817285579105, 6734.696938637675, 6738.7304507819135, 6543.509236807354, 6626.503012274003, 6639.402453779183, 6712.731490740316, 6745.898811517733, 6508.349165083454, 6582.703778329891, 6591.307912669101, 6601.570897891622, 6621.768238111643, 6720.8319792005595, 6730.966345168243, 6743.2582415935185, 6746.6626668665385, 6753.763618273533, 6324.494040476843, 6371.076193951943, 6371.87256254863, 6398.776042838478, 6413.230412350548], "value": 6148.3694578570385, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [747.5477336211592, 810.7770363150137, 827.6234313387409, 844.923956843884, 856.3363393370138, 40315.395269070985, 40753.184936301295, 40757.0, 40801.183976320455, 40833.59166408339, 40754.79622601883, 40846.978825529375, 41073.17853642925, 41083.17833643325, 41195.794021029855, 7601.277878601538, 7699.846003079935, 7708.498947568418, 7708.845823083535, 7713.6914523419, 7579.772606821776, 7666.425018123635, 7690.2694244046725, 7718.729844455445, 7723.884141737878, 8245.587720613976, 8462.78843028923, 8493.108223636522, 8494.74515764525, 8496.405251632388, 8472.78818029551, 8530.104339044436, 8533.14668533148, 8533.317334613246, 8546.188112129368, 8225.341972642174, 8267.793305167419, 8295.419320647557, 8296.585170741499, 8316.41785075045, 37878.86363409102, 37896.431553526716, 37908.43137352942, 37980.24039519194, 38133.23733525342, 3584.874529391485, 3598.064503229153, 3614.114541937216, 3614.4578313253223, 3623.655752703489, 30661.0, 30935.535966960513, 30943.535846962088, 31115.0, 31122.844385778153, 2997.4005198960294, 3086.4752991991413, 3107.6115485564305, 3142.025971948703, 3173.8730450782173, 216116.0, 227942.7205727938, 262013.68993154913, 311137.44431277923, 311775.0, 39999.40000899989, 40170.79914600467, 49466.75266623644, 49520.51438456852, 50033.9993200134, 52792.0, 53032.204516932274, 53039.0, 53190.468095319324, 53399.466005340226, 15612.985155964825, 15739.370425182864, 15743.921280393524, 15960.441384551485, 16123.677526449404, 12857.1000029997, 12921.03092268065, 12936.417861196269, 12941.352932353344, 12960.157589756636, 14020.509282175075, 14147.080439771382, 14216.573502794932, 14220.573382798533, 14342.21117838508, 5049.772760225798, 5141.820036298712, 5993.071073983452, 6016.127661489059, 6170.753169873245, 1011.4234886114843, 1016.9593216271414, 1028.3932479836933, 1076.8976947190004, 1077.8329358949375, 19602.803971960097, 23503.14932366962, 23531.0, 24045.27864164043, 24978.500429991298, 843.3244266089254, 917.2707697380598, 1069.588208539718, 1118.1613789657756, 1175.6061719323952, 13160.934195328962, 13554.728905422033, 15210.0, 16747.078910659788, 16937.745933811002, 357444.63833042525, 358652.0, 361191.0, 361711.0, 362068.0, 60809.695951519956, 60981.08528372078, 61150.69424652848, 61168.69415652893, 61450.38549614536, 1484.4136566056347, 1553.9766903496457, 1569.3408768317333, 1570.701566702323, 1571.2143928035753, 26495.867520662272, 28984.275393115342, 29664.851675741484, 30964.0, 31402.685973139975, 41025.0, 41169.794151029055, 41353.379699303936, 41463.7926810364, 41677.0, 312168.43915781187, 312570.0, 313073.0, 313135.0, 313382.0, 30016.699833002265, 30545.389092218476, 30563.694363056973, 30597.847010764803, 36756.44865327076, 37138.814305928296, 42638.78680606577, 43548.0, 44530.777346113064, 45737.77131114323, 28885.711142888304, 29025.12924612265, 29692.703072968994, 29852.253693657833, 30973.69026309708, 21282.148714051265, 30592.388152236384, 30644.693553064182, 30933.535996959614, 30948.69051309458, 35648.465273021444, 36816.0, 37355.6264437363, 37473.87578372653, 37526.43710344292, 28865.85567072151, 29885.5517167247, 30425.32660703594, 30509.389812204077, 30737.538936915516, 23868.40328991789, 24199.879000604884, 24481.265562033168, 24524.50950981006, 24546.01815927343], "value": 17797.07575704599, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [522.8888611700137, 527.8231792349562, 530.2735252703797, 534.3961323704215, 545.4463719324884, 19350.516237094078, 22904.88547557262, 23502.764972350273, 23646.52706945862, 23685.5262894742, 22846.428839279026, 23740.0, 25966.350841228978, 25977.610335844976, 25985.610215846777, 6436.098946147543, 6557.147570815811, 6569.047488114221, 6577.934220657804, 6578.243501997284, 6518.380753828379, 6592.472602191837, 6592.736290548372, 6602.933970660304, 6603.504737144709, 5088.974555127219, 5853.941460585404, 6247.0, 6377.266614339341, 6545.0, 6210.037444196134, 6214.720337584774, 6222.595531290474, 6269.655168965728, 6269.780557680459, 5730.82807515771, 5742.081266997302, 5745.195672605839, 5748.16651585522, 5783.681897495636, 30186.0, 30566.694333056606, 30569.0, 31144.0, 31145.532817007763, 2294.4263934016494, 2302.26327575176, 2304.9423764406024, 2326.3951372643232, 2329.6272596384497, 19414.902925485425, 19644.901775491173, 20011.399658010283, 20013.1994720211, 20061.79938200614, 1927.6433859735994, 2203.2398822406244, 2249.5950728868797, 2278.4303924018996, 2283.6003699352545, 205307.0, 209691.0, 210556.0, 210942.8358574638, 211818.0, 12908.935455322664, 15438.614034649, 15442.838002679633, 15482.30329635169, 15559.14424706652, 20787.0, 20929.89535052315, 21072.0, 21074.157033718788, 21113.472163195736, 10069.0, 10358.223133264884, 10552.366857988545, 10586.417747023988, 10692.305000174963, 8166.714165004315, 9935.254855885838, 9952.900470995197, 9978.501074946224, 10041.995800420045, 7905.802354941057, 8949.149830766068, 9314.86027709585, 9371.297152713574, 9390.671326503676, 2611.8824652890667, 2635.3543381871564, 2650.787936965038, 2655.2963464681875, 2777.5694767311097, 924.8578006162384, 946.4841661294611, 961.6153538584622, 971.5482300730054, 1002.6290272599185, 19255.0, 21315.040823162995, 21327.253546125812, 21594.352169434944, 22183.11267549312, 1007.7329507680471, 1026.1175389165312, 1048.7220886465093, 1055.18223376882, 1061.2518174686827, 9755.609775609038, 10850.0, 10992.340459572451, 11072.667819965414, 11299.209055366046, 568159.0, 588963.0, 604875.0, 606622.0, 607989.0, 154392.22803886133, 155639.0, 156355.21822390816, 157366.0, 158954.0, 1186.2763714134371, 1188.7800756860008, 1197.4910662968377, 1209.1596340543238, 1217.2331431198465, 18822.52943676419, 19213.903930480257, 19544.24101830842, 20051.0, 20150.720023876675, 67479.98780018206, 67954.0, 69354.0, 71368.64315678595, 73955.63022184855, 87371.0, 87638.56180719056, 89346.55326723325, 90003.0, 91461.0, 11849.703757406134, 12063.276203427822, 12170.878291217328, 12183.695407614881, 12212.450439730055, 18609.720854186926, 18628.0, 18646.0, 18679.719804202676, 18700.438986830417, 11080.722981925517, 15133.319000645217, 15180.468683596022, 15308.540743777705, 15534.533963981099, 13231.0, 13409.195448273134, 13695.0, 13978.650533736738, 14104.365303561159, 15703.450379236672, 15828.28772705208, 15846.92076539656, 15872.682546348777, 15939.963902346773, 9903.504824758877, 11969.0, 11980.700482487659, 12000.63998080059, 12070.637880863587, 14264.144151350953, 14901.552953411416, 14974.95175337716, 15012.849871501581, 15110.773338400153], "value": 11896.27760743876, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1275.8724127587238, 1458.329168582452, 1461.5030889497568, 1465.9560213193602, 1477.5271912987844, 13510.932445337772, 14021.789673154884, 14022.929885350584, 14092.295385230736, 14094.577162685111, 13483.932580337108, 13905.513307034256, 13919.02566820323, 13926.025178237527, 13947.16317020977, 2779.722027797214, 2816.084772448962, 2826.6749323827717, 2827.7879159063154, 2829.6604407471027, 2769.5014897318474, 2794.147784925597, 2802.6636803583497, 2808.5506318988964, 2815.0147448393104, 2613.751693589106, 2706.323419145214, 2707.241972247764, 2715.0768738628863, 2728.3315587681045, 2324.59319619066, 2739.260399692082, 2761.130243973142, 2792.0507027610506, 2801.5097357962277, 2783.7494625483805, 2801.859907004662, 2803.088996076263, 2805.989843656282, 2817.7323154300516, 13371.933140334237, 13686.794698079537, 13686.931565342209, 13709.588712338646, 13721.931390343085, 7524.473286869921, 7769.339606133485, 7821.0, 7838.843223135563, 7898.170692077364, 4073.8981525461804, 4389.758563279019, 4696.365990591261, 5032.119379108642, 5033.597312215014, 1410.4358256697403, 1416.1644629668544, 1416.2635429576665, 1430.5922811998635, 1440.5102265229768, 19162.520936976547, 19628.901855490774, 20967.16131354744, 21018.5796284075, 21035.94820258981, 6035.637861728223, 6283.2460104787715, 6724.6301453419555, 6944.583325000516, 7033.718651253893, 14548.41806327735, 14563.344649490798, 14573.41706331756, 14611.78082328766, 14639.560813175622, 6028.246469191351, 7585.051868516435, 7602.391808655294, 7608.429367797437, 7621.504602200839, 6949.583025018515, 7040.577565346096, 7093.858122837514, 7123.82190445243, 7166.749163779243, 5224.738763061831, 5226.059309324358, 5254.737263136828, 5316.494932981361, 5398.082326004589, 1528.4726769264587, 1552.0, 1554.5491807375734, 1570.7957965464416, 1573.8504842039988, 753.7060546386914, 775.162824149928, 779.1662920674937, 783.8079670480769, 807.9838403231903, 5920.348761636216, 6290.716917738713, 6483.3192514786215, 6606.372394622503, 6875.140607424072, 650.187265917603, 682.5120039172016, 712.5368510468134, 715.9833037087349, 721.6319676964786, 4455.796934827578, 4899.5590396863745, 4938.481459446778, 4954.752262386866, 5231.293775340357, 18261.178246978918, 18623.161957711935, 18679.906600467184, 18855.340063097727, 18891.14989825461, 17920.641587168182, 18530.980796056345, 18773.1552080154, 18805.435836924913, 18805.717914231296, 9610.279229057849, 9634.421934683942, 9805.705828825146, 9858.753531161778, 9970.999999999854, 7809.3362064225175, 7833.49082309648, 7883.921160788319, 7884.329831964347, 8110.746914010891, 16889.32442702278, 17192.0, 17532.0, 17603.735943961106, 17626.20682069284, 14967.251637418303, 16902.23939922682, 17665.558361041076, 18183.18175682124, 18216.089195539906, 9465.47939863314, 10559.63041293551, 11162.944185279022, 11265.493052812806, 11318.26431281964, 12623.11638185355, 12669.556565520163, 12704.555340563036, 12758.0, 12807.743845123232, 9960.452175130438, 10139.138173255356, 10250.077493025663, 10526.947365263124, 10556.524956377136, 9854.753631159278, 9950.0, 10058.547365368722, 10303.896961030594, 10332.328398654063, 6989.720411183495, 7852.568108754073, 9307.953460232655, 9394.624215031323, 9410.0, 6804.82987925286, 6978.302169782981, 6981.127359080115, 7212.2427145151105, 7275.1997280298065, 5599.272094627672, 6981.476389270723, 7144.857102858018, 7191.3887319578425, 7263.237360077116], "value": 6117.163605134323, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.86880460643374, 433.48415385691044, 447.0767864360096, 474.49229324622644, 474.75312837324594, 5240.816571419996, 5548.833534993946, 6507.739690412378, 6532.902006469901, 6543.312952140022, 5285.877015927583, 5393.568514518839, 5862.88274234515, 6284.937150628492, 6286.308506064341, 3953.861614843481, 4260.957390426102, 4677.047894529117, 4768.117898188838, 4768.809247630091, 4005.479287692596, 4148.958510414903, 4417.845375411861, 4762.904741905159, 4783.856484305476, 4523.869032741814, 4550.998780268353, 4737.099951009314, 4743.217369134089, 4781.306710526971, 4726.858194254178, 4737.620990320766, 4752.239641657318, 4758.619310455155, 4814.927776083362, 4150.460440142792, 4166.500019997589, 4172.061286210609, 4174.749515029108, 4177.039280965395, 4766.0, 5773.191753154561, 6176.598521096114, 6217.626942383471, 6223.595466294676, 5146.922796158061, 5641.435856414367, 5657.547396208293, 5667.931205110105, 5673.432656734335, 5554.611177217596, 6374.024052543727, 6395.848747225497, 6442.93557064428, 6866.690998905061, 3962.5641179470235, 4197.4753155855515, 4388.517263101088, 4810.13417584838, 4838.588719958808, 5885.205497257858, 6371.076193951851, 6647.468202543785, 6859.691313890887, 7264.891026634605, 4276.2516559601945, 4387.3857659927635, 4800.66395352329, 4840.34655321527, 4909.828156014522, 4377.621049369439, 4960.627952903546, 4974.27872958419, 5000.424951130593, 5008.398992120969, 5401.297831281908, 5692.14617807324, 5699.344575373802, 5701.173329867229, 5722.54219662426, 6299.338569450233, 6779.423748981392, 6896.275931017246, 6897.862042759117, 6922.550034247758, 1571.2772124822482, 1697.516207880748, 1698.4989428118488, 1708.3081352052438, 1712.1952682239328, 1025.7845852370936, 1053.3258714422775, 1114.1421105748527, 1152.2222499812592, 1156.306216270248, 5747.0, 6417.647029413333, 6425.614463132227, 6438.742450302029, 6477.967610161919, 5983.461488465972, 6167.0, 6919.6540172991145, 7083.787486375417, 7116.288371162946, 5370.91943620846, 5598.992181407386, 5858.602010833706, 6406.903896441557, 6441.935580644134, 2857.271395794101, 3676.47426318075, 3680.2823449427106, 3683.134463401152, 3686.3548878946613, 5618.831435056954, 6303.621782693053, 7243.066558044001, 7300.269973002763, 7326.96336518314, 6525.543211975116, 6641.601503909781, 6696.928491441346, 7237.819054523574, 7315.561686007568, 7184.856302874018, 7229.09636295463, 7256.419486441072, 7271.418286537064, 7295.598742069131, 5889.852753681193, 6733.831654208684, 7024.578525288499, 7079.929200708133, 7089.7873063808165, 5528.944710552843, 5886.911696324473, 6861.176658800976, 6892.931070689229, 6896.586179848795, 5746.511546518593, 6650.634215118215, 7262.3826974707745, 7275.0, 7293.671784769805, 4569.794359253775, 4781.282807578891, 4795.304680821331, 4798.5441383068555, 4805.927911081267, 5643.71781410936, 6754.966225168842, 6989.440844732511, 7048.894266586108, 7129.287071293037, 5160.039185109635, 6175.0, 6639.203295604559, 6785.491088168308, 6821.727130914708, 6784.151981002375, 6796.96601517009, 6817.147856517935, 6836.658167091725, 6851.2121106071445, 6034.517238620812, 6084.75262571163, 6359.268684101203, 6629.734810607714, 6678.432333251728, 6831.531220787555, 6833.0, 6876.449884009367, 6880.0, 6901.689423976033, 5724.341700704473, 5924.733386997509, 6145.262568491809, 6622.635755033327, 6652.600843949185], "value": 4933.752779732364, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [820.3478234803331, 833.6498670558365, 834.8330333933211, 845.9890430935031, 855.9101294364093, 16770.496885093453, 17109.059001754915, 17253.13734313284, 17327.960322380633, 17371.565710857205, 13567.525136620221, 16807.655387568997, 16894.0, 18168.45494635163, 18594.256229750794, 869.7823047733161, 873.8558137907238, 961.6634178037701, 964.3346091197069, 976.4531862157214, 941.9434833909987, 988.0, 1007.2948935744971, 1008.2740426892625, 1009.99000999001, 921.0513171433435, 953.6900507335109, 1025.5538840604324, 1031.9793604127951, 1032.8140934631763, 1024.0169437340162, 1025.646152077532, 1028.4754775064696, 1030.9381437113723, 1031.494567661848, 742.3430264216207, 743.1453828097677, 749.3518106837546, 749.7001199520181, 751.8421131562378, 16933.153342332833, 16995.49013529596, 17064.744028839577, 17129.14354282281, 17177.484675459757, 17212.56968575783, 17432.564185895328, 17452.476425707122, 17660.205290761947, 17841.286348546044, 13361.86424153948, 14083.859161408358, 14691.926540367229, 14734.70530589393, 14765.630859228499, 3591.4792355108625, 3608.9097772555897, 3610.530631017977, 3612.9638703613155, 3619.927601447983, 121244.39377803143, 138967.0, 144748.0, 145070.27464862715, 148902.0, 13847.446102155802, 13974.790378144337, 14105.36525856339, 14150.575482735534, 14227.217503037433, 25992.740072599034, 27396.863015684794, 27405.0, 27651.0, 27701.584476232874, 8201.58992050407, 8291.253787159185, 8341.290990265896, 8355.206255405727, 8366.832663346699, 4078.163976384834, 4112.732672376286, 4151.3772934059525, 4154.023804405962, 4155.563665815106, 4057.4928133983253, 4757.167495688241, 4831.323614693945, 4853.12643724126, 4858.684185527929, 1013.366645846346, 1033.039273475675, 1042.3329069395593, 1047.8533005379254, 1048.7745134796055, 635.6059243269202, 650.2619526836979, 660.2506155513527, 669.6618207805027, 681.3697329969857, 11182.496787644575, 12016.459259333351, 12112.39438028095, 12131.5147394105, 12234.265944043385, 635.8696467224208, 646.7606985415426, 649.376598465469, 677.8203775999364, 685.5509641185032, 5475.698836563947, 6064.575479716377, 6207.34822843604, 6310.936890631035, 6475.4819614430735, 97092.0, 136642.0, 142252.0, 148655.0, 151505.0, 107664.0, 118954.21568676477, 119481.80518194707, 134900.0, 136763.0, 109642.0, 111948.0, 114407.0, 118871.0, 118945.40527297481, 12281.263124212219, 12537.310447925453, 12669.74660506803, 12715.872841271468, 13119.606411807661, 40042.39936400898, 46064.76967615275, 48982.510174899224, 49226.01547969092, 49406.0, 82503.17496824954, 83347.5832620833, 87616.68574971508, 90832.63751043871, 92635.61046584172, 15443.382264709286, 16749.665006699553, 17551.912240438716, 17836.0, 18043.81956180474, 18840.152193151065, 19127.33054343091, 19220.231190752213, 19508.31720889762, 19654.21383144658, 17590.208440619946, 17590.38433654816, 18269.908650456662, 18379.356722514647, 18575.978321192466, 17335.56661083483, 17820.732689009936, 18023.639527209645, 18235.088245587933, 18370.81629183691, 16786.58033549171, 16897.070661113758, 16961.745573816155, 17360.0, 17372.739408908623, 11336.94331528337, 11849.274666826506, 12175.086868485076, 12370.62888113358, 12418.37908104573, 11844.881551184379, 12998.350082495648, 13239.801402979156, 13691.110077844909, 14138.50515231962], "value": 8988.257660353325, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1570.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 172.0, 110.0, 162.0, 113.0, 114.0, 113.0, 119.0, 164.0, 118.0], "value": 130.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 112.0, 173.0, 128.0, 135.0, 156.0, 149.0, 137.0, 153.0, 133.0], "value": 140.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 233.0, 277.0, 205.0, 196.0, 198.0, 198.0, 196.0, 199.0, 193.0], "value": 209.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 86.0, 78.0, 147.0, 57.0, 58.0, 62.0, 70.0, 120.0, 57.0], "value": 79.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 80.0, 115.0, 70.0, 70.0, 73.0, 104.0, 69.0, 72.0, 105.0], "value": 82.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 102.0, 115.0, 93.0, 95.0, 112.0, 94.0, 94.0, 95.0, 91.0], "value": 98.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 97.0, 115.0, 152.0, 96.0, 125.0, 98.0, 97.0, 93.0, 157.0], "value": 116.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 96.0, 93.0, 91.0, 94.0, 89.0, 121.0, 148.0, 126.0, 92.0], "value": 104.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 48.0, 48.0, 54.0, 69.0, 46.0, 46.0, 56.0, 71.0, 76.0], "value": 56.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 57.0, 72.0, 78.0, 89.0, 64.0, 56.0, 53.0, 56.0], "value": 63.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 79.0, 79.0, 80.0, 82.0, 82.0, 94.0, 104.0, 71.0, 94.0], "value": 84.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 135.0, 145.0, 129.0, 134.0, 136.0, 134.0, 138.0, 148.0, 172.0], "value": 140.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 180.0, 183.0, 180.0, 181.0, 180.0, 213.0, 174.0, 180.0, 181.0], "value": 183.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 89.0, 95.0, 94.0, 93.0, 88.0, 67.0, 63.0, 76.0], "value": 79.8, "unit": "score"}]}]} 19:32:20 INFO - Return code: 0 19:32:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:32:20 INFO - # TBPL SUCCESS # 19:32:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:32:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:32:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:32:20 INFO - 2017-06-12 19:32:20.581 screenresolution[1675:11861] starting screenresolution argv=screenresolution get 19:32:20 INFO - 2017-06-12 19:32:20.584 screenresolution[1675:11861] Display 0: 1600x1200x32@60 19:32:20 INFO - 2017-06-12 19:32:20.590 screenresolution[1676:11864] starting screenresolution argv=screenresolution list 19:32:20 INFO - Available Modes on Display 0 19:32:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:32:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:32:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:32:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:32:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:32:20 INFO - Intel Iris: 19:32:20 INFO - Chipset Model: Intel Iris 19:32:20 INFO - Type: GPU 19:32:20 INFO - Bus: Built-In 19:32:20 INFO - VRAM (Dynamic, Max): 1536 MB 19:32:20 INFO - Vendor: Intel (0x8086) 19:32:20 INFO - Device ID: 0x0a2e 19:32:20 INFO - Revision ID: 0x0009 19:32:20 INFO - Displays: 19:32:20 INFO - Display: 19:32:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:32:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:32:20 INFO - Display Serial Number: 1600x1200 60 19:32:20 INFO - Main Display: Yes 19:32:20 INFO - Mirror: Off 19:32:20 INFO - Online: Yes 19:32:20 INFO - Rotation: Supported 19:32:20 INFO - Return code: 0 19:32:20 INFO - Running post-action listener: _resource_record_post_action 19:32:20 INFO - [mozharness: 2017-06-13 02:32:20.829591Z] Finished run-tests step (success) 19:32:20 INFO - Running post-run listener: _resource_record_post_run 19:32:20 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 35832320; Write bytes: 563362304; Read time: 336; Write time: 1750 19:32:20 INFO - TinderboxPrint: CPU usage
53.2% 19:32:20 INFO - TinderboxPrint: I/O read bytes / time
35,832,320 / 336 19:32:20 INFO - TinderboxPrint: I/O write bytes / time
563,362,304 / 1,750 19:32:20 INFO - TinderboxPrint: CPU idle
2,354.1 (46.8%) 19:32:20 INFO - TinderboxPrint: CPU system
1,021.4 (20.3%) 19:32:20 INFO - TinderboxPrint: CPU user
1,650.2 (32.8%) 19:32:20 INFO - TinderboxPrint: Swap in / out
275,824,640 / 0 19:32:20 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 244079104; Write bytes: 245503488; Read time: 18419; Write time: 495 19:32:20 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 19:32:20 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 31166976; Write bytes: 316425216; Read time: 304; Write time: 1251 19:32:21 INFO - Running post-run listener: _upload_blobber_files 19:32:21 INFO - Blob upload gear active. 19:32:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:32:21 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 19:32:21 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:32:21 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:32:21 INFO - (blobuploader) - INFO - Open directory for files ... 19:32:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:32:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:32:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:32:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:32:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:32:22 INFO - (blobuploader) - INFO - Done attempting. 19:32:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:32:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:32:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:32:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:32:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:32:24 INFO - (blobuploader) - INFO - Done attempting. 19:32:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:32:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:32:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:32:25 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:32:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:32:25 INFO - (blobuploader) - INFO - Done attempting. 19:32:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:32:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:32:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:32:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:32:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:32:26 INFO - (blobuploader) - INFO - Done attempting. 19:32:26 INFO - (blobuploader) - INFO - Iteration through files over. 19:32:26 INFO - Return code: 0 19:32:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:32:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:32:26 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/739ec2c0262533dee4ec3e6c36d1ade5fb0ab96081b92fe618145924bb543a20d3d402f15f78b56321674d3c822079c789b48decb8e69cf4bb14685ae7d62ec8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5960037b0963d07852c6ae06ae29088fd71479fd826ada2e7636d7a5395effb9ea9842167b22ff0ecad6c29f4cb6d6a05601cb90301bc8d3790f30032a2e478d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/eeabc03a3ceb43079e37e1ba31275bfbeddc1d8b22a5b3d11c5a2f250e0cffe8e18528b77f260f36220b2eb661d6d8821062dff173cabc346d356a0f81a4333d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6c3711da4a0fd23e8cec2188fc37309bb267a1887a05dc49e1604eaf99aa696d5721844603eea878f7309c8711409f50eef4d647bf27a7088b236ae3a5b08930"} 19:32:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:32:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:32:26 INFO - Contents: 19:32:26 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/739ec2c0262533dee4ec3e6c36d1ade5fb0ab96081b92fe618145924bb543a20d3d402f15f78b56321674d3c822079c789b48decb8e69cf4bb14685ae7d62ec8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5960037b0963d07852c6ae06ae29088fd71479fd826ada2e7636d7a5395effb9ea9842167b22ff0ecad6c29f4cb6d6a05601cb90301bc8d3790f30032a2e478d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/eeabc03a3ceb43079e37e1ba31275bfbeddc1d8b22a5b3d11c5a2f250e0cffe8e18528b77f260f36220b2eb661d6d8821062dff173cabc346d356a0f81a4333d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6c3711da4a0fd23e8cec2188fc37309bb267a1887a05dc49e1604eaf99aa696d5721844603eea878f7309c8711409f50eef4d647bf27a7088b236ae3a5b08930"} 19:32:26 INFO - Running post-run listener: copy_logs_to_upload_dir 19:32:26 INFO - Copying logs to upload dir... 19:32:26 INFO - mkdir: /builds/slave/test/build/upload/logs 19:32:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1338.929711 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-06-12 19:32:27.064794) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-12 19:32:27.068512) ========= 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.6lsYYTdvWw/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.RzRUTHqgOB/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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/Mozilla-Beta/sha512/739ec2c0262533dee4ec3e6c36d1ade5fb0ab96081b92fe618145924bb543a20d3d402f15f78b56321674d3c822079c789b48decb8e69cf4bb14685ae7d62ec8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5960037b0963d07852c6ae06ae29088fd71479fd826ada2e7636d7a5395effb9ea9842167b22ff0ecad6c29f4cb6d6a05601cb90301bc8d3790f30032a2e478d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/eeabc03a3ceb43079e37e1ba31275bfbeddc1d8b22a5b3d11c5a2f250e0cffe8e18528b77f260f36220b2eb661d6d8821062dff173cabc346d356a0f81a4333d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6c3711da4a0fd23e8cec2188fc37309bb267a1887a05dc49e1604eaf99aa696d5721844603eea878f7309c8711409f50eef4d647bf27a7088b236ae3a5b08930"} build_url:https://queue.taskcluster.net/v1/task/VbFe07bERzWfuxK3-eOZiQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011009 build_url: 'https://queue.taskcluster.net/v1/task/VbFe07bERzWfuxK3-eOZiQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/739ec2c0262533dee4ec3e6c36d1ade5fb0ab96081b92fe618145924bb543a20d3d402f15f78b56321674d3c822079c789b48decb8e69cf4bb14685ae7d62ec8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5960037b0963d07852c6ae06ae29088fd71479fd826ada2e7636d7a5395effb9ea9842167b22ff0ecad6c29f4cb6d6a05601cb90301bc8d3790f30032a2e478d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/eeabc03a3ceb43079e37e1ba31275bfbeddc1d8b22a5b3d11c5a2f250e0cffe8e18528b77f260f36220b2eb661d6d8821062dff173cabc346d356a0f81a4333d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6c3711da4a0fd23e8cec2188fc37309bb267a1887a05dc49e1604eaf99aa696d5721844603eea878f7309c8711409f50eef4d647bf27a7088b236ae3a5b08930"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-12 19:32:27.102189) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 19:32:27.102653) ========= 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.6lsYYTdvWw/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.RzRUTHqgOB/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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.005239 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 19:32:27.152997) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-12 19:32:27.153402) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-12 19:32:27.153759) ========= ========= Total master_lag: 0.20 =========