builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0029 starttime: 1497870459.02 results: success (0) buildid: 20170619023922 builduid: 90b8606552634938818d7e488bd199d1 revision: ea84df3bdb9982a8f52adff05bcf2f5d1bef6189 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-19 04:07:39.015591) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-19 04:07:39.020323) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-19 04:07:39.020640) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-19 04:07:39.043298) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-19 04:07:39.043587) ========= 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.eWc9x9yS6v/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.rHKmgx805d/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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-19 04:07:39-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 13.3M=0.001s 2017-06-19 04:07:39 (13.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.516992 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-19 04:07:39.587599) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-19 04:07:39.587925) ========= 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.eWc9x9yS6v/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.rHKmgx805d/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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.020918 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-19 04:07:39.637571) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-19 04:07:39.637953) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev ea84df3bdb9982a8f52adff05bcf2f5d1bef6189 --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 ea84df3bdb9982a8f52adff05bcf2f5d1bef6189 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.eWc9x9yS6v/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.rHKmgx805d/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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-19 04:07:39,711 truncating revision to first 12 chars 2017-06-19 04:07:39,711 Setting DEBUG logging. 2017-06-19 04:07:39,711 attempt 1/10 2017-06-19 04:07:39,711 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/ea84df3bdb99?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-19 04:07:40,965 unpacking tar archive at: mozilla-beta-ea84df3bdb99/testing/mozharness/ program finished with exit code 0 elapsedTime=1.516850 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-19 04:07:41.176964) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-19 04:07:41.177283) ========= script_repo_revision: ea84df3bdb9982a8f52adff05bcf2f5d1bef6189 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-19 04:07:41.177675) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-19 04:07:41.177949) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-19 04:07:41.195814) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 22 secs) (at 2017-06-19 04:07:41.196118) ========= /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.eWc9x9yS6v/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.rHKmgx805d/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 04:07:41 INFO - MultiFileLogger online at 20170619 04:07:41 in /builds/slave/test 04:07:41 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 04:07:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:07:41 INFO - {'append_to_log': False, 04:07:41 INFO - 'base_work_dir': '/builds/slave/test', 04:07:41 INFO - 'blob_upload_branch': 'Mozilla-Beta', 04:07:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:07:41 INFO - 'branch': 'Mozilla-Beta', 04:07:41 INFO - 'buildbot_json_path': 'buildprops.json', 04:07:41 INFO - 'config_files': ('talos/mac_config.py',), 04:07:41 INFO - 'default_actions': ('clobber', 04:07:41 INFO - 'read-buildbot-config', 04:07:41 INFO - 'download-and-extract', 04:07:41 INFO - 'populate-webroot', 04:07:41 INFO - 'create-virtualenv', 04:07:41 INFO - 'install', 04:07:41 INFO - 'setup-mitmproxy', 04:07:41 INFO - 'run-tests'), 04:07:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:07:41 INFO - 'download_minidump_stackwalk': True, 04:07:41 INFO - 'download_symbols': 'ondemand', 04:07:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:07:41 INFO - 'tooltool.py': '/tools/tooltool.py', 04:07:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:07:41 INFO - '/tools/misc-python/virtualenv.py')}, 04:07:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:07:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:07:41 INFO - 'gecko_profile': False, 04:07:41 INFO - 'gecko_profile_interval': 0, 04:07:41 INFO - 'installer_path': 'installer.exe', 04:07:41 INFO - 'log_level': 'info', 04:07:41 INFO - 'log_name': 'talos', 04:07:41 INFO - 'log_to_console': True, 04:07:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:07:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:07:41 INFO - 'opt_config_files': (), 04:07:41 INFO - 'pip_index': False, 04:07:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:07:41 INFO - 'cmd': ('bash', 04:07:41 INFO - '-c', 04:07:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:07:41 INFO - 'enabled': True, 04:07:41 INFO - 'halt_on_failure': False, 04:07:41 INFO - 'name': 'check_screen_resolution'},), 04:07:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:07:41 INFO - 'cmd': ('bash', 04:07:41 INFO - '-c', 04:07:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:07:41 INFO - 'enabled': True, 04:07:41 INFO - 'halt_on_failure': False, 04:07:41 INFO - 'name': 'check_screen_resolution'},), 04:07:41 INFO - 'run_cmd_checks_enabled': True, 04:07:41 INFO - 'suite': 'dromaeojs-e10s', 04:07:41 INFO - 'system_bits': '32', 04:07:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:07:41 INFO - 'title': 't-yosemite-r7-0029', 04:07:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:07:41 INFO - 'use_talos_json': True, 04:07:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:07:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:07:41 INFO - 'work_dir': 'build'} 04:07:41 INFO - [mozharness: 2017-06-19 11:07:41.395157Z] Running clobber step. 04:07:41 INFO - Running pre-action listener: _resource_record_pre_action 04:07:41 INFO - Running main action method: clobber 04:07:41 INFO - rmtree: /builds/slave/test/build 04:07:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:07:43 INFO - Running post-action listener: _resource_record_post_action 04:07:43 INFO - [mozharness: 2017-06-19 11:07:43.264424Z] Finished clobber step (success) 04:07:43 INFO - [mozharness: 2017-06-19 11:07:43.264587Z] Running read-buildbot-config step. 04:07:43 INFO - Running pre-action listener: _resource_record_pre_action 04:07:43 INFO - Running main action method: read_buildbot_config 04:07:43 INFO - Using buildbot properties: 04:07:43 INFO - { 04:07:43 INFO - "project": "", 04:07:43 INFO - "product": "firefox", 04:07:43 INFO - "script_repo_revision": "production", 04:07:43 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 04:07:43 INFO - "repository": "", 04:07:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 04:07:43 INFO - "buildid": "20170619023922", 04:07:43 INFO - "pgo_build": "False", 04:07:43 INFO - "basedir": "/builds/slave/test", 04:07:43 INFO - "buildnumber": 1, 04:07:43 INFO - "slavename": "t-yosemite-r7-0029", 04:07:43 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 04:07:43 INFO - "platform": "yosemite_r7_devedition", 04:07:43 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 04:07:43 INFO - "branch": "mozilla-beta", 04:07:43 INFO - "repo_path": "releases/mozilla-beta", 04:07:43 INFO - "revision": "ea84df3bdb9982a8f52adff05bcf2f5d1bef6189", 04:07:43 INFO - "stage_platform": "macosx64-devedition", 04:07:43 INFO - "builduid": "90b8606552634938818d7e488bd199d1", 04:07:43 INFO - "slavebuilddir": "test" 04:07:43 INFO - } 04:07:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/Fnm0frauRh25-hNetyOeSg/artifacts/public/build/firefox-55.0.en-US.mac.dmg. 04:07:43 INFO - Running post-action listener: _resource_record_post_action 04:07:43 INFO - [mozharness: 2017-06-19 11:07:43.268148Z] Finished read-buildbot-config step (success) 04:07:43 INFO - [mozharness: 2017-06-19 11:07:43.268290Z] Running download-and-extract step. 04:07:43 INFO - Running pre-action listener: _resource_record_pre_action 04:07:43 INFO - Running main action method: download_and_extract 04:07:43 INFO - mkdir: /builds/slave/test/build/tests 04:07:43 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')]} 04:07:43 INFO - trying https://queue.taskcluster.net/v1/task/Fnm0frauRh25-hNetyOeSg/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json 04:07:43 INFO - Downloading https://queue.taskcluster.net/v1/task/Fnm0frauRh25-hNetyOeSg/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 04:07:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Fnm0frauRh25-hNetyOeSg/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 04:07:44 INFO - Downloaded 1290 bytes. 04:07:44 INFO - Reading from file /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 04:07:44 INFO - Using the following test package requirements: 04:07:44 INFO - {u'awsy': [u'firefox-55.0.en-US.mac.common.tests.zip', 04:07:44 INFO - u'firefox-55.0.en-US.mac.awsy.tests.zip'], 04:07:44 INFO - u'common': [u'firefox-55.0.en-US.mac.common.tests.zip'], 04:07:44 INFO - u'cppunittest': [u'firefox-55.0.en-US.mac.common.tests.zip', 04:07:44 INFO - u'firefox-55.0.en-US.mac.cppunittest.tests.zip'], 04:07:44 INFO - u'gtest': [u'firefox-55.0.en-US.mac.common.tests.zip', 04:07:44 INFO - u'firefox-55.0.en-US.mac.gtest.tests.zip'], 04:07:44 INFO - u'jittest': [u'firefox-55.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 04:07:44 INFO - u'mochitest': [u'firefox-55.0.en-US.mac.common.tests.zip', 04:07:44 INFO - u'firefox-55.0.en-US.mac.mochitest.tests.zip'], 04:07:44 INFO - u'mozbase': [u'firefox-55.0.en-US.mac.common.tests.zip'], 04:07:44 INFO - u'reftest': [u'firefox-55.0.en-US.mac.common.tests.zip', 04:07:44 INFO - u'firefox-55.0.en-US.mac.reftest.tests.zip'], 04:07:44 INFO - u'talos': [u'firefox-55.0.en-US.mac.common.tests.zip', 04:07:44 INFO - u'firefox-55.0.en-US.mac.talos.tests.zip'], 04:07:44 INFO - u'web-platform': [u'firefox-55.0.en-US.mac.common.tests.zip', 04:07:44 INFO - u'firefox-55.0.en-US.mac.web-platform.tests.tar.gz'], 04:07:44 INFO - u'xpcshell': [u'firefox-55.0.en-US.mac.common.tests.zip', 04:07:44 INFO - u'firefox-55.0.en-US.mac.xpcshell.tests.zip']} 04:07:44 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip'] for test suite category: common 04:07:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Fnm0frauRh25-hNetyOeSg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 04:07:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Fnm0frauRh25-hNetyOeSg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 04:07:44 INFO - Fetch https://queue.taskcluster.net/v1/task/Fnm0frauRh25-hNetyOeSg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 04:07:45 INFO - Content-Length response header: 59545170 04:07:45 INFO - Bytes received: 59545170 04:08:04 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 04:08:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Fnm0frauRh25-hNetyOeSg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 04:08:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Fnm0frauRh25-hNetyOeSg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 04:08:04 INFO - Fetch https://queue.taskcluster.net/v1/task/Fnm0frauRh25-hNetyOeSg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 04:08:06 INFO - Content-Length response header: 59545170 04:08:06 INFO - Bytes received: 59545170 04:08:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Fnm0frauRh25-hNetyOeSg/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip 04:08:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Fnm0frauRh25-hNetyOeSg/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip'}, attempt #1 04:08:24 INFO - Fetch https://queue.taskcluster.net/v1/task/Fnm0frauRh25-hNetyOeSg/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip into memory 04:08:25 INFO - Content-Length response header: 14454470 04:08:25 INFO - Bytes received: 14454470 04:08:25 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')]} 04:08:25 INFO - trying https://queue.taskcluster.net/v1/task/Fnm0frauRh25-hNetyOeSg/artifacts/public/build/firefox-55.0.en-US.mac.dmg 04:08:25 INFO - Downloading https://queue.taskcluster.net/v1/task/Fnm0frauRh25-hNetyOeSg/artifacts/public/build/firefox-55.0.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0.en-US.mac.dmg 04:08:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Fnm0frauRh25-hNetyOeSg/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 04:08:27 INFO - Downloaded 66844891 bytes. 04:08:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Fnm0frauRh25-hNetyOeSg/artifacts/public/build/firefox-55.0.en-US.mac.dmg 04:08:27 INFO - mkdir: /builds/slave/test/properties 04:08:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:08:27 INFO - Writing to file /builds/slave/test/properties/build_url 04:08:27 INFO - Contents: 04:08:27 INFO - build_url:https://queue.taskcluster.net/v1/task/Fnm0frauRh25-hNetyOeSg/artifacts/public/build/firefox-55.0.en-US.mac.dmg 04:08:28 INFO - Running post-action listener: _resource_record_post_action 04:08:28 INFO - Running post-action listener: set_extra_try_arguments 04:08:28 INFO - [mozharness: 2017-06-19 11:08:28.434960Z] Finished download-and-extract step (success) 04:08:28 INFO - [mozharness: 2017-06-19 11:08:28.435120Z] Running populate-webroot step. 04:08:28 INFO - Running pre-action listener: _resource_record_pre_action 04:08:28 INFO - Running main action method: populate_webroot 04:08:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:08:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:08:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:08:28 INFO - '--tppagecycles', 04:08:28 INFO - '7'], 04:08:28 INFO - 'tests': ['tsvgm']}}, 04:08:28 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:08:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:08:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:08:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:08:28 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:08:28 INFO - 'talos_options': ['--disable-e10s'], 04:08:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:08:28 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:08:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:08:28 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:08:28 INFO - 'talos_options': ['--disable-e10s'], 04:08:28 INFO - 'tests': ['damp', 'tps']}, 04:08:28 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:08:28 INFO - 'tests': ['damp', 'tps']}, 04:08:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:08:28 INFO - 'tests': ['dromaeo_dom']}, 04:08:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:08:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:08:28 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 04:08:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 04:08:28 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 04:08:28 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 04:08:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:08:28 INFO - 'tests': ['a11yr', 04:08:28 INFO - 'ts_paint', 04:08:28 INFO - 'tpaint', 04:08:28 INFO - 'sessionrestore', 04:08:28 INFO - 'sessionrestore_no_auto_restore', 04:08:28 INFO - 'tabpaint']}, 04:08:28 INFO - 'other-e10s': {'tests': ['a11yr', 04:08:28 INFO - 'ts_paint', 04:08:28 INFO - 'tpaint', 04:08:28 INFO - 'sessionrestore', 04:08:28 INFO - 'sessionrestore_no_auto_restore', 04:08:28 INFO - 'tabpaint']}, 04:08:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:08:28 INFO - 'ts_paint', 04:08:28 INFO - 'tpaint', 04:08:28 INFO - 'sessionrestore', 04:08:28 INFO - 'sessionrestore_no_auto_restore', 04:08:28 INFO - 'tabpaint']}, 04:08:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:08:28 INFO - 'ts_paint', 04:08:28 INFO - 'tpaint', 04:08:28 INFO - 'sessionrestore', 04:08:28 INFO - 'sessionrestore_no_auto_restore', 04:08:28 INFO - 'tabpaint']}, 04:08:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:08:28 INFO - 'tests': ['a11yr', 04:08:28 INFO - 'ts_paint', 04:08:28 INFO - 'tpaint', 04:08:28 INFO - 'sessionrestore', 04:08:28 INFO - 'sessionrestore_no_auto_restore', 04:08:28 INFO - 'tabpaint']}, 04:08:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:08:28 INFO - 'tests': ['a11yr', 04:08:28 INFO - 'ts_paint', 04:08:28 INFO - 'tpaint', 04:08:28 INFO - 'sessionrestore', 04:08:28 INFO - 'sessionrestore_no_auto_restore', 04:08:28 INFO - 'tabpaint']}, 04:08:28 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 04:08:28 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 04:08:28 INFO - 'bloom_basic_ref']}, 04:08:28 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:08:28 INFO - 'talos_options': ['--mitmproxy', 04:08:28 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:08:28 INFO - '--firstNonBlankPaint'], 04:08:28 INFO - 'tests': ['quantum_pageload_google', 04:08:28 INFO - 'quantum_pageload_youtube', 04:08:28 INFO - 'quantum_pageload_amazon', 04:08:28 INFO - 'quantum_pageload_facebook']}, 04:08:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:08:28 INFO - 'tests': ['tsvgx', 04:08:28 INFO - 'tsvgr_opacity', 04:08:28 INFO - 'tart', 04:08:28 INFO - 'tscrollx', 04:08:28 INFO - 'cart', 04:08:28 INFO - 'tsvg_static']}, 04:08:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:08:28 INFO - 'tsvgr_opacity', 04:08:28 INFO - 'tart', 04:08:28 INFO - 'tscrollx', 04:08:28 INFO - 'cart', 04:08:28 INFO - 'tsvg_static']}, 04:08:28 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:08:28 INFO - 'talos_options': ['--disable-e10s'], 04:08:28 INFO - 'tests': ['tp5o']}, 04:08:28 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:08:28 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:08:28 INFO - 'talos_options': ['--disable-e10s', 04:08:28 INFO - '--xperf_path', 04:08:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:08:28 INFO - 'tests': ['tp5n']}, 04:08:28 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:08:28 INFO - 'talos_options': ['--xperf_path', 04:08:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:08:28 INFO - 'tests': ['tp5n']}}, 04:08:28 INFO - 'talos.zip': {'path': '', 04:08:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:08:28 INFO - Running post-action listener: _resource_record_post_action 04:08:28 INFO - [mozharness: 2017-06-19 11:08:28.456254Z] Finished populate-webroot step (success) 04:08:28 INFO - [mozharness: 2017-06-19 11:08:28.456390Z] Running create-virtualenv step. 04:08:28 INFO - Running pre-action listener: _resource_record_pre_action 04:08:28 INFO - Running main action method: create_virtualenv 04:08:28 INFO - Creating virtualenv /builds/slave/test/build/venv 04:08:28 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 04:08:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:08:28 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 04:08:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:08:28 INFO - Using real prefix '/tools/python27' 04:08:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:08:29 INFO - Installing distribute.............................................................................................................................................................................................done. 04:08:32 INFO - Installing pip.................done. 04:08:32 INFO - Return code: 0 04:08:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:08:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:08:32 INFO - Reading from file tmpfile_stdout 04:08:32 INFO - Output received: 04:08:32 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) 04:08:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:08:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:08:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:08:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08:32 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 0x109048c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10904c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87e258c200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d9ed30>, '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 0x108d68cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d8fbe8>, '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.eWc9x9yS6v/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.rHKmgx805d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 04:08:32 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 04:08:32 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 04:08:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eWc9x9yS6v/Render', 04:08:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:08:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:08:32 INFO - 'HOME': '/Users/cltbld', 04:08:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:08:32 INFO - 'LOGNAME': 'cltbld', 04:08:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:32 INFO - 'MOZ_NO_REMOTE': '1', 04:08:32 INFO - 'NO_EM_RESTART': '1', 04:08:32 INFO - 'PAGER': '/bin/cat', 04:08:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:08:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:32 INFO - 'PWD': '/builds/slave/test', 04:08:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:08:32 INFO - 'SHELL': '/bin/bash', 04:08:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rHKmgx805d/Listeners', 04:08:32 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 04:08:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:08:32 INFO - 'USER': 'cltbld', 04:08:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:08:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:08:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:32 INFO - 'XPC_FLAGS': '0x0', 04:08:32 INFO - 'XPC_SERVICE_NAME': '0', 04:08:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:08:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:32 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 04:08:32 INFO - Cleaning up... 04:08:32 INFO - Return code: 0 04:08:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:08:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:08:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:08:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08:32 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 0x109048c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10904c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87e258c200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d9ed30>, '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 0x108d68cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d8fbe8>, '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.eWc9x9yS6v/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.rHKmgx805d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 04:08:32 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 04:08:32 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 04:08:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eWc9x9yS6v/Render', 04:08:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:08:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:08:32 INFO - 'HOME': '/Users/cltbld', 04:08:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:08:32 INFO - 'LOGNAME': 'cltbld', 04:08:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:32 INFO - 'MOZ_NO_REMOTE': '1', 04:08:32 INFO - 'NO_EM_RESTART': '1', 04:08:32 INFO - 'PAGER': '/bin/cat', 04:08:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:08:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:32 INFO - 'PWD': '/builds/slave/test', 04:08:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:08:32 INFO - 'SHELL': '/bin/bash', 04:08:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rHKmgx805d/Listeners', 04:08:32 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 04:08:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:08:32 INFO - 'USER': 'cltbld', 04:08:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:08:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:08:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:32 INFO - 'XPC_FLAGS': '0x0', 04:08:32 INFO - 'XPC_SERVICE_NAME': '0', 04:08:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:08:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:32 INFO - Downloading/unpacking psutil>=3.1.1 04:08:32 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 04:08:32 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 04:08:32 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 04:08:32 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 04:08:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:08:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:08:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:08:35 INFO - Installing collected packages: psutil 04:08:35 INFO - Running setup.py install for psutil 04:08:35 INFO - building 'psutil._psutil_osx' extension 04:08:35 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 04:08:35 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 04:08:35 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 04:08:35 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 04:08:36 INFO - building 'psutil._psutil_posix' extension 04:08:36 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 04:08:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:08:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:08:36 INFO - ^ 04:08:36 INFO - 1 warning generated. 04:08:36 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 04:08:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:08:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:08:36 INFO - Successfully installed psutil 04:08:36 INFO - Cleaning up... 04:08:36 INFO - Return code: 0 04:08:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:08:36 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')]} 04:08:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08:36 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')]} 04:08:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08:36 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 0x109048c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10904c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87e258c200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d9ed30>, '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 0x108d68cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d8fbe8>, '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.eWc9x9yS6v/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.rHKmgx805d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 04:08:36 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 04:08:36 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 04:08:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eWc9x9yS6v/Render', 04:08:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:08:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:08:36 INFO - 'HOME': '/Users/cltbld', 04:08:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:08:36 INFO - 'LOGNAME': 'cltbld', 04:08:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:36 INFO - 'MOZ_NO_REMOTE': '1', 04:08:36 INFO - 'NO_EM_RESTART': '1', 04:08:36 INFO - 'PAGER': '/bin/cat', 04:08:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:08:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:36 INFO - 'PWD': '/builds/slave/test', 04:08:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:08:36 INFO - 'SHELL': '/bin/bash', 04:08:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rHKmgx805d/Listeners', 04:08:36 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 04:08:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:08:36 INFO - 'USER': 'cltbld', 04:08:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:08:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:08:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:36 INFO - 'XPC_FLAGS': '0x0', 04:08:36 INFO - 'XPC_SERVICE_NAME': '0', 04:08:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:08:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:08:36 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 04:08:36 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 04:08:36 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 04:08:36 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 04:08:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:08:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:08:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:08:38 INFO - Installing collected packages: mozsystemmonitor 04:08:38 INFO - Running setup.py install for mozsystemmonitor 04:08:38 INFO - Successfully installed mozsystemmonitor 04:08:38 INFO - Cleaning up... 04:08:38 INFO - Return code: 0 04:08:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:08:38 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')]} 04:08:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08:38 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')]} 04:08:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x109048c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10904c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87e258c200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d9ed30>, '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 0x108d68cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d8fbe8>, '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.eWc9x9yS6v/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.rHKmgx805d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 04:08:38 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 04:08:38 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 04:08:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eWc9x9yS6v/Render', 04:08:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:08:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:08:38 INFO - 'HOME': '/Users/cltbld', 04:08:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:08:38 INFO - 'LOGNAME': 'cltbld', 04:08:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:38 INFO - 'MOZ_NO_REMOTE': '1', 04:08:38 INFO - 'NO_EM_RESTART': '1', 04:08:38 INFO - 'PAGER': '/bin/cat', 04:08:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:08:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:38 INFO - 'PWD': '/builds/slave/test', 04:08:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:08:38 INFO - 'SHELL': '/bin/bash', 04:08:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rHKmgx805d/Listeners', 04:08:38 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 04:08:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:08:38 INFO - 'USER': 'cltbld', 04:08:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:08:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:08:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:38 INFO - 'XPC_FLAGS': '0x0', 04:08:38 INFO - 'XPC_SERVICE_NAME': '0', 04:08:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:08:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:38 INFO - Downloading/unpacking jsonschema==2.5.1 04:08:38 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 04:08:38 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 04:08:38 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 04:08:38 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 04:08:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:08:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:08:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:08:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:08:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:08:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:08:40 INFO - Downloading functools32-3.2.3-2.tar.gz 04:08:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:08:40 INFO - warning: no files found matching '*.txt' 04:08:40 INFO - no previously-included directories found matching 'build' 04:08:40 INFO - no previously-included directories found matching 'dist' 04:08:40 INFO - no previously-included directories found matching '.git*' 04:08:40 INFO - Installing collected packages: jsonschema, functools32 04:08:40 INFO - Running setup.py install for functools32 04:08:40 INFO - warning: no files found matching '*.txt' 04:08:40 INFO - no previously-included directories found matching 'build' 04:08:40 INFO - no previously-included directories found matching 'dist' 04:08:40 INFO - no previously-included directories found matching '.git*' 04:08:40 INFO - Successfully installed jsonschema functools32 04:08:40 INFO - Cleaning up... 04:08:40 INFO - Return code: 0 04:08:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:08:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:08:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:08:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08:40 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 0x109048c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10904c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87e258c200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d9ed30>, '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 0x108d68cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d8fbe8>, '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.eWc9x9yS6v/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.rHKmgx805d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 04:08:40 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 04:08:40 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 04:08:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eWc9x9yS6v/Render', 04:08:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:08:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:08:40 INFO - 'HOME': '/Users/cltbld', 04:08:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:08:40 INFO - 'LOGNAME': 'cltbld', 04:08:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:40 INFO - 'MOZ_NO_REMOTE': '1', 04:08:40 INFO - 'NO_EM_RESTART': '1', 04:08:40 INFO - 'PAGER': '/bin/cat', 04:08:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:08:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:40 INFO - 'PWD': '/builds/slave/test', 04:08:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:08:40 INFO - 'SHELL': '/bin/bash', 04:08:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rHKmgx805d/Listeners', 04:08:40 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 04:08:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:08:40 INFO - 'USER': 'cltbld', 04:08:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:08:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:08:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:40 INFO - 'XPC_FLAGS': '0x0', 04:08:40 INFO - 'XPC_SERVICE_NAME': '0', 04:08:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:08:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:08:40 INFO - Cleaning up... 04:08:40 INFO - Return code: 0 04:08:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:08:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:08:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:08:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08:40 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 0x109048c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10904c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87e258c200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d9ed30>, '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 0x108d68cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d8fbe8>, '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.eWc9x9yS6v/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.rHKmgx805d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 04:08:40 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 04:08:40 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 04:08:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eWc9x9yS6v/Render', 04:08:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:08:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:08:40 INFO - 'HOME': '/Users/cltbld', 04:08:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:08:40 INFO - 'LOGNAME': 'cltbld', 04:08:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:40 INFO - 'MOZ_NO_REMOTE': '1', 04:08:40 INFO - 'NO_EM_RESTART': '1', 04:08:40 INFO - 'PAGER': '/bin/cat', 04:08:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:08:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:40 INFO - 'PWD': '/builds/slave/test', 04:08:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:08:40 INFO - 'SHELL': '/bin/bash', 04:08:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rHKmgx805d/Listeners', 04:08:40 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 04:08:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:08:40 INFO - 'USER': 'cltbld', 04:08:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:08:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:08:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:40 INFO - 'XPC_FLAGS': '0x0', 04:08:40 INFO - 'XPC_SERVICE_NAME': '0', 04:08:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:08:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:40 INFO - Downloading/unpacking blobuploader==1.2.4 04:08:40 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 04:08:40 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 04:08:40 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 04:08:40 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 04:08:42 INFO - Downloading blobuploader-1.2.4.tar.gz 04:08:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:08:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:08:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:08:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:08:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:08:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:08:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:08:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:08:43 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 04:08:43 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 04:08:43 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 04:08:43 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 04:08:43 INFO - Downloading docopt-0.6.1.tar.gz 04:08:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:08:43 INFO - Installing collected packages: blobuploader, requests, docopt 04:08:43 INFO - Running setup.py install for blobuploader 04:08:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:08:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:08:43 INFO - Running setup.py install for requests 04:08:44 INFO - Running setup.py install for docopt 04:08:44 INFO - Successfully installed blobuploader requests docopt 04:08:44 INFO - Cleaning up... 04:08:44 INFO - Return code: 0 04:08:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:08:44 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')]} 04:08:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08:44 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')]} 04:08:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08:44 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 0x109048c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10904c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87e258c200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d9ed30>, '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 0x108d68cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d8fbe8>, '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.eWc9x9yS6v/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.rHKmgx805d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 04:08:44 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 04:08:44 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 04:08:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eWc9x9yS6v/Render', 04:08:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:08:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:08:44 INFO - 'HOME': '/Users/cltbld', 04:08:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:08:44 INFO - 'LOGNAME': 'cltbld', 04:08:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:44 INFO - 'MOZ_NO_REMOTE': '1', 04:08:44 INFO - 'NO_EM_RESTART': '1', 04:08:44 INFO - 'PAGER': '/bin/cat', 04:08:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:08:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:44 INFO - 'PWD': '/builds/slave/test', 04:08:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:08:44 INFO - 'SHELL': '/bin/bash', 04:08:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rHKmgx805d/Listeners', 04:08:44 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 04:08:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:08:44 INFO - 'USER': 'cltbld', 04:08:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:08:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:08:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:44 INFO - 'XPC_FLAGS': '0x0', 04:08:44 INFO - 'XPC_SERVICE_NAME': '0', 04:08:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:08:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:08:44 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-1GT6Ct-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:08:44 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-U12gDd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:08:44 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-v4RG7K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:08:44 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-DOMgzk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:08:44 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-SuaZQ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:08:45 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-U6JXjL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:08:45 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-h58l9c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:08:45 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-qDyc7i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:08:45 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-b1mha2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:08:45 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-a61zts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:08:45 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-j_ura3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:08:45 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-fqsFB8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:08:45 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-jTITzg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:08:45 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-Swtq3y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:08:46 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-tpvxBf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:08:46 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-AKD7K8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:08:46 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-wJAFrw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:08:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:08:46 INFO - Running setup.py install for manifestparser 04:08:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:08:46 INFO - Running setup.py install for mozcrash 04:08:46 INFO - Running setup.py install for mozdebug 04:08:46 INFO - Running setup.py install for mozdevice 04:08:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:08:46 INFO - Running setup.py install for mozfile 04:08:47 INFO - Running setup.py install for mozhttpd 04:08:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:08:47 INFO - Running setup.py install for mozinfo 04:08:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:08:47 INFO - Running setup.py install for mozInstall 04:08:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:08:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:08:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:08:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:08:47 INFO - Running setup.py install for mozleak 04:08:47 INFO - Running setup.py install for mozlog 04:08:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:08:47 INFO - Running setup.py install for moznetwork 04:08:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:08:47 INFO - Running setup.py install for mozprocess 04:08:48 INFO - Running setup.py install for mozprofile 04:08:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:08:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:08:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:08:48 INFO - Running setup.py install for mozrunner 04:08:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:08:48 INFO - Running setup.py install for mozscreenshot 04:08:48 INFO - Running setup.py install for moztest 04:08:48 INFO - Running setup.py install for mozversion 04:08:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:08:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:08:48 INFO - Cleaning up... 04:08:48 INFO - Return code: 0 04:08:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:08:48 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')]} 04:08:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08:48 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')]} 04:08:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08:48 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 0x109048c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10904c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87e258c200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d9ed30>, '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 0x108d68cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d8fbe8>, '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.eWc9x9yS6v/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.rHKmgx805d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 04:08:48 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 04:08:48 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 04:08:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eWc9x9yS6v/Render', 04:08:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:08:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:08:48 INFO - 'HOME': '/Users/cltbld', 04:08:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:08:48 INFO - 'LOGNAME': 'cltbld', 04:08:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:48 INFO - 'MOZ_NO_REMOTE': '1', 04:08:48 INFO - 'NO_EM_RESTART': '1', 04:08:48 INFO - 'PAGER': '/bin/cat', 04:08:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:08:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:48 INFO - 'PWD': '/builds/slave/test', 04:08:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:08:48 INFO - 'SHELL': '/bin/bash', 04:08:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rHKmgx805d/Listeners', 04:08:48 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 04:08:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:08:48 INFO - 'USER': 'cltbld', 04:08:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:08:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:08:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:48 INFO - 'XPC_FLAGS': '0x0', 04:08:48 INFO - 'XPC_SERVICE_NAME': '0', 04:08:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:08:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:08:49 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-yefawr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:08:49 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)) 04:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:08:49 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-GwXx7D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:08:49 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)) 04:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:08:49 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-7I5CWe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:08:49 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)) 04:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:08:49 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-0tsGg0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:08:49 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)) 04:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:08:49 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-tkC2HZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:08:49 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)) 04:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:08:49 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-tr46rg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:08:49 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)) 04:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:08:49 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-KRcb5z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:08:49 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)) 04:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:08:49 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-kjUUtY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:08:50 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)) 04:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:08:50 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-luizTj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:08:50 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)) 04:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:08:50 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-xaBLjP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:08:50 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)) 04:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:08:50 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-k4vYzU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:08:50 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)) 04:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:08:50 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-QU1T72-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:08:50 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)) 04:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:08:50 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-GTck3I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:08:50 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)) 04:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:08:50 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-95e2Hq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:08:50 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)) 04:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:08:50 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-Dk87wp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:08:50 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)) 04:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:08:50 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-0tIf3R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:08:50 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)) 04:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:08:50 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-TwTEom-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:08:51 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)) 04:08:51 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)) 04:08:51 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)) 04:08:51 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)) 04:08:51 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)) 04:08:51 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)) 04:08:51 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)) 04:08:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:08:51 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 04:08:51 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 04:08:51 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 04:08:51 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 04:08:52 INFO - Downloading blessings-1.6.tar.gz 04:08:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:08:52 INFO - Installing collected packages: blessings 04:08:52 INFO - Running setup.py install for blessings 04:08:52 INFO - Successfully installed blessings 04:08:52 INFO - Cleaning up... 04:08:52 INFO - Return code: 0 04:08:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:08:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:08:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:08:53 INFO - Reading from file tmpfile_stdout 04:08:53 INFO - Current package versions: 04:08:53 INFO - blessings == 1.6 04:08:53 INFO - blobuploader == 1.2.4 04:08:53 INFO - docopt == 0.6.1 04:08:53 INFO - functools32 == 3.2.3-2 04:08:53 INFO - jsonschema == 2.5.1 04:08:53 INFO - manifestparser == 1.1 04:08:53 INFO - mozInstall == 1.13 04:08:53 INFO - mozcrash == 1.0 04:08:53 INFO - mozdebug == 0.1 04:08:53 INFO - mozdevice == 0.51 04:08:53 INFO - mozfile == 1.2 04:08:53 INFO - mozhttpd == 0.7 04:08:53 INFO - mozinfo == 0.10 04:08:53 INFO - mozleak == 0.1 04:08:53 INFO - mozlog == 3.4 04:08:53 INFO - moznetwork == 0.27 04:08:53 INFO - mozprocess == 0.25 04:08:53 INFO - mozprofile == 0.28 04:08:53 INFO - mozrunner == 6.13 04:08:53 INFO - mozscreenshot == 0.1 04:08:53 INFO - mozsystemmonitor == 0.3 04:08:53 INFO - moztest == 0.8 04:08:53 INFO - mozversion == 1.4 04:08:53 INFO - psutil == 3.1.1 04:08:53 INFO - requests == 1.2.3 04:08:53 INFO - wsgiref == 0.1.2 04:08:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:08:53 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')]} 04:08:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08:53 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')]} 04:08:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08:53 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 0x109048c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10904c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87e258c200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d9ed30>, '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 0x108d68cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d8fbe8>, '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.eWc9x9yS6v/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.rHKmgx805d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 04:08:53 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 04:08:53 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 04:08:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eWc9x9yS6v/Render', 04:08:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:08:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:08:53 INFO - 'HOME': '/Users/cltbld', 04:08:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:08:53 INFO - 'LOGNAME': 'cltbld', 04:08:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:53 INFO - 'MOZ_NO_REMOTE': '1', 04:08:53 INFO - 'NO_EM_RESTART': '1', 04:08:53 INFO - 'PAGER': '/bin/cat', 04:08:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:08:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:53 INFO - 'PWD': '/builds/slave/test', 04:08:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:08:53 INFO - 'SHELL': '/bin/bash', 04:08:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rHKmgx805d/Listeners', 04:08:53 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 04:08:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:08:53 INFO - 'USER': 'cltbld', 04:08:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:08:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:08:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:53 INFO - 'XPC_FLAGS': '0x0', 04:08:53 INFO - 'XPC_SERVICE_NAME': '0', 04:08:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:08:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:53 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)) 04:08:53 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)) 04:08:53 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)) 04:08:53 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)) 04:08:53 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)) 04:08:53 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)) 04:08:53 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)) 04:08:53 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)) 04:08:53 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)) 04:08:53 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 04:08:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:08:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:08:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:08:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:08:54 INFO - Downloading marionette_driver-1.4.0.tar.gz 04:08:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 04:08:54 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)) 04:08:54 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)) 04:08:54 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)) 04:08:54 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)) 04:08:54 INFO - Installing collected packages: marionette-driver 04:08:54 INFO - Running setup.py install for marionette-driver 04:08:55 INFO - Successfully installed marionette-driver 04:08:55 INFO - Cleaning up... 04:08:55 INFO - Return code: 0 04:08:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:08:55 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')]} 04:08:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08:55 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')]} 04:08:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08:55 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 0x109048c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10904c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87e258c200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d9ed30>, '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 0x108d68cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d8fbe8>, '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.eWc9x9yS6v/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.rHKmgx805d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 04:08:55 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 04:08:55 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 04:08:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eWc9x9yS6v/Render', 04:08:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:08:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:08:55 INFO - 'HOME': '/Users/cltbld', 04:08:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:08:55 INFO - 'LOGNAME': 'cltbld', 04:08:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:55 INFO - 'MOZ_NO_REMOTE': '1', 04:08:55 INFO - 'NO_EM_RESTART': '1', 04:08:55 INFO - 'PAGER': '/bin/cat', 04:08:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:08:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:55 INFO - 'PWD': '/builds/slave/test', 04:08:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:08:55 INFO - 'SHELL': '/bin/bash', 04:08:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rHKmgx805d/Listeners', 04:08:55 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 04:08:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:08:55 INFO - 'USER': 'cltbld', 04:08:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:08:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:08:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:55 INFO - 'XPC_FLAGS': '0x0', 04:08:55 INFO - 'XPC_SERVICE_NAME': '0', 04:08:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:08:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:08:55 INFO - Cleaning up... 04:08:55 INFO - Return code: 0 04:08:55 INFO - Running post-action listener: _resource_record_post_action 04:08:55 INFO - Running post-action listener: _start_resource_monitoring 04:08:55 INFO - Starting resource monitoring. 04:08:55 INFO - [mozharness: 2017-06-19 11:08:55.349438Z] Finished create-virtualenv step (success) 04:08:55 INFO - [mozharness: 2017-06-19 11:08:55.350441Z] Running install step. 04:08:55 INFO - Running pre-action listener: _resource_record_pre_action 04:08:55 INFO - Running main action method: install 04:08:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:08:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:08:55 INFO - Reading from file tmpfile_stdout 04:08:55 INFO - Detecting whether we're running mozinstall >=1.0... 04:08:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:08:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:08:55 INFO - Reading from file tmpfile_stdout 04:08:55 INFO - Output received: 04:08:55 INFO - Usage: mozinstall [options] installer 04:08:55 INFO - Options: 04:08:55 INFO - -h, --help show this help message and exit 04:08:55 INFO - -d DEST, --destination=DEST 04:08:55 INFO - Directory to install application into. [default: 04:08:55 INFO - "/builds/slave/test"] 04:08:55 INFO - --app=APP Application being installed. [default: firefox] 04:08:55 INFO - mkdir: /builds/slave/test/build/application 04:08:55 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'] 04:08:55 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 04:09:17 INFO - Reading from file tmpfile_stdout 04:09:17 INFO - Output received: 04:09:17 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 04:09:17 INFO - Running post-action listener: _resource_record_post_action 04:09:17 INFO - [mozharness: 2017-06-19 11:09:17.232222Z] Finished install step (success) 04:09:17 INFO - [mozharness: 2017-06-19 11:09:17.232409Z] Running setup-mitmproxy step. 04:09:17 INFO - Running pre-action listener: _resource_record_pre_action 04:09:17 INFO - Running main action method: setup_mitmproxy 04:09:17 INFO - Skipping: mitmproxy is not required 04:09:17 INFO - Running post-action listener: _resource_record_post_action 04:09:17 INFO - [mozharness: 2017-06-19 11:09:17.232991Z] Finished setup-mitmproxy step (success) 04:09:17 INFO - [mozharness: 2017-06-19 11:09:17.233104Z] Running run-tests step. 04:09:17 INFO - Running pre-action listener: _resource_record_pre_action 04:09:17 INFO - Running main action method: run_tests 04:09:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:09:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:09:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:09:17 INFO - 2017-06-19 04:09:17.246 screenresolution[2942:53745] starting screenresolution argv=screenresolution get 04:09:17 INFO - 2017-06-19 04:09:17.249 screenresolution[2942:53745] Display 0: 1600x1200x32@60 04:09:17 INFO - 2017-06-19 04:09:17.254 screenresolution[2943:53747] starting screenresolution argv=screenresolution list 04:09:17 INFO - Available Modes on Display 0 04:09:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:09:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:09:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:09:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:09:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:09:17 INFO - Intel Iris: 04:09:17 INFO - Chipset Model: Intel Iris 04:09:17 INFO - Type: GPU 04:09:17 INFO - Bus: Built-In 04:09:17 INFO - VRAM (Dynamic, Max): 1536 MB 04:09:17 INFO - Vendor: Intel (0x8086) 04:09:17 INFO - Device ID: 0x0a2e 04:09:17 INFO - Revision ID: 0x0009 04:09:17 INFO - Displays: 04:09:17 INFO - Display: 04:09:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:09:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:09:17 INFO - Display Serial Number: 1600x1200 60 04:09:17 INFO - Main Display: Yes 04:09:17 INFO - Mirror: Off 04:09:17 INFO - Online: Yes 04:09:17 INFO - Rotation: Supported 04:09:17 INFO - Return code: 0 04:09:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:09:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:09:17 INFO - Python 2.7.3 04:09:17 INFO - Return code: 0 04:09:17 INFO - grabbing minidump binary from tooltool 04:09: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')]} 04:09:17 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 0x108d9ed30>, '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 0x108d68cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d8fbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:09:17 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 04:09:17 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 04:09:17 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 04:09:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:09:17 INFO - Return code: 0 04:09:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:09:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:09:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:09:17 INFO - ENV: RUST_BACKTRACE is now 1 04:09:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:09:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:09:17 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/Fnm0frauRh25-hNetyOeSg/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0029', '--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 04:09:17 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/Fnm0frauRh25-hNetyOeSg/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0029 --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 04:09:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eWc9x9yS6v/Render', 04:09:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:09:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:09:17 INFO - 'HOME': '/Users/cltbld', 04:09:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:09:17 INFO - 'LOGNAME': 'cltbld', 04:09:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:09:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:09:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:17 INFO - 'MOZ_NO_REMOTE': '1', 04:09:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:09:17 INFO - 'NO_EM_RESTART': '1', 04:09:17 INFO - 'PAGER': '/bin/cat', 04:09:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:09:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:17 INFO - 'PWD': '/builds/slave/test', 04:09:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:09:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:09:17 INFO - 'RUST_BACKTRACE': '1', 04:09:17 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:09:17 INFO - 'SHELL': '/bin/bash', 04:09:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rHKmgx805d/Listeners', 04:09:17 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 04:09:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:09:17 INFO - 'USER': 'cltbld', 04:09:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:09:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:09:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:17 INFO - 'XPC_FLAGS': '0x0', 04:09:17 INFO - 'XPC_SERVICE_NAME': '0', 04:09:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:09:17 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/Fnm0frauRh25-hNetyOeSg/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0029', '--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 04:09:17 INFO - mozversion application_buildid: 20170619023922 04:09:17 INFO - mozversion application_changeset: ea84df3bdb9982a8f52adff05bcf2f5d1bef6189 04:09:17 INFO - mozversion application_display_name: FirefoxDeveloperEdition 04:09:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:09:17 INFO - mozversion application_name: Firefox 04:09:17 INFO - mozversion application_remotingname: firefox-dev 04:09:17 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 04:09:17 INFO - mozversion application_vendor: Mozilla 04:09:17 INFO - mozversion application_version: 55.0 04:09:17 INFO - mozversion platform_buildid: 20170619023922 04:09:17 INFO - mozversion platform_changeset: ea84df3bdb9982a8f52adff05bcf2f5d1bef6189 04:09:17 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 04:09:17 INFO - mozversion platform_version: 55.0 04:09:17 INFO - using testdate: 1497870557 04:09:17 INFO - actual date: 1497870557 04:09:17 INFO - starting webserver on 'localhost:61627' 04:09:17 INFO - SUITE-START | Running 2 tests 04:09:17 INFO - TEST-START | dromaeo_css 04:09:17 INFO - operating with platform_type : mac_ 04:09:17 INFO - Initialising browser for dromaeo_css test... 04:09:17 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:61627/getInfo.html -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmp9FY5yX/profile 04:09:17 INFO - TEST-INFO | started process 2953 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:61627/getInfo.html -foreground) 04:09:51 INFO - TEST-INFO | 2953: killed by SIGTERM 04:09:51 INFO - Browser initialized. 04:09:51 INFO - Running cycle 1/1 for dromaeo_css test... 04:09:51 INFO - TEST-INFO | started process 2960 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmp9FY5yX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:09:53 INFO - PID 2960 | 2017-06-19 04:09:53.266 plugin-container[2961:54364] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 04:09:53 INFO - PID 2960 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:09:53 INFO - PID 2960 | 2017-06-19 04:09:53.266 plugin-container[2961:54364] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9717, name = 'com.apple.CFPasteboardClient' 04:09:53 INFO - PID 2960 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:09:53 INFO - PID 2960 | 2017-06-19 04:09:53.266 plugin-container[2961:54364] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:09:54 INFO - PID 2960 | 2017-06-19 04:09:54.039 plugin-container[2962:54409] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 04:09:54 INFO - PID 2960 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:09:54 INFO - PID 2960 | 2017-06-19 04:09:54.039 plugin-container[2962:54409] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 04:09:54 INFO - PID 2960 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:09:54 INFO - PID 2960 | 2017-06-19 04:09:54.039 plugin-container[2962:54409] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:10:03 INFO - PID 2960 | RSS: Main: 214765568 04:10:03 INFO - PID 2960 | 04:13:08 INFO - PID 2960 | Cycle 1(1): loaded http://localhost:61627/tests/dromaeo/cssquery-dojo.html (next: http://localhost:61627/tests/dromaeo/cssquery-ext.html) 04:13:10 INFO - PID 2960 | RSS: Main: 229257216 04:13:10 INFO - PID 2960 | 04:16:11 INFO - PID 2960 | Cycle 1(1): loaded http://localhost:61627/tests/dromaeo/cssquery-ext.html (next: http://localhost:61627/tests/dromaeo/cssquery-jquery.html) 04:16:12 INFO - PID 2960 | RSS: Main: 239194112 04:16:12 INFO - PID 2960 | 04:19:14 INFO - PID 2960 | Cycle 1(1): loaded http://localhost:61627/tests/dromaeo/cssquery-jquery.html (next: http://localhost:61627/tests/dromaeo/cssquery-mootools.html) 04:19:14 INFO - PID 2960 | RSS: Main: 242077696 04:19:14 INFO - PID 2960 | 04:22:16 INFO - PID 2960 | Cycle 1(1): loaded http://localhost:61627/tests/dromaeo/cssquery-mootools.html (next: http://localhost:61627/tests/dromaeo/cssquery-prototype.html) 04:22:17 INFO - PID 2960 | RSS: Main: 244502528 04:22:17 INFO - PID 2960 | 04:25:19 INFO - PID 2960 | Cycle 1(1): loaded http://localhost:61627/tests/dromaeo/cssquery-prototype.html (next: http://localhost:61627/tests/dromaeo/cssquery-yui.html) 04:25:20 INFO - PID 2960 | RSS: Main: 245477376 04:25:20 INFO - PID 2960 | 04:28:22 INFO - PID 2960 | Cycle 1(1): loaded http://localhost:61627/tests/dromaeo/cssquery-yui.html (next: http://localhost:61627/tests/dromaeo/cssquery-dojo.html) 04:28:22 INFO - PID 2960 | RSS: Main: 243986432 04:28:22 INFO - PID 2960 | 04:28:22 INFO - PID 2960 | __start_tp_report 04:28:22 INFO - PID 2960 | _x_x_mozilla_page_load 04:28:22 INFO - PID 2960 | _x_x_mozilla_page_load_details 04:28:22 INFO - PID 2960 | |i|pagename|runs| 04:28:22 INFO - PID 2960 | |0;dojo.html;2964.8814047438104;2983.9403211935746;3528.4530897710833;3598.6401359864017;3652.36083685355;6052.213212282408;6286.119943207948;6290.968545157273;6362.04569314603;6473.708683109259;6355.428011478977;6413.8075857724225;6417.839554011152;6426.646534440604;6445.1943507061615;5526.364468086162;5607.803726869561;5613.354464236604;5644.5484361251;5707.800226992056;5612;5618.297712785922;5637.069883469223;5645.576581756364;5694.971525142369;5469.671819690791;5495.670259784426;5523.7238138092935;5553.305836770403;5561.582881283879;5499.257600223998;5544.695041772701;5626.465485778845;5632.9436705632825;5675.489205971441;5559.360673522516;5602.439756024406;5609.803656872031;5614.859628509279;5625.831225063254;5357.08929481989;6230.7819226326865;6255.218097737783;6378.681065946684;6394.168758061469;6928.61892595907;6936.410405115571;6940.410065144469;6942.271061538516;6950.992106144581;6783.457323414115;6831.419329357011;6894.482913781436;6986.336298051677;7042.0493233413345;6442.774502892376;6515.51133664977;6521.93478065218;6531.3468653134305;6538.542302038907;6902.378785909293;6986.615736134561;7182.066331376887;7188.712451501881;7215.242399547972;5492.725363731797;6635.303293154149;6642.236142843538;6654.201495820533;6670.566413183127;6869.931300686929;6951.7914462566205;6960.164780226304;6971.5468494547695;6974.895376569356;6883.414909732629;6899.655017249117;6917.169939607279;6921.792346229621;6929.376356127872;5315.335583052119;6891.655417229219;6897.896531552031;6911.75808846688;6971.755988540377;5358.839234822962;5384;5385.084535628953;5390.029794636925;5422.267993820863;4120.938185927213;4753.35829662998;4772.832950846704;4911.803527858846;4951.232558953368;5251.448597897166;5330.466953304638;5335.946640533545;6453.838654033687;6460.644664543402;7013.473989450812;7024.297570242935;7028.437724981989;7033.929660703429;7060.152781666233;5694.743736531866;6458.870822583616;6462.063000864849;6496.707648155844;6502.154719886385;5960.284765828128;6046;6061.121137435047;6079.665618390943;6133.355997620275;7008.22909479967;7027.7188912442925;7057.117860267466;7093.787186384417;7155.856882862209;6071.2714474263375;6602.966985165043;6801.1158549388265;6868.450523958171;6869.210040845167;6412.166418365583;6515.12045873801;6974.734075772759;6993.545419547713;7003.964980175067;5566.91649625264;6696.19645642526;6741.662916854039;6742.561733487504;6781.491388146008;6797.524173308018;6875.931240687529;6887.139107611549;6917.757878474229;7022.683979220845;6774.220964589135;6800.251972283144;6804.285550017375;6840.144981877265;6850.109485766819;6777.864442711019;6788.321167883172;6821.761238356634;6825.795226143224;6846.110005699227;6450.612963222222;6527.11883895668;6554.4756419487285;6593.571417858016;6709.295523969913;5587.776488940397;6617.66911654425;6668.599884006975;6682.565633233824;6705.4635629150525;6508.869822603616;6558.278589355072;6600.273969863407;6634.137562116894;6643.368879956396 04:28:22 INFO - PID 2960 | |1;ext.html;783.9925695481307;841.9410641255115;848.6138806842887;866.0689758509602;875.9737207883766;39984.80083595401;41215.793921030425;41261.79369103158;41264.58735412645;41363.58636413635;41165.17669646613;41382.96542586437;41428.58571414285;41459.37810932838;41463.58536414635;7653.579053152061;7730.652120654556;7757.689692412297;7768.689252429896;7770.378369730408;7654.808629784245;7743.2256774322395;7750.8449831003345;7753.457257991943;7770.4560680752365;7690.884636730426;7726.111497177841;7737.497062690907;7757.069151701804;7762.805929851771;8374.455660382086;8581.871271930926;8631.870521942175;8634.136586341378;8681.21869031787;8438.36712246584;8446.197611226924;8520.74437766868;8524.957375213084;8546.70086546974;38472.65345712915;38533.843984680505;38635.613643863486;38715.41926871099;38721;3134.2947836736553;3585.480105384731;3629.800360980145;3633.019084847103;3642.453631955228;31555.684443155733;31578.894738684037;31580.684193158006;31704.841475792473;31959.360812783845;3077.3691393264326;3093.0720783765005;3140.309131990958;3161.5573819665265;3189.888353907602;257955;262862.6856865723;263149;315867;316997;39663.603363965995;49188.75405622949;49373;49467;49790.0041999158;52308.738456307474;52481;52622.47377526176;52700.73649631727;52965.7351713239;15443.61390965235;15514.689706205812;15598.298076586581;15731.44939927097;15835.445759398366;12590.937045314715;12714.936425317814;12807.167534110253;12832.871671283354;12846.743065138831;11505.827412588818;14195.78706319406;14214.360353784105;14229.430822766974;14294.928525357307;4637.327587499861;5703.17303990919;5825.747464295112;6093.847653808691;6134.601250918676;892.4377642085444;927.679950417105;931.9860202096974;1013.4122209118693;1041.354360296621;23628.05487780504;23835.642465363035;31369.05892823219;31887.681123188937;32228.83885580604;1015.21320976244;1028.4035259549444;1108.5233349659563;1118.6140781430393;1127.813910704741;11334.603288885015;12669.809952850715;13180.406881690347;13239.073264871558;13299.069065165346;349604;353427;353539;353546;355413;56450.15324770132;56979;57108.42891571031;57202.427975720544;57408.71295643579;1310.5675127207999;1493.4474244529376;1536.7310720623846;1537.7232098222203;1569.2232344989304;22990.310290691305;23574.88212558926;23589.646155308026;23684.28947131589;24475.877620611784;34288.48567271443;40886.59113408828;41261.79369103135;41264.793676031426;41341.37987930123;310962;310982.8901710954;312035;312747;312787;34331;35486.11284717903;36473.45289820708;36690.44964325484;36696.81651591832;40356.798216008734;44568.88577785451;44916.326255105545;45343.63969080933;47486.28770568375;24862.627060594466;29664.406711866075;30395.392092158476;30469.390612188075;32413.837930810194;30986.380272394876;31452.84273578709;31932;32341.35317293688;32418;36984.815075924445;38193;38845.417318739674;39069.80465097656;39111.6088839108;30261.546076809307;31313;31612.051638450885;31714.84142579272;33206.16984575405;24741.25776226716;25067.373315666526;25149.622755659046;25320;25481.617775733008 04:28:22 INFO - PID 2960 | |2;jquery.html;436.26707132018214;511.1744532579882;515.9355080614923;528.6510902804149;538.8172960352028;21796.78203217967;22244.8887755561;22606.982685779138;23072;23373.415664608394;23832.64251036236;24336.878315608395;24662.630060549105;24693.753062469368;24722.87638561805;6259.186305780254;6332.905006424908;6335.588186767845;6378.681065946684;6380.744770209186;5454.890902181954;6264.185655864754;6329.177206963089;6378.915584350649;6383.329750376213;5661.46216109471;6238.251409830827;6273.027680709497;6328.11406403104;6347.61914285142;5959.731812068446;6262.906056409158;6311.495080393581;6314.7474101035905;6320.652364120017;5712.343080545707;5741.483266505993;5747.195392645033;5777.306723193244;5788.27646544182;28308.575371369447;28492.44950088228;29694.257643558867;29787;30054.248643783863;2256.097560975623;2281.5779080869925;2292.209187830196;2302.3553405046446;2304.0092760113143;18704.158312875952;19218.135183916616;19257.22971081155;19280.614387712307;19323.710144347846;1908.761404824397;2189.9781002190093;2210.502636906699;2244.0799272298277;2258.6837842702034;161869.3813061866;168019;170865;171868;212911;14000.929995349958;15398.230088495531;15464.304106315018;15775.68448631044;15808.680952617802;20584.794152058286;20586.897065514575;20587.279445219345;20589.691154632692;20625.381238562866;10213.33613315132;10220.642277520252;10240.078392944672;10242.975702429698;10317.246068168279;9372.718818435458;9374.578143983537;9491.952540237255;9526.952365238129;9564.61741530345;7730.342920851791;8849.336299777542;9083.273338132933;9196.08039196075;9249.16757491829;2492.5762620354585;2624.8162628616196;2626.9474610508064;2628.7502687244682;2640.917223938176;883.1786438612153;899.4603238057114;899.6581299106298;917.7426925112505;973.7030205787162;20475.07862146207;20691.27580534674;20762.89618551928;20897.976975965346;21556.13775448996;859.7678626770862;959.9904000960041;985.4136788610765;994.1152374386656;1015.8171529124828;10806.297590656739;10926;10934.890651093387;11216.733830746472;11234.606788762518;568122;577445;577680;585931;596163;150370;150458.24770876075;151551;152401;152862;1235.932023738703;1236.0914727674972;1284.441268048402;1286.3568215892053;1287.9806802897965;21048.26331078405;21257.830819305087;23812.52374952526;23867.164649237195;24182.516349672554;72859.27140728525;74325.62837185779;75043.24956750582;75244.87132692905;75538.48923021619;95989;96806;98969;100030;100836;16876.57808554796;17471.56321091983;17891.642167156846;17935.551611209823;17956.730649040015;18565.793223440432;18828.152733127317;18832.717509237646;18878.71681924745;19174.52063698363;14476.276186190858;15456.690866182838;15468.690626187637;15509.99185052968;15527.922360388126;13603.79594306106;13622.863771362556;13624.931875340957;13680;14121.929390352983;15648.687026259184;15732.528024159294;15753;15786.447474338343;16044.598885027968;16089.75865361997;17039.91480042592;17269.222884969957;17455;17494;10846.728831778953;12082.697932551757;15103.32035058403;15186.848131518544;15706.842931570995 04:28:22 INFO - PID 2960 | |3;mootools.html;1188.3582865252763;1426.108682073704;1430.4278288684525;1445.0462694621551;1456.0244636093817;13667;14129.222892740909;14243.928780356084;14248.073875198104;14256.501022464228;13344.59966201013;13757.931210343935;13758.587242382719;13777.311134443264;13881.791773123412;2760.309922519375;2797.734215249548;2798.118592643321;2808.606795048695;2816.774658027353;2688.852113133777;2798.8040837141307;2805.3267215868145;2806.859657017151;2815.1132393296048;2770.570561562951;2785.6935737068907;2788.4423115376903;2799.0902956539207;2804.383035732136;2735.411886444414;2761.9171424857086;2766.8478233697138;2768.0865314446182;2779.249602607305;2662.4142688608476;2718.972810271892;2786.958195627067;2792.8463934483593;2799.790015748827;13447.99140064499;13522.053456258065;13543.322833858367;13556.864431355758;13602.863971360259;6436.774712885027;6553.2136143663065;7679.462437629368;7707.6916923323;7772;4200.432941552882;4568.97198130418;4810.35060266863;4871.342368780205;4941.45643979162;1373.7595920713836;1374.3197117426837;1374.9175049497062;1388.2780953903914;1393.0666453476194;18772;19196;20103.39689809308;20229.291974780957;20412.5917481651;6076.635401875901;6209.410106039919;6682.231543372574;6922.06552115468;6983.057287266154;10645.78708425827;14409.78385324221;14490.565283041526;14497.927510362591;14548.781768273484;7281.526700764433;7538.434617403607;7623.313901748758;7651.808704782425;7660.463767536219;6845.349691779273;6995.300469952964;7016.719331226694;7090.078289822393;7109.324614161646;5267.71027593486;5279.47205279469;5299.973500132474;5316.627836051517;5339.092354299778;1511.2896938439135;1532.946346877854;1540.4839378808;1551.9379224831107;1556.089687532806;705.340506626306;809.3929552835374;838.2455789789238;841.2302742990217;854.598338780772;6195.31851496335;7730.226977302224;8480.067192608809;8566.18621230982;8843.734687959372;974.4153507895206;977.8444690672235;992.3252188511881;998.5157198758596;1016.7051555048953;4798.880027999258;4922.187839006596;4940.6788558743565;4983.9252411213865;5252.369715634149;18302.816971830376;18836.434906952814;18870.056497175086;18876;18904.338348157748;17842.643147136983;18588.25646974106;18820.623587528447;18828.905855470908;18944.71582926257;9548.665796697082;9700.417974921385;9710.417374957526;9749.707508774749;9751.268654850914;6811;7624.618769061635;7840.607969601383;8505.362097842562;8612.354073444641;15849.603759906095;16257.268422921234;16560.834391655928;16748.078855663054;16789.244483998493;15215.847841521443;17530.47408577745;17586.472405827844;17589.120543972494;17817.554561136076;9237.538123093684;9622.51887405641;10389.636362727268;10877.456127193767;11172.273802202832;12095.092868034757;12609.621711348675;12675.556355527513;12767.425465853874;12779.105462617528;9926.801463970825;9990.400575965465;10227.48862556884;10570.735731606772;10618.09746171584;8737.427263092704;8751.774751534664;9821.950890245504;9951.800963980824;10345.482725863527;9210;9309.022552631875;9319.767005824908;9324.927633322255;9365.297602679962;6716.194056713226;6741.898871516833;6895.6552172390175;6920.411765000032;7120.572765634079;5360.517553420211;6876.518643694893;7095.858082838417;7140.857182856417;7149.070620819468 04:28:22 INFO - PID 2960 | |4;prototype.html;438.6052552702569;458.80042181650987;466.5730856266516;476.0787875460981;483.0893765252498;5494.5054945054935;6004.94913390156;6472.320406357336;6531.542792004561;6533.803985880425;6176.84557886052;6229.785191887584;6239.594426362295;6252.249730032391;6362.331955144701;4939.777710003041;4948.8267910623135;4951.55436010759;4957.033378491177;4972.378452693422;4297.527272000078;4512.894340886471;4594.724316541002;4965;4968.925466118011;4921.941782516758;4945.986072855056;4949.133901567229;4987.900241995158;4996.900061998758;4616.838410655628;4935.80256789728;4940.135476291653;4958.900821983576;4972.428170760372;3756.605556416564;4411.316245981878;4589.2198326284615;4600.6549508786975;4638.605718513931;5487;5601.60788744788;6275.995840665517;6292.4966002719675;6329.702411005779;5474.343078830526;5909.079549146544;6095.329513753483;6128.724207410677;6134;6720.428763555104;6855.897161542531;6961.199462061826;6991.720331186746;6992.720291188346;5384.892302153974;5395.676259424408;5410.161424979135;5414.620976531644;5420.105682562373;6424.5502814802985;6944.8540990736465;7283.490155689051;7350.77947661571;7370.852582948364;5516.586256030814;5521.806736764194;5531.640443371168;5538.978272930432;5580.883823235288;4731.52684731531;4937.481564435682;5101.285819985205;5308.893822123536;5619.550435965113;5141.897162056738;5865.914805760948;6151.569390142737;6159;6168.506519478431;6427.421532062137;6443.645599491979;6821.386075253251;7031.542949708252;7058.223595404502;1527.9083255004734;1616.83023282556;1631.8939268947718;1919.7521610952992;1924.7112933059873;1064.6167379743285;1186.4957393108068;1263.5072321794507;1367.9863201368057;1373.1623709537175;5133.948660513347;5887.646741195542;5891.7054147292465;6399.8080057598345;6468.191476065492;5470.100846551334;6153.1693221415435;6955.65221738911;7023.473239507058;7138.6085661303005;6382.297947225801;6397.776077837253;6408.423241908251;6410.8717825643225;6428.678566071678;2962.0225325642486;3198.952015719766;3586;3685.3734865073;3720.069982504374;5995.640261584319;6907.654617269116;7226.591779014797;7346.485745997729;7375.459779305856;6003.339632640406;7032.402245809163;7292.489525733148;7341.192468828424;7382.298681625237;6097.725602347817;7030.769615317299;7306.634668266565;7318.560886346836;7370.852582948311;5875.680240664011;6999.895001574879;7046.224915259215;7118.572885626879;7141.250168732208;5486;5821.330546987151;6130.080487926846;6952.44380449573;7015.790525684549;5416.458354164552;5643.88712225745;7135.825542057954;7225.290516056479;7278.890816637861;4540;4575.58819706228;4885.267209918541;4904.337914381513;4920.852374428773;6729.495287853332;6853.383195512428;7166.390856777024;7170.282971702996;7194.388476979515;6300.873982520416;6318.925782616874;6437.259715132633;6528.608283503005;6890.586564806128;5814.267294371639;6047.516198704006;6282.743451309665;6813.3527314905;6913;6684.197896252481;6718.294579069323;6760.898586521108;6761.526693131434;6765.019072234597;6841.555298905555;6858.897116543156;6882.0751180748985;6893.862122757417;6911.343422374866;6606.9008964866525;6653.73385064592;6742.292059333896;6754.358335958076;6759.391654751096 04:28:22 INFO - PID 2960 | |5;yui.html;826.1284345016006;834.3825569078883;837.932965362771;845.5053793530785;870.4820631724124;16761.580960475978;17212.053337066456;17290.91354543227;17500.387486437976;17500.56248593786;17510.474685759415;18483.722744158847;18698;18792.15435305411;18857.62284754304;1000.9249306302002;1009.4902074452391;1012.2610494339137;1014.786894752103;1019.2559431614926;852.433131967241;1028.0850043461326;1032.5250384822991;1036.3367444835312;1038;955.9856602150974;973.2894986659743;1057.9153667706564;1087.8640169978753;1092.6612750047511;1043.984340234897;1051.232600201853;1051.8842927277992;1056.75166335911;1058.1904842795268;764.4228607401432;779.1429427629619;781.6795114003288;788.9684412623488;791.0309870408703;17470.388536401293;17512.824871751247;17683.204255808392;17866.910665446718;17902.552436189068;17323;17466.388676396266;17630.471085867444;17852.55368615795;17916.372926947493;14807.0375425597;14816;14941.701165976729;14979.101253924799;14985.175815330153;3555.6444355564236;3557.786532808014;3583.5878873929573;3583.6774690278;3587.0852932502307;151046;155094;156160;157877.21061394733;158135.2093239538;13573;13651.863481365059;13682.384292706853;13791.241481718602;13972.86027139736;26000.34999125037;26591.734082659314;26718;26771;27641.447171056465;7956.681732730626;8066.314363279187;8094.31198348147;8122.83754324922;8136.633851476579;3387.458006718913;4071.1654110907193;4123.072308730511;4137.627613514738;4137.965508622844;4562.475315338778;4784.569388755029;4787.018661174451;4788.066327066258;4794.472608013116;839.8200528257807;877.7191298784455;971.4220039076738;973.6592192732487;977.3012296208103;599.5203836930525;632.0740115730476;653.9247986481521;660.4782222044531;682.4165338635495;8793.472391656522;11437.428128593703;11935.701607459883;12005;12544.560940367044;623.688155922039;623.7006237006262;635.548760380126;650.1125963060389;657.3098246840798;5481.451854814566;5662.405447428043;5693.231413759089;5712.171735098471;5993.250843644544;145282.09435811226;151798;152947;154104;158203;126621;131371;134516;137249;138294;108701;109985;111499;113654.29518557228;115740.42129789466;12007.639770806889;12131.818022729489;12341.642419334046;12577.559785407468;12989.155704879153;45197;46344.84137896579;48526.02947940951;49808.750956244985;50010.74973125556;65214.021789674145;81253.59373203096;81967;88392;88653;15875.745472178407;16025.27886245145;16943.576410589834;17215.31138754436;17288;18515.35196268124;19024;19051.71422428637;19083.950382728526;19246.23015079381;15046.774298385313;15366.615834604225;15404;15980.760288595447;17869.0172040539;16403.42588009414;18004.819951800313;18217.544561386072;18556.536086597942;18624.348147814762;17574.38489652856;17987.550311242325;18000.36998705039;18353.265869365077;18908.243670253036;10666.36001839892;11121.832207618083;11613.245139065933;11918.940405297919;11999.220050696676;10612.734681633021;13157.407916643582;13315;13359;13635.522756703467 04:28:22 INFO - PID 2960 | __end_tp_report 04:28:22 INFO - PID 2960 | __start_cc_report 04:28:22 INFO - PID 2960 | _x_x_mozilla_cycle_collect,399 04:28:22 INFO - PID 2960 | __end_cc_report 04:28:22 INFO - PID 2960 | __startTimestamp1497871702447__endTimestamp 04:28:22 INFO - PID 2960 | 04:28:22 INFO - PID 2960 | ------- Summary: start ------- 04:28:22 INFO - PID 2960 | Number of tests: 6 04:28:22 INFO - PID 2960 | 04:28:22 INFO - PID 2960 | [#0] dojo.html Cycles:165 Average:6269.03 Median:6527.12 stddev:817.33 (12.5%) stddev-sans-first:777.66 04:28:22 INFO - PID 2960 | Values: 2964.9 2983.9 3528.5 3598.6 3652.4 6052.2 6286.1 6291.0 6362.0 6473.7 6355.4 6413.8 6417.8 6426.6 6445.2 5526.4 5607.8 5613.4 5644.5 5707.8 5612.0 5618.3 5637.1 5645.6 5695.0 5469.7 5495.7 5523.7 5553.3 5561.6 5499.3 5544.7 5626.5 5632.9 5675.5 5559.4 5602.4 5609.8 5614.9 5625.8 5357.1 6230.8 6255.2 6378.7 6394.2 6928.6 6936.4 6940.4 6942.3 6951.0 6783.5 6831.4 6894.5 6986.3 7042.0 6442.8 6515.5 6521.9 6531.3 6538.5 6902.4 6986.6 7182.1 7188.7 7215.2 5492.7 6635.3 6642.2 6654.2 6670.6 6869.9 6951.8 6960.2 6971.5 6974.9 6883.4 6899.7 6917.2 6921.8 6929.4 5315.3 6891.7 6897.9 6911.8 6971.8 5358.8 5384.0 5385.1 5390.0 5422.3 4120.9 4753.4 4772.8 4911.8 4951.2 5251.4 5330.5 5335.9 6453.8 6460.6 7013.5 7024.3 7028.4 7033.9 7060.2 5694.7 6458.9 6462.1 6496.7 6502.2 5960.3 6046.0 6061.1 6079.7 6133.4 7008.2 7027.7 7057.1 7093.8 7155.9 6071.3 6603.0 6801.1 6868.5 6869.2 6412.2 6515.1 6974.7 6993.5 7004.0 5566.9 6696.2 6741.7 6742.6 6781.5 6797.5 6875.9 6887.1 6917.8 7022.7 6774.2 6800.3 6804.3 6840.1 6850.1 6777.9 6788.3 6821.8 6825.8 6846.1 6450.6 6527.1 6554.5 6593.6 6709.3 5587.8 6617.7 6668.6 6682.6 6705.5 6508.9 6558.3 6600.3 6634.1 6643.4 04:28:22 INFO - PID 2960 | 04:28:22 INFO - PID 2960 | [#1] ext.html Cycles:180 Average:47198.54 Median:179499.24 stddev:83465.49 (46.5%) stddev-sans-first:83626.88 04:28:22 INFO - PID 2960 | Values: 784.0 841.9 848.6 866.1 876.0 39984.8 41215.8 41261.8 41264.6 41363.6 41165.2 41383.0 41428.6 41459.4 41463.6 7653.6 7730.7 7757.7 7768.7 7770.4 7654.8 7743.2 7750.8 7753.5 7770.5 7690.9 7726.1 7737.5 7757.1 7762.8 8374.5 8581.9 8631.9 8634.1 8681.2 8438.4 8446.2 8520.7 8525.0 8546.7 38472.7 38533.8 38635.6 38715.4 38721.0 3134.3 3585.5 3629.8 3633.0 3642.5 31555.7 31578.9 31580.7 31704.8 31959.4 3077.4 3093.1 3140.3 3161.6 3189.9 257955.0 262862.7 263149.0 315867.0 316997.0 39663.6 49188.8 49373.0 49467.0 49790.0 52308.7 52481.0 52622.5 52700.7 52965.7 15443.6 15514.7 15598.3 15731.4 15835.4 12590.9 12714.9 12807.2 12832.9 12846.7 11505.8 14195.8 14214.4 14229.4 14294.9 4637.3 5703.2 5825.7 6093.8 6134.6 892.4 927.7 932.0 1013.4 1041.4 23628.1 23835.6 31369.1 31887.7 32228.8 1015.2 1028.4 1108.5 1118.6 1127.8 11334.6 12669.8 13180.4 13239.1 13299.1 349604.0 353427.0 353539.0 353546.0 355413.0 56450.2 56979.0 57108.4 57202.4 57408.7 1310.6 1493.4 1536.7 1537.7 1569.2 22990.3 23574.9 23589.6 23684.3 24475.9 34288.5 40886.6 41261.8 41264.8 41341.4 310962.0 310982.9 312035.0 312747.0 312787.0 34331.0 35486.1 36473.5 36690.4 36696.8 40356.8 44568.9 44916.3 45343.6 47486.3 24862.6 29664.4 30395.4 30469.4 32413.8 30986.4 31452.8 31932.0 32341.4 32418.0 36984.8 38193.0 38845.4 39069.8 39111.6 30261.5 31313.0 31612.1 31714.8 33206.2 24741.3 25067.4 25149.6 25320.0 25481.6 04:28:22 INFO - PID 2960 | 04:28:22 INFO - PID 2960 | [#2] jquery.html Cycles:180 Average:40305.14 Median:2250.09 stddev:99659.15 (4429.1%) stddev-sans-first:99893.76 04:28:22 INFO - PID 2960 | Values: 436.3 511.2 515.9 528.7 538.8 21796.8 22244.9 22607.0 23072.0 23373.4 23832.6 24336.9 24662.6 24693.8 24722.9 6259.2 6332.9 6335.6 6378.7 6380.7 5454.9 6264.2 6329.2 6378.9 6383.3 5661.5 6238.3 6273.0 6328.1 6347.6 5959.7 6262.9 6311.5 6314.7 6320.7 5712.3 5741.5 5747.2 5777.3 5788.3 28308.6 28492.4 29694.3 29787.0 30054.2 2256.1 2281.6 2292.2 2302.4 2304.0 18704.2 19218.1 19257.2 19280.6 19323.7 1908.8 2190.0 2210.5 2244.1 2258.7 161869.4 168019.0 170865.0 171868.0 212911.0 14000.9 15398.2 15464.3 15775.7 15808.7 20584.8 20586.9 20587.3 20589.7 20625.4 10213.3 10220.6 10240.1 10243.0 10317.2 9372.7 9374.6 9492.0 9527.0 9564.6 7730.3 8849.3 9083.3 9196.1 9249.2 2492.6 2624.8 2626.9 2628.8 2640.9 883.2 899.5 899.7 917.7 973.7 20475.1 20691.3 20762.9 20898.0 21556.1 859.8 960.0 985.4 994.1 1015.8 10806.3 10926.0 10934.9 11216.7 11234.6 568122.0 577445.0 577680.0 585931.0 596163.0 150370.0 150458.2 151551.0 152401.0 152862.0 1235.9 1236.1 1284.4 1286.4 1288.0 21048.3 21257.8 23812.5 23867.2 24182.5 72859.3 74325.6 75043.2 75244.9 75538.5 95989.0 96806.0 98969.0 100030.0 100836.0 16876.6 17471.6 17891.6 17935.6 17956.7 18565.8 18828.2 18832.7 18878.7 19174.5 14476.3 15456.7 15468.7 15510.0 15527.9 13603.8 13622.9 13624.9 13680.0 14121.9 15648.7 15732.5 15753.0 15786.4 16044.6 16089.8 17039.9 17269.2 17455.0 17494.0 10846.7 12082.7 15103.3 15186.8 15706.8 04:28:22 INFO - PID 2960 | 04:28:22 INFO - PID 2960 | [#3] mootools.html Cycles:180 Average:8337.70 Median:2799.44 stddev:5534.67 (197.7%) stddev-sans-first:5524.12 04:28:22 INFO - PID 2960 | Values: 1188.4 1426.1 1430.4 1445.0 1456.0 13667.0 14129.2 14243.9 14248.1 14256.5 13344.6 13757.9 13758.6 13777.3 13881.8 2760.3 2797.7 2798.1 2808.6 2816.8 2688.9 2798.8 2805.3 2806.9 2815.1 2770.6 2785.7 2788.4 2799.1 2804.4 2735.4 2761.9 2766.8 2768.1 2779.2 2662.4 2719.0 2787.0 2792.8 2799.8 13448.0 13522.1 13543.3 13556.9 13602.9 6436.8 6553.2 7679.5 7707.7 7772.0 4200.4 4569.0 4810.4 4871.3 4941.5 1373.8 1374.3 1374.9 1388.3 1393.1 18772.0 19196.0 20103.4 20229.3 20412.6 6076.6 6209.4 6682.2 6922.1 6983.1 10645.8 14409.8 14490.6 14497.9 14548.8 7281.5 7538.4 7623.3 7651.8 7660.5 6845.3 6995.3 7016.7 7090.1 7109.3 5267.7 5279.5 5300.0 5316.6 5339.1 1511.3 1532.9 1540.5 1551.9 1556.1 705.3 809.4 838.2 841.2 854.6 6195.3 7730.2 8480.1 8566.2 8843.7 974.4 977.8 992.3 998.5 1016.7 4798.9 4922.2 4940.7 4983.9 5252.4 18302.8 18836.4 18870.1 18876.0 18904.3 17842.6 18588.3 18820.6 18828.9 18944.7 9548.7 9700.4 9710.4 9749.7 9751.3 6811.0 7624.6 7840.6 8505.4 8612.4 15849.6 16257.3 16560.8 16748.1 16789.2 15215.8 17530.5 17586.5 17589.1 17817.6 9237.5 9622.5 10389.6 10877.5 11172.3 12095.1 12609.6 12675.6 12767.4 12779.1 9926.8 9990.4 10227.5 10570.7 10618.1 8737.4 8751.8 9822.0 9951.8 10345.5 9210.0 9309.0 9319.8 9324.9 9365.3 6716.2 6741.9 6895.7 6920.4 7120.6 5360.5 6876.5 7095.9 7140.9 7149.1 04:28:22 INFO - PID 2960 | 04:28:22 INFO - PID 2960 | [#4] prototype.html Cycles:180 Average:5622.43 Median:6142.78 stddev:1655.77 (27.0%) stddev-sans-first:1614.05 04:28:22 INFO - PID 2960 | Values: 438.6 458.8 466.6 476.1 483.1 5494.5 6004.9 6472.3 6531.5 6533.8 6176.8 6229.8 6239.6 6252.2 6362.3 4939.8 4948.8 4951.6 4957.0 4972.4 4297.5 4512.9 4594.7 4965.0 4968.9 4921.9 4946.0 4949.1 4987.9 4996.9 4616.8 4935.8 4940.1 4958.9 4972.4 3756.6 4411.3 4589.2 4600.7 4638.6 5487.0 5601.6 6276.0 6292.5 6329.7 5474.3 5909.1 6095.3 6128.7 6134.0 6720.4 6855.9 6961.2 6991.7 6992.7 5384.9 5395.7 5410.2 5414.6 5420.1 6424.6 6944.9 7283.5 7350.8 7370.9 5516.6 5521.8 5531.6 5539.0 5580.9 4731.5 4937.5 5101.3 5308.9 5619.6 5141.9 5865.9 6151.6 6159.0 6168.5 6427.4 6443.6 6821.4 7031.5 7058.2 1527.9 1616.8 1631.9 1919.8 1924.7 1064.6 1186.5 1263.5 1368.0 1373.2 5133.9 5887.6 5891.7 6399.8 6468.2 5470.1 6153.2 6955.7 7023.5 7138.6 6382.3 6397.8 6408.4 6410.9 6428.7 2962.0 3199.0 3586.0 3685.4 3720.1 5995.6 6907.7 7226.6 7346.5 7375.5 6003.3 7032.4 7292.5 7341.2 7382.3 6097.7 7030.8 7306.6 7318.6 7370.9 5875.7 6999.9 7046.2 7118.6 7141.3 5486.0 5821.3 6130.1 6952.4 7015.8 5416.5 5643.9 7135.8 7225.3 7278.9 4540.0 4575.6 4885.3 4904.3 4920.9 6729.5 6853.4 7166.4 7170.3 7194.4 6300.9 6318.9 6437.3 6528.6 6890.6 5814.3 6047.5 6282.7 6813.4 6913.0 6684.2 6718.3 6760.9 6761.5 6765.0 6841.6 6858.9 6882.1 6893.9 6911.3 6606.9 6653.7 6742.3 6754.4 6759.4 04:28:22 INFO - PID 2960 | 04:28:22 INFO - PID 2960 | [#5] yui.html Cycles:180 Average:27191.90 Median:17867.96 stddev:42581.82 (238.3%) stddev-sans-first:42655.26 04:28:22 INFO - PID 2960 | Values: 826.1 834.4 837.9 845.5 870.5 16761.6 17212.1 17290.9 17500.4 17500.6 17510.5 18483.7 18698.0 18792.2 18857.6 1000.9 1009.5 1012.3 1014.8 1019.3 852.4 1028.1 1032.5 1036.3 1038.0 956.0 973.3 1057.9 1087.9 1092.7 1044.0 1051.2 1051.9 1056.8 1058.2 764.4 779.1 781.7 789.0 791.0 17470.4 17512.8 17683.2 17866.9 17902.6 17323.0 17466.4 17630.5 17852.6 17916.4 14807.0 14816.0 14941.7 14979.1 14985.2 3555.6 3557.8 3583.6 3583.7 3587.1 151046.0 155094.0 156160.0 157877.2 158135.2 13573.0 13651.9 13682.4 13791.2 13972.9 26000.3 26591.7 26718.0 26771.0 27641.4 7956.7 8066.3 8094.3 8122.8 8136.6 3387.5 4071.2 4123.1 4137.6 4138.0 4562.5 4784.6 4787.0 4788.1 4794.5 839.8 877.7 971.4 973.7 977.3 599.5 632.1 653.9 660.5 682.4 8793.5 11437.4 11935.7 12005.0 12544.6 623.7 623.7 635.5 650.1 657.3 5481.5 5662.4 5693.2 5712.2 5993.3 145282.1 151798.0 152947.0 154104.0 158203.0 126621.0 131371.0 134516.0 137249.0 138294.0 108701.0 109985.0 111499.0 113654.3 115740.4 12007.6 12131.8 12341.6 12577.6 12989.2 45197.0 46344.8 48526.0 49808.8 50010.7 65214.0 81253.6 81967.0 88392.0 88653.0 15875.7 16025.3 16943.6 17215.3 17288.0 18515.4 19024.0 19051.7 19084.0 19246.2 15046.8 15366.6 15404.0 15980.8 17869.0 16403.4 18004.8 18217.5 18556.5 18624.3 17574.4 17987.6 18000.4 18353.3 18908.2 10666.4 11121.8 11613.2 11918.9 11999.2 10612.7 13157.4 13315.0 13359.0 13635.5 04:28:22 INFO - PID 2960 | -------- Summary: end -------- 04:28:22 INFO - PID 2960 | 04:28:23 INFO - TEST-INFO | 2960: exit 0 04:28:23 INFO - TEST-OK | dromaeo_css | took 1145187ms 04:28:23 INFO - TEST-START | kraken 04:28:23 INFO - operating with platform_type : mac_ 04:28:23 INFO - Initialising browser for kraken test... 04:28:23 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:61627/getInfo.html -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpjV7GMI/profile 04:28:23 INFO - TEST-INFO | started process 2967 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:61627/getInfo.html -foreground) 04:28:56 INFO - TEST-INFO | 2967: killed by SIGTERM 04:28:56 INFO - Browser initialized. 04:28:56 INFO - Running cycle 1/1 for kraken test... 04:28:56 INFO - TEST-INFO | started process 2971 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpjV7GMI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:28:58 INFO - PID 2971 | 2017-06-19 04:28:58.441 plugin-container[2972:56651] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 04:28:58 INFO - PID 2971 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:28:58 INFO - PID 2971 | 2017-06-19 04:28:58.441 plugin-container[2972:56651] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x970b, name = 'com.apple.CFPasteboardClient' 04:28:58 INFO - PID 2971 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:28:58 INFO - PID 2971 | 2017-06-19 04:28:58.441 plugin-container[2972:56651] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:28:59 INFO - PID 2971 | 2017-06-19 04:28:59.223 plugin-container[2973:56695] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9437, name = 'com.apple.tsm.portname' 04:28:59 INFO - PID 2971 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:28:59 INFO - PID 2971 | 2017-06-19 04:28:59.223 plugin-container[2973:56695] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a23, name = 'com.apple.CFPasteboardClient' 04:28:59 INFO - PID 2971 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:28:59 INFO - PID 2971 | 2017-06-19 04:28:59.223 plugin-container[2973:56695] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:29:08 INFO - PID 2971 | RSS: Main: 215044096 04:29:08 INFO - PID 2971 | 04:29:11 INFO - PID 2971 | Cycle 1(1): loaded http://localhost:61627/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:61627/tests/kraken/driver.html?testName=audio-beat-detection) 04:29:12 INFO - PID 2971 | RSS: Main: 228315136 04:29:12 INFO - PID 2971 | 04:29:14 INFO - PID 2971 | Cycle 1(1): loaded http://localhost:61627/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:61627/tests/kraken/driver.html?testName=audio-dft) 04:29:15 INFO - PID 2971 | RSS: Main: 239067136 04:29:15 INFO - PID 2971 | 04:29:18 INFO - PID 2971 | Cycle 1(1): loaded http://localhost:61627/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:61627/tests/kraken/driver.html?testName=audio-fft) 04:29:19 INFO - PID 2971 | RSS: Main: 243621888 04:29:19 INFO - PID 2971 | 04:29:21 INFO - PID 2971 | Cycle 1(1): loaded http://localhost:61627/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:61627/tests/kraken/driver.html?testName=audio-oscillator) 04:29:22 INFO - PID 2971 | RSS: Main: 244858880 04:29:22 INFO - PID 2971 | 04:29:24 INFO - PID 2971 | Cycle 1(1): loaded http://localhost:61627/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:61627/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:29:25 INFO - PID 2971 | RSS: Main: 245219328 04:29:25 INFO - PID 2971 | 04:29:28 INFO - PID 2971 | Cycle 1(1): loaded http://localhost:61627/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:61627/tests/kraken/driver.html?testName=imaging-darkroom) 04:29:28 INFO - PID 2971 | RSS: Main: 251625472 04:29:28 INFO - PID 2971 | 04:29:31 INFO - PID 2971 | Cycle 1(1): loaded http://localhost:61627/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:61627/tests/kraken/driver.html?testName=imaging-desaturate) 04:29:32 INFO - PID 2971 | RSS: Main: 251613184 04:29:32 INFO - PID 2971 | 04:29:35 INFO - PID 2971 | Cycle 1(1): loaded http://localhost:61627/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:61627/tests/kraken/driver.html?testName=json-parse-financial) 04:29:36 INFO - PID 2971 | RSS: Main: 251310080 04:29:36 INFO - PID 2971 | 04:29:37 INFO - PID 2971 | Cycle 1(1): loaded http://localhost:61627/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:61627/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:29:38 INFO - PID 2971 | RSS: Main: 251600896 04:29:38 INFO - PID 2971 | 04:29:39 INFO - PID 2971 | Cycle 1(1): loaded http://localhost:61627/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:61627/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:29:40 INFO - PID 2971 | RSS: Main: 251019264 04:29:40 INFO - PID 2971 | 04:29:42 INFO - PID 2971 | Cycle 1(1): loaded http://localhost:61627/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:61627/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:29:43 INFO - PID 2971 | RSS: Main: 251297792 04:29:43 INFO - PID 2971 | 04:29:46 INFO - PID 2971 | Cycle 1(1): loaded http://localhost:61627/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:61627/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:29:47 INFO - PID 2971 | RSS: Main: 251691008 04:29:47 INFO - PID 2971 | 04:29:50 INFO - PID 2971 | Cycle 1(1): loaded http://localhost:61627/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:61627/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:29:50 INFO - PID 2971 | RSS: Main: 251420672 04:29:50 INFO - PID 2971 | 04:29:52 INFO - PID 2971 | Cycle 1(1): loaded http://localhost:61627/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:61627/tests/kraken/driver.html?testName=ai-astar) 04:29:52 INFO - PID 2971 | RSS: Main: 253808640 04:29:52 INFO - PID 2971 | 04:29:52 INFO - PID 2971 | __start_tp_report 04:29:52 INFO - PID 2971 | _x_x_mozilla_page_load 04:29:52 INFO - PID 2971 | _x_x_mozilla_page_load_details 04:29:52 INFO - PID 2971 | |i|pagename|runs| 04:29:52 INFO - PID 2971 | |0;ai-astar;103;115;109;155;170;145;113;105;117;117 04:29:52 INFO - PID 2971 | |1;audio-beat-detection;140;116;132;144;108;120;142;108;161;112 04:29:52 INFO - PID 2971 | |2;audio-dft;204;200;248;243;197;198;197;245;203;218 04:29:52 INFO - PID 2971 | |3;audio-fft;70;93;96;166;65;79;87;61;143;94 04:29:52 INFO - PID 2971 | |4;audio-oscillator;55;103;139;56;106;102;106;74;106;148 04:29:52 INFO - PID 2971 | |5;imaging-gaussian-blur;95;99;94;93;93;95;144;92;93;90 04:29:52 INFO - PID 2971 | |6;imaging-darkroom;155;146;97;98;96;97;97;97;95;97 04:29:52 INFO - PID 2971 | |7;imaging-desaturate;93;95;94;91;90;92;90;98;140;93 04:29:52 INFO - PID 2971 | |8;json-parse-financial;49;50;51;52;53;53;53;52;54;53 04:29:52 INFO - PID 2971 | |9;json-stringify-tinderbox;56;80;82;91;78;63;54;57;57;56 04:29:52 INFO - PID 2971 | |10;stanford-crypto-aes;81;82;78;106;82;96;93;104;77;79 04:29:52 INFO - PID 2971 | |11;stanford-crypto-ccm;146;129;144;142;149;141;135;139;142;160 04:29:52 INFO - PID 2971 | |12;stanford-crypto-pbkdf2;181;180;181;177;178;177;200;207;238;225 04:29:52 INFO - PID 2971 | |13;stanford-crypto-sha256-iterative;67;82;82;67;65;64;67;67;63;67 04:29:52 INFO - PID 2971 | __end_tp_report 04:29:52 INFO - PID 2971 | __start_cc_report 04:29:52 INFO - PID 2971 | _x_x_mozilla_cycle_collect,1021 04:29:52 INFO - PID 2971 | __end_cc_report 04:29:52 INFO - PID 2971 | __startTimestamp1497871792751__endTimestamp 04:29:52 INFO - PID 2971 | 04:29:52 INFO - PID 2971 | ------- Summary: start ------- 04:29:52 INFO - PID 2971 | Number of tests: 14 04:29:52 INFO - PID 2971 | 04:29:52 INFO - PID 2971 | [#0] ai-astar Cycles:10 Average:124.90 Median:117.00 stddev:23.18 (19.8%) stddev-sans-first:23.19 04:29:52 INFO - PID 2971 | Values: 103.0 115.0 109.0 155.0 170.0 145.0 113.0 105.0 117.0 117.0 04:29:52 INFO - PID 2971 | 04:29:52 INFO - PID 2971 | [#1] audio-beat-detection Cycles:10 Average:128.30 Median:136.00 stddev:18.15 (13.3%) stddev-sans-first:18.75 04:29:52 INFO - PID 2971 | Values: 140.0 116.0 132.0 144.0 108.0 120.0 142.0 108.0 161.0 112.0 04:29:52 INFO - PID 2971 | 04:29:52 INFO - PID 2971 | [#2] audio-dft Cycles:10 Average:215.30 Median:211.00 stddev:21.62 (10.2%) stddev-sans-first:22.55 04:29:52 INFO - PID 2971 | Values: 204.0 200.0 248.0 243.0 197.0 198.0 197.0 245.0 203.0 218.0 04:29:52 INFO - PID 2971 | 04:29:52 INFO - PID 2971 | [#3] audio-fft Cycles:10 Average:95.40 Median:83.00 stddev:33.91 (40.9%) stddev-sans-first:34.70 04:29:52 INFO - PID 2971 | Values: 70.0 93.0 96.0 166.0 65.0 79.0 87.0 61.0 143.0 94.0 04:29:52 INFO - PID 2971 | 04:29:52 INFO - PID 2971 | [#4] audio-oscillator Cycles:10 Average:99.50 Median:143.50 stddev:30.88 (21.5%) stddev-sans-first:28.24 04:29:52 INFO - PID 2971 | Values: 55.0 103.0 139.0 56.0 106.0 102.0 106.0 74.0 106.0 148.0 04:29:52 INFO - PID 2971 | 04:29:52 INFO - PID 2971 | [#5] imaging-gaussian-blur Cycles:10 Average:98.80 Median:93.50 stddev:16.05 (17.2%) stddev-sans-first:16.97 04:29:52 INFO - PID 2971 | Values: 95.0 99.0 94.0 93.0 93.0 95.0 144.0 92.0 93.0 90.0 04:29:52 INFO - PID 2971 | 04:29:52 INFO - PID 2971 | [#6] imaging-darkroom Cycles:10 Average:107.50 Median:97.00 stddev:22.78 (23.5%) stddev-sans-first:16.44 04:29:52 INFO - PID 2971 | Values: 155.0 146.0 97.0 98.0 96.0 97.0 97.0 97.0 95.0 97.0 04:29:52 INFO - PID 2971 | 04:29:52 INFO - PID 2971 | [#7] imaging-desaturate Cycles:10 Average:97.60 Median:93.00 stddev:15.09 (16.2%) stddev-sans-first:15.92 04:29:52 INFO - PID 2971 | Values: 93.0 95.0 94.0 91.0 90.0 92.0 90.0 98.0 140.0 93.0 04:29:52 INFO - PID 2971 | 04:29:52 INFO - PID 2971 | [#8] json-parse-financial Cycles:10 Average:52.00 Median:53.00 stddev:1.56 (2.9%) stddev-sans-first:1.22 04:29:52 INFO - PID 2971 | Values: 49.0 50.0 51.0 52.0 53.0 53.0 53.0 52.0 54.0 53.0 04:29:52 INFO - PID 2971 | 04:29:52 INFO - PID 2971 | [#9] json-stringify-tinderbox Cycles:10 Average:67.40 Median:70.50 stddev:13.81 (19.6%) stddev-sans-first:14.02 04:29:52 INFO - PID 2971 | Values: 56.0 80.0 82.0 91.0 78.0 63.0 54.0 57.0 57.0 56.0 04:29:52 INFO - PID 2971 | 04:29:52 INFO - PID 2971 | [#10] stanford-crypto-aes Cycles:10 Average:87.80 Median:81.50 stddev:11.01 (13.5%) stddev-sans-first:11.40 04:29:52 INFO - PID 2971 | Values: 81.0 82.0 78.0 106.0 82.0 96.0 93.0 104.0 77.0 79.0 04:29:52 INFO - PID 2971 | 04:29:52 INFO - PID 2971 | [#11] stanford-crypto-ccm Cycles:10 Average:142.70 Median:143.00 stddev:8.27 (5.8%) stddev-sans-first:8.69 04:29:52 INFO - PID 2971 | Values: 146.0 129.0 144.0 142.0 149.0 141.0 135.0 139.0 142.0 160.0 04:29:52 INFO - PID 2971 | 04:29:52 INFO - PID 2971 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:194.40 Median:190.50 stddev:22.28 (11.7%) stddev-sans-first:23.10 04:29:52 INFO - PID 2971 | Values: 181.0 180.0 181.0 177.0 178.0 177.0 200.0 207.0 238.0 225.0 04:29:52 INFO - PID 2971 | 04:29:52 INFO - PID 2971 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:67.00 stddev:6.95 (10.4%) stddev-sans-first:7.33 04:29:52 INFO - PID 2971 | Values: 67.0 82.0 82.0 67.0 65.0 64.0 67.0 67.0 63.0 67.0 04:29:52 INFO - PID 2971 | -------- Summary: end -------- 04:29:52 INFO - PID 2971 | 04:29:53 INFO - TEST-INFO | 2971: exit 0 04:29:53 INFO - TEST-OK | kraken | took 90498ms 04:29:53 INFO - SUITE-END | took 1235s 04:29:54 INFO - Completed test suite (00:20:36) 04:29:54 INFO - javascript benchmark 04:29:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8510.157290878515, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2964.8814047438104, 2983.9403211935746, 3528.4530897710833, 3598.6401359864017, 3652.36083685355, 6052.213212282408, 6286.119943207948, 6290.968545157273, 6362.04569314603, 6473.708683109259, 6355.428011478977, 6413.8075857724225, 6417.839554011152, 6426.646534440604, 6445.1943507061615, 5526.364468086162, 5607.803726869561, 5613.354464236604, 5644.5484361251, 5707.800226992056, 5612.0, 5618.297712785922, 5637.069883469223, 5645.576581756364, 5694.971525142369, 5469.671819690791, 5495.670259784426, 5523.7238138092935, 5553.305836770403, 5561.582881283879, 5499.257600223998, 5544.695041772701, 5626.465485778845, 5632.9436705632825, 5675.489205971441, 5559.360673522516, 5602.439756024406, 5609.803656872031, 5614.859628509279, 5625.831225063254, 5357.08929481989, 6230.7819226326865, 6255.218097737783, 6378.681065946684, 6394.168758061469, 6928.61892595907, 6936.410405115571, 6940.410065144469, 6942.271061538516, 6950.992106144581, 6783.457323414115, 6831.419329357011, 6894.482913781436, 6986.336298051677, 7042.0493233413345, 6442.774502892376, 6515.51133664977, 6521.93478065218, 6531.3468653134305, 6538.542302038907, 6902.378785909293, 6986.615736134561, 7182.066331376887, 7188.712451501881, 7215.242399547972, 5492.725363731797, 6635.303293154149, 6642.236142843538, 6654.201495820533, 6670.566413183127, 6869.931300686929, 6951.7914462566205, 6960.164780226304, 6971.5468494547695, 6974.895376569356, 6883.414909732629, 6899.655017249117, 6917.169939607279, 6921.792346229621, 6929.376356127872, 5315.335583052119, 6891.655417229219, 6897.896531552031, 6911.75808846688, 6971.755988540377, 5358.839234822962, 5384.0, 5385.084535628953, 5390.029794636925, 5422.267993820863, 4120.938185927213, 4753.35829662998, 4772.832950846704, 4911.803527858846, 4951.232558953368, 5251.448597897166, 5330.466953304638, 5335.946640533545, 6453.838654033687, 6460.644664543402, 7013.473989450812, 7024.297570242935, 7028.437724981989, 7033.929660703429, 7060.152781666233, 5694.743736531866, 6458.870822583616, 6462.063000864849, 6496.707648155844, 6502.154719886385, 5960.284765828128, 6046.0, 6061.121137435047, 6079.665618390943, 6133.355997620275, 7008.22909479967, 7027.7188912442925, 7057.117860267466, 7093.787186384417, 7155.856882862209, 6071.2714474263375, 6602.966985165043, 6801.1158549388265, 6868.450523958171, 6869.210040845167, 6412.166418365583, 6515.12045873801, 6974.734075772759, 6993.545419547713, 7003.964980175067, 5566.91649625264, 6696.19645642526, 6741.662916854039, 6742.561733487504, 6781.491388146008, 6797.524173308018, 6875.931240687529, 6887.139107611549, 6917.757878474229, 7022.683979220845, 6774.220964589135, 6800.251972283144, 6804.285550017375, 6840.144981877265, 6850.109485766819, 6777.864442711019, 6788.321167883172, 6821.761238356634, 6825.795226143224, 6846.110005699227, 6450.612963222222, 6527.11883895668, 6554.4756419487285, 6593.571417858016, 6709.295523969913, 5587.776488940397, 6617.66911654425, 6668.599884006975, 6682.565633233824, 6705.4635629150525, 6508.869822603616, 6558.278589355072, 6600.273969863407, 6634.137562116894, 6643.368879956396], "value": 6210.168540514887, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [783.9925695481307, 841.9410641255115, 848.6138806842887, 866.0689758509602, 875.9737207883766, 39984.80083595401, 41215.793921030425, 41261.79369103158, 41264.58735412645, 41363.58636413635, 41165.17669646613, 41382.96542586437, 41428.58571414285, 41459.37810932838, 41463.58536414635, 7653.579053152061, 7730.652120654556, 7757.689692412297, 7768.689252429896, 7770.378369730408, 7654.808629784245, 7743.2256774322395, 7750.8449831003345, 7753.457257991943, 7770.4560680752365, 7690.884636730426, 7726.111497177841, 7737.497062690907, 7757.069151701804, 7762.805929851771, 8374.455660382086, 8581.871271930926, 8631.870521942175, 8634.136586341378, 8681.21869031787, 8438.36712246584, 8446.197611226924, 8520.74437766868, 8524.957375213084, 8546.70086546974, 38472.65345712915, 38533.843984680505, 38635.613643863486, 38715.41926871099, 38721.0, 3134.2947836736553, 3585.480105384731, 3629.800360980145, 3633.019084847103, 3642.453631955228, 31555.684443155733, 31578.894738684037, 31580.684193158006, 31704.841475792473, 31959.360812783845, 3077.3691393264326, 3093.0720783765005, 3140.309131990958, 3161.5573819665265, 3189.888353907602, 257955.0, 262862.6856865723, 263149.0, 315867.0, 316997.0, 39663.603363965995, 49188.75405622949, 49373.0, 49467.0, 49790.0041999158, 52308.738456307474, 52481.0, 52622.47377526176, 52700.73649631727, 52965.7351713239, 15443.61390965235, 15514.689706205812, 15598.298076586581, 15731.44939927097, 15835.445759398366, 12590.937045314715, 12714.936425317814, 12807.167534110253, 12832.871671283354, 12846.743065138831, 11505.827412588818, 14195.78706319406, 14214.360353784105, 14229.430822766974, 14294.928525357307, 4637.327587499861, 5703.17303990919, 5825.747464295112, 6093.847653808691, 6134.601250918676, 892.4377642085444, 927.679950417105, 931.9860202096974, 1013.4122209118693, 1041.354360296621, 23628.05487780504, 23835.642465363035, 31369.05892823219, 31887.681123188937, 32228.83885580604, 1015.21320976244, 1028.4035259549444, 1108.5233349659563, 1118.6140781430393, 1127.813910704741, 11334.603288885015, 12669.809952850715, 13180.406881690347, 13239.073264871558, 13299.069065165346, 349604.0, 353427.0, 353539.0, 353546.0, 355413.0, 56450.15324770132, 56979.0, 57108.42891571031, 57202.427975720544, 57408.71295643579, 1310.5675127207999, 1493.4474244529376, 1536.7310720623846, 1537.7232098222203, 1569.2232344989304, 22990.310290691305, 23574.88212558926, 23589.646155308026, 23684.28947131589, 24475.877620611784, 34288.48567271443, 40886.59113408828, 41261.79369103135, 41264.793676031426, 41341.37987930123, 310962.0, 310982.8901710954, 312035.0, 312747.0, 312787.0, 34331.0, 35486.11284717903, 36473.45289820708, 36690.44964325484, 36696.81651591832, 40356.798216008734, 44568.88577785451, 44916.326255105545, 45343.63969080933, 47486.28770568375, 24862.627060594466, 29664.406711866075, 30395.392092158476, 30469.390612188075, 32413.837930810194, 30986.380272394876, 31452.84273578709, 31932.0, 32341.35317293688, 32418.0, 36984.815075924445, 38193.0, 38845.417318739674, 39069.80465097656, 39111.6088839108, 30261.546076809307, 31313.0, 31612.051638450885, 31714.84142579272, 33206.16984575405, 24741.25776226716, 25067.373315666526, 25149.622755659046, 25320.0, 25481.617775733008], "value": 17831.11937931348, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [436.26707132018214, 511.1744532579882, 515.9355080614923, 528.6510902804149, 538.8172960352028, 21796.78203217967, 22244.8887755561, 22606.982685779138, 23072.0, 23373.415664608394, 23832.64251036236, 24336.878315608395, 24662.630060549105, 24693.753062469368, 24722.87638561805, 6259.186305780254, 6332.905006424908, 6335.588186767845, 6378.681065946684, 6380.744770209186, 5454.890902181954, 6264.185655864754, 6329.177206963089, 6378.915584350649, 6383.329750376213, 5661.46216109471, 6238.251409830827, 6273.027680709497, 6328.11406403104, 6347.61914285142, 5959.731812068446, 6262.906056409158, 6311.495080393581, 6314.7474101035905, 6320.652364120017, 5712.343080545707, 5741.483266505993, 5747.195392645033, 5777.306723193244, 5788.27646544182, 28308.575371369447, 28492.44950088228, 29694.257643558867, 29787.0, 30054.248643783863, 2256.097560975623, 2281.5779080869925, 2292.209187830196, 2302.3553405046446, 2304.0092760113143, 18704.158312875952, 19218.135183916616, 19257.22971081155, 19280.614387712307, 19323.710144347846, 1908.761404824397, 2189.9781002190093, 2210.502636906699, 2244.0799272298277, 2258.6837842702034, 161869.3813061866, 168019.0, 170865.0, 171868.0, 212911.0, 14000.929995349958, 15398.230088495531, 15464.304106315018, 15775.68448631044, 15808.680952617802, 20584.794152058286, 20586.897065514575, 20587.279445219345, 20589.691154632692, 20625.381238562866, 10213.33613315132, 10220.642277520252, 10240.078392944672, 10242.975702429698, 10317.246068168279, 9372.718818435458, 9374.578143983537, 9491.952540237255, 9526.952365238129, 9564.61741530345, 7730.342920851791, 8849.336299777542, 9083.273338132933, 9196.08039196075, 9249.16757491829, 2492.5762620354585, 2624.8162628616196, 2626.9474610508064, 2628.7502687244682, 2640.917223938176, 883.1786438612153, 899.4603238057114, 899.6581299106298, 917.7426925112505, 973.7030205787162, 20475.07862146207, 20691.27580534674, 20762.89618551928, 20897.976975965346, 21556.13775448996, 859.7678626770862, 959.9904000960041, 985.4136788610765, 994.1152374386656, 1015.8171529124828, 10806.297590656739, 10926.0, 10934.890651093387, 11216.733830746472, 11234.606788762518, 568122.0, 577445.0, 577680.0, 585931.0, 596163.0, 150370.0, 150458.24770876075, 151551.0, 152401.0, 152862.0, 1235.932023738703, 1236.0914727674972, 1284.441268048402, 1286.3568215892053, 1287.9806802897965, 21048.26331078405, 21257.830819305087, 23812.52374952526, 23867.164649237195, 24182.516349672554, 72859.27140728525, 74325.62837185779, 75043.24956750582, 75244.87132692905, 75538.48923021619, 95989.0, 96806.0, 98969.0, 100030.0, 100836.0, 16876.57808554796, 17471.56321091983, 17891.642167156846, 17935.551611209823, 17956.730649040015, 18565.793223440432, 18828.152733127317, 18832.717509237646, 18878.71681924745, 19174.52063698363, 14476.276186190858, 15456.690866182838, 15468.690626187637, 15509.99185052968, 15527.922360388126, 13603.79594306106, 13622.863771362556, 13624.931875340957, 13680.0, 14121.929390352983, 15648.687026259184, 15732.528024159294, 15753.0, 15786.447474338343, 16044.598885027968, 16089.75865361997, 17039.91480042592, 17269.222884969957, 17455.0, 17494.0, 10846.728831778953, 12082.697932551757, 15103.32035058403, 15186.848131518544, 15706.842931570995], "value": 12050.19283241628, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1188.3582865252763, 1426.108682073704, 1430.4278288684525, 1445.0462694621551, 1456.0244636093817, 13667.0, 14129.222892740909, 14243.928780356084, 14248.073875198104, 14256.501022464228, 13344.59966201013, 13757.931210343935, 13758.587242382719, 13777.311134443264, 13881.791773123412, 2760.309922519375, 2797.734215249548, 2798.118592643321, 2808.606795048695, 2816.774658027353, 2688.852113133777, 2798.8040837141307, 2805.3267215868145, 2806.859657017151, 2815.1132393296048, 2770.570561562951, 2785.6935737068907, 2788.4423115376903, 2799.0902956539207, 2804.383035732136, 2735.411886444414, 2761.9171424857086, 2766.8478233697138, 2768.0865314446182, 2779.249602607305, 2662.4142688608476, 2718.972810271892, 2786.958195627067, 2792.8463934483593, 2799.790015748827, 13447.99140064499, 13522.053456258065, 13543.322833858367, 13556.864431355758, 13602.863971360259, 6436.774712885027, 6553.2136143663065, 7679.462437629368, 7707.6916923323, 7772.0, 4200.432941552882, 4568.97198130418, 4810.35060266863, 4871.342368780205, 4941.45643979162, 1373.7595920713836, 1374.3197117426837, 1374.9175049497062, 1388.2780953903914, 1393.0666453476194, 18772.0, 19196.0, 20103.39689809308, 20229.291974780957, 20412.5917481651, 6076.635401875901, 6209.410106039919, 6682.231543372574, 6922.06552115468, 6983.057287266154, 10645.78708425827, 14409.78385324221, 14490.565283041526, 14497.927510362591, 14548.781768273484, 7281.526700764433, 7538.434617403607, 7623.313901748758, 7651.808704782425, 7660.463767536219, 6845.349691779273, 6995.300469952964, 7016.719331226694, 7090.078289822393, 7109.324614161646, 5267.71027593486, 5279.47205279469, 5299.973500132474, 5316.627836051517, 5339.092354299778, 1511.2896938439135, 1532.946346877854, 1540.4839378808, 1551.9379224831107, 1556.089687532806, 705.340506626306, 809.3929552835374, 838.2455789789238, 841.2302742990217, 854.598338780772, 6195.31851496335, 7730.226977302224, 8480.067192608809, 8566.18621230982, 8843.734687959372, 974.4153507895206, 977.8444690672235, 992.3252188511881, 998.5157198758596, 1016.7051555048953, 4798.880027999258, 4922.187839006596, 4940.6788558743565, 4983.9252411213865, 5252.369715634149, 18302.816971830376, 18836.434906952814, 18870.056497175086, 18876.0, 18904.338348157748, 17842.643147136983, 18588.25646974106, 18820.623587528447, 18828.905855470908, 18944.71582926257, 9548.665796697082, 9700.417974921385, 9710.417374957526, 9749.707508774749, 9751.268654850914, 6811.0, 7624.618769061635, 7840.607969601383, 8505.362097842562, 8612.354073444641, 15849.603759906095, 16257.268422921234, 16560.834391655928, 16748.078855663054, 16789.244483998493, 15215.847841521443, 17530.47408577745, 17586.472405827844, 17589.120543972494, 17817.554561136076, 9237.538123093684, 9622.51887405641, 10389.636362727268, 10877.456127193767, 11172.273802202832, 12095.092868034757, 12609.621711348675, 12675.556355527513, 12767.425465853874, 12779.105462617528, 9926.801463970825, 9990.400575965465, 10227.48862556884, 10570.735731606772, 10618.09746171584, 8737.427263092704, 8751.774751534664, 9821.950890245504, 9951.800963980824, 10345.482725863527, 9210.0, 9309.022552631875, 9319.767005824908, 9324.927633322255, 9365.297602679962, 6716.194056713226, 6741.898871516833, 6895.6552172390175, 6920.411765000032, 7120.572765634079, 5360.517553420211, 6876.518643694893, 7095.858082838417, 7140.857182856417, 7149.070620819468], "value": 6169.4229072682165, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [438.6052552702569, 458.80042181650987, 466.5730856266516, 476.0787875460981, 483.0893765252498, 5494.5054945054935, 6004.94913390156, 6472.320406357336, 6531.542792004561, 6533.803985880425, 6176.84557886052, 6229.785191887584, 6239.594426362295, 6252.249730032391, 6362.331955144701, 4939.777710003041, 4948.8267910623135, 4951.55436010759, 4957.033378491177, 4972.378452693422, 4297.527272000078, 4512.894340886471, 4594.724316541002, 4965.0, 4968.925466118011, 4921.941782516758, 4945.986072855056, 4949.133901567229, 4987.900241995158, 4996.900061998758, 4616.838410655628, 4935.80256789728, 4940.135476291653, 4958.900821983576, 4972.428170760372, 3756.605556416564, 4411.316245981878, 4589.2198326284615, 4600.6549508786975, 4638.605718513931, 5487.0, 5601.60788744788, 6275.995840665517, 6292.4966002719675, 6329.702411005779, 5474.343078830526, 5909.079549146544, 6095.329513753483, 6128.724207410677, 6134.0, 6720.428763555104, 6855.897161542531, 6961.199462061826, 6991.720331186746, 6992.720291188346, 5384.892302153974, 5395.676259424408, 5410.161424979135, 5414.620976531644, 5420.105682562373, 6424.5502814802985, 6944.8540990736465, 7283.490155689051, 7350.77947661571, 7370.852582948364, 5516.586256030814, 5521.806736764194, 5531.640443371168, 5538.978272930432, 5580.883823235288, 4731.52684731531, 4937.481564435682, 5101.285819985205, 5308.893822123536, 5619.550435965113, 5141.897162056738, 5865.914805760948, 6151.569390142737, 6159.0, 6168.506519478431, 6427.421532062137, 6443.645599491979, 6821.386075253251, 7031.542949708252, 7058.223595404502, 1527.9083255004734, 1616.83023282556, 1631.8939268947718, 1919.7521610952992, 1924.7112933059873, 1064.6167379743285, 1186.4957393108068, 1263.5072321794507, 1367.9863201368057, 1373.1623709537175, 5133.948660513347, 5887.646741195542, 5891.7054147292465, 6399.8080057598345, 6468.191476065492, 5470.100846551334, 6153.1693221415435, 6955.65221738911, 7023.473239507058, 7138.6085661303005, 6382.297947225801, 6397.776077837253, 6408.423241908251, 6410.8717825643225, 6428.678566071678, 2962.0225325642486, 3198.952015719766, 3586.0, 3685.3734865073, 3720.069982504374, 5995.640261584319, 6907.654617269116, 7226.591779014797, 7346.485745997729, 7375.459779305856, 6003.339632640406, 7032.402245809163, 7292.489525733148, 7341.192468828424, 7382.298681625237, 6097.725602347817, 7030.769615317299, 7306.634668266565, 7318.560886346836, 7370.852582948311, 5875.680240664011, 6999.895001574879, 7046.224915259215, 7118.572885626879, 7141.250168732208, 5486.0, 5821.330546987151, 6130.080487926846, 6952.44380449573, 7015.790525684549, 5416.458354164552, 5643.88712225745, 7135.825542057954, 7225.290516056479, 7278.890816637861, 4540.0, 4575.58819706228, 4885.267209918541, 4904.337914381513, 4920.852374428773, 6729.495287853332, 6853.383195512428, 7166.390856777024, 7170.282971702996, 7194.388476979515, 6300.873982520416, 6318.925782616874, 6437.259715132633, 6528.608283503005, 6890.586564806128, 5814.267294371639, 6047.516198704006, 6282.743451309665, 6813.3527314905, 6913.0, 6684.197896252481, 6718.294579069323, 6760.898586521108, 6761.526693131434, 6765.019072234597, 6841.555298905555, 6858.897116543156, 6882.0751180748985, 6893.862122757417, 6911.343422374866, 6606.9008964866525, 6653.73385064592, 6742.292059333896, 6754.358335958076, 6759.391654751096], "value": 5129.373663216796, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [826.1284345016006, 834.3825569078883, 837.932965362771, 845.5053793530785, 870.4820631724124, 16761.580960475978, 17212.053337066456, 17290.91354543227, 17500.387486437976, 17500.56248593786, 17510.474685759415, 18483.722744158847, 18698.0, 18792.15435305411, 18857.62284754304, 1000.9249306302002, 1009.4902074452391, 1012.2610494339137, 1014.786894752103, 1019.2559431614926, 852.433131967241, 1028.0850043461326, 1032.5250384822991, 1036.3367444835312, 1038.0, 955.9856602150974, 973.2894986659743, 1057.9153667706564, 1087.8640169978753, 1092.6612750047511, 1043.984340234897, 1051.232600201853, 1051.8842927277992, 1056.75166335911, 1058.1904842795268, 764.4228607401432, 779.1429427629619, 781.6795114003288, 788.9684412623488, 791.0309870408703, 17470.388536401293, 17512.824871751247, 17683.204255808392, 17866.910665446718, 17902.552436189068, 17323.0, 17466.388676396266, 17630.471085867444, 17852.55368615795, 17916.372926947493, 14807.0375425597, 14816.0, 14941.701165976729, 14979.101253924799, 14985.175815330153, 3555.6444355564236, 3557.786532808014, 3583.5878873929573, 3583.6774690278, 3587.0852932502307, 151046.0, 155094.0, 156160.0, 157877.21061394733, 158135.2093239538, 13573.0, 13651.863481365059, 13682.384292706853, 13791.241481718602, 13972.86027139736, 26000.34999125037, 26591.734082659314, 26718.0, 26771.0, 27641.447171056465, 7956.681732730626, 8066.314363279187, 8094.31198348147, 8122.83754324922, 8136.633851476579, 3387.458006718913, 4071.1654110907193, 4123.072308730511, 4137.627613514738, 4137.965508622844, 4562.475315338778, 4784.569388755029, 4787.018661174451, 4788.066327066258, 4794.472608013116, 839.8200528257807, 877.7191298784455, 971.4220039076738, 973.6592192732487, 977.3012296208103, 599.5203836930525, 632.0740115730476, 653.9247986481521, 660.4782222044531, 682.4165338635495, 8793.472391656522, 11437.428128593703, 11935.701607459883, 12005.0, 12544.560940367044, 623.688155922039, 623.7006237006262, 635.548760380126, 650.1125963060389, 657.3098246840798, 5481.451854814566, 5662.405447428043, 5693.231413759089, 5712.171735098471, 5993.250843644544, 145282.09435811226, 151798.0, 152947.0, 154104.0, 158203.0, 126621.0, 131371.0, 134516.0, 137249.0, 138294.0, 108701.0, 109985.0, 111499.0, 113654.29518557228, 115740.42129789466, 12007.639770806889, 12131.818022729489, 12341.642419334046, 12577.559785407468, 12989.155704879153, 45197.0, 46344.84137896579, 48526.02947940951, 49808.750956244985, 50010.74973125556, 65214.021789674145, 81253.59373203096, 81967.0, 88392.0, 88653.0, 15875.745472178407, 16025.27886245145, 16943.576410589834, 17215.31138754436, 17288.0, 18515.35196268124, 19024.0, 19051.71422428637, 19083.950382728526, 19246.23015079381, 15046.774298385313, 15366.615834604225, 15404.0, 15980.760288595447, 17869.0172040539, 16403.42588009414, 18004.819951800313, 18217.544561386072, 18556.536086597942, 18624.348147814762, 17574.38489652856, 17987.550311242325, 18000.36998705039, 18353.265869365077, 18908.243670253036, 10666.36001839892, 11121.832207618083, 11613.245139065933, 11918.940405297919, 11999.220050696676, 10612.734681633021, 13157.407916643582, 13315.0, 13359.0, 13635.522756703467], "value": 8995.248821445946, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1580.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 115.0, 109.0, 155.0, 170.0, 145.0, 113.0, 105.0, 117.0, 117.0], "value": 124.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 116.0, 132.0, 144.0, 108.0, 120.0, 142.0, 108.0, 161.0, 112.0], "value": 128.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [204.0, 200.0, 248.0, 243.0, 197.0, 198.0, 197.0, 245.0, 203.0, 218.0], "value": 215.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 93.0, 96.0, 166.0, 65.0, 79.0, 87.0, 61.0, 143.0, 94.0], "value": 95.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 103.0, 139.0, 56.0, 106.0, 102.0, 106.0, 74.0, 106.0, 148.0], "value": 99.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 99.0, 94.0, 93.0, 93.0, 95.0, 144.0, 92.0, 93.0, 90.0], "value": 98.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 146.0, 97.0, 98.0, 96.0, 97.0, 97.0, 97.0, 95.0, 97.0], "value": 107.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 94.0, 91.0, 90.0, 92.0, 90.0, 98.0, 140.0, 93.0], "value": 97.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 50.0, 51.0, 52.0, 53.0, 53.0, 53.0, 52.0, 54.0, 53.0], "value": 52.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 80.0, 82.0, 91.0, 78.0, 63.0, 54.0, 57.0, 57.0, 56.0], "value": 67.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 78.0, 106.0, 82.0, 96.0, 93.0, 104.0, 77.0, 79.0], "value": 87.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 129.0, 144.0, 142.0, 149.0, 141.0, 135.0, 139.0, 142.0, 160.0], "value": 142.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 180.0, 181.0, 177.0, 178.0, 177.0, 200.0, 207.0, 238.0, 225.0], "value": 194.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 82.0, 82.0, 67.0, 65.0, 64.0, 67.0, 67.0, 63.0, 67.0], "value": 69.1, "unit": "score"}]}]} 04:29:54 INFO - Return code: 0 04:29:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:29:54 INFO - # TBPL SUCCESS # 04:29:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:29:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:29:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:29:54 INFO - 2017-06-19 04:29:54.185 screenresolution[2979:57075] starting screenresolution argv=screenresolution get 04:29:54 INFO - 2017-06-19 04:29:54.187 screenresolution[2979:57075] Display 0: 1600x1200x32@60 04:29:54 INFO - 2017-06-19 04:29:54.194 screenresolution[2980:57078] starting screenresolution argv=screenresolution list 04:29:54 INFO - Available Modes on Display 0 04:29:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:29:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:29:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:29:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:29:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:29:54 INFO - Intel Iris: 04:29:54 INFO - Chipset Model: Intel Iris 04:29:54 INFO - Type: GPU 04:29:54 INFO - Bus: Built-In 04:29:54 INFO - VRAM (Dynamic, Max): 1536 MB 04:29:54 INFO - Vendor: Intel (0x8086) 04:29:54 INFO - Device ID: 0x0a2e 04:29:54 INFO - Revision ID: 0x0009 04:29:54 INFO - Displays: 04:29:54 INFO - Display: 04:29:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:29:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:29:54 INFO - Display Serial Number: 1600x1200 60 04:29:54 INFO - Main Display: Yes 04:29:54 INFO - Mirror: Off 04:29:54 INFO - Online: Yes 04:29:54 INFO - Rotation: Supported 04:29:54 INFO - Return code: 0 04:29:54 INFO - Running post-action listener: _resource_record_post_action 04:29:54 INFO - [mozharness: 2017-06-19 11:29:54.430430Z] Finished run-tests step (success) 04:29:54 INFO - Running post-run listener: _resource_record_post_run 04:29:54 INFO - Total resource usage - Wall time: 1258s; CPU: 53.0%; Read bytes: 1860096; Write bytes: 560464896; Read time: 15; Write time: 1887 04:29:54 INFO - TinderboxPrint: CPU usage
53.0% 04:29:54 INFO - TinderboxPrint: I/O read bytes / time
1,860,096 / 15 04:29:54 INFO - TinderboxPrint: I/O write bytes / time
560,464,896 / 1,887 04:29:54 INFO - TinderboxPrint: CPU idle
2,366.6 (47.0%) 04:29:54 INFO - TinderboxPrint: CPU system
1,023.6 (20.3%) 04:29:54 INFO - TinderboxPrint: CPU user
1,643.4 (32.6%) 04:29:54 INFO - TinderboxPrint: Swap in / out
244,535,296 / 0 04:29:54 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 225630720; Write bytes: 227518464; Read time: 17045; Write time: 469 04:29:54 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 04:29:54 INFO - run-tests - Wall time: 1237s; CPU: 53.0%; Read bytes: 1860096; Write bytes: 314498048; Read time: 15; Write time: 1376 04:29:54 INFO - Running post-run listener: _upload_blobber_files 04:29:54 INFO - Blob upload gear active. 04:29:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:29:54 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 04:29:54 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'] 04:29:54 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 04:29:56 INFO - (blobuploader) - INFO - Open directory for files ... 04:29:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:29:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:29:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:29:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:29:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:29:57 INFO - (blobuploader) - INFO - Done attempting. 04:29:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:29:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:29:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:29:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:29:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:29:58 INFO - (blobuploader) - INFO - Done attempting. 04:29:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:29:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:29:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:30:01 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:30:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:30:01 INFO - (blobuploader) - INFO - Done attempting. 04:30:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:30:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:30:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:30:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:30:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:30:03 INFO - (blobuploader) - INFO - Done attempting. 04:30:03 INFO - (blobuploader) - INFO - Iteration through files over. 04:30:03 INFO - Return code: 0 04:30:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:30:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:30:03 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a580128dede53a9d56edfa60e74928d1781b422e61e8c98c6be3363a4dd1de96c21d7d79f4ac045915ed98a85f0d4310a5f9d6eb1db172e12221d1d59e89ed6b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fe4939d271608160e85237cd46d6b926ac97cecad7ca9325f575ec711cede03e37ab561dd162bd31f3ad91d71402a5f47c0aa8664d8427acf777b549fbdb708f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/db0cdc0e9d647649eedcb25aa279aa10ccbf50026a8f76a3e2d9677ab3077e5a5ea2a1c49c4230eb122b5aa1735ee6a85de155360a11d50a9a141e9bc3b81613", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ed6495b9daab2798793e5a77d7ba420520b3e6f671ae6a1ef2ef2206b659dada0a8d9437ca8010299088fcbc0ade94d6b33b7e16265e6320f254aaec8470fd01"} 04:30:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:30:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:30:03 INFO - Contents: 04:30:03 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a580128dede53a9d56edfa60e74928d1781b422e61e8c98c6be3363a4dd1de96c21d7d79f4ac045915ed98a85f0d4310a5f9d6eb1db172e12221d1d59e89ed6b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fe4939d271608160e85237cd46d6b926ac97cecad7ca9325f575ec711cede03e37ab561dd162bd31f3ad91d71402a5f47c0aa8664d8427acf777b549fbdb708f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/db0cdc0e9d647649eedcb25aa279aa10ccbf50026a8f76a3e2d9677ab3077e5a5ea2a1c49c4230eb122b5aa1735ee6a85de155360a11d50a9a141e9bc3b81613", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ed6495b9daab2798793e5a77d7ba420520b3e6f671ae6a1ef2ef2206b659dada0a8d9437ca8010299088fcbc0ade94d6b33b7e16265e6320f254aaec8470fd01"} 04:30:03 INFO - Running post-run listener: copy_logs_to_upload_dir 04:30:03 INFO - Copying logs to upload dir... 04:30:03 INFO - mkdir: /builds/slave/test/build/upload/logs 04:30:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1342.764757 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 22 secs) (at 2017-06-19 04:30:04.009608) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-19 04:30:04.014121) ========= 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.eWc9x9yS6v/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.rHKmgx805d/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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/a580128dede53a9d56edfa60e74928d1781b422e61e8c98c6be3363a4dd1de96c21d7d79f4ac045915ed98a85f0d4310a5f9d6eb1db172e12221d1d59e89ed6b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fe4939d271608160e85237cd46d6b926ac97cecad7ca9325f575ec711cede03e37ab561dd162bd31f3ad91d71402a5f47c0aa8664d8427acf777b549fbdb708f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/db0cdc0e9d647649eedcb25aa279aa10ccbf50026a8f76a3e2d9677ab3077e5a5ea2a1c49c4230eb122b5aa1735ee6a85de155360a11d50a9a141e9bc3b81613", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ed6495b9daab2798793e5a77d7ba420520b3e6f671ae6a1ef2ef2206b659dada0a8d9437ca8010299088fcbc0ade94d6b33b7e16265e6320f254aaec8470fd01"} build_url:https://queue.taskcluster.net/v1/task/Fnm0frauRh25-hNetyOeSg/artifacts/public/build/firefox-55.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011433 build_url: 'https://queue.taskcluster.net/v1/task/Fnm0frauRh25-hNetyOeSg/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/a580128dede53a9d56edfa60e74928d1781b422e61e8c98c6be3363a4dd1de96c21d7d79f4ac045915ed98a85f0d4310a5f9d6eb1db172e12221d1d59e89ed6b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fe4939d271608160e85237cd46d6b926ac97cecad7ca9325f575ec711cede03e37ab561dd162bd31f3ad91d71402a5f47c0aa8664d8427acf777b549fbdb708f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/db0cdc0e9d647649eedcb25aa279aa10ccbf50026a8f76a3e2d9677ab3077e5a5ea2a1c49c4230eb122b5aa1735ee6a85de155360a11d50a9a141e9bc3b81613", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ed6495b9daab2798793e5a77d7ba420520b3e6f671ae6a1ef2ef2206b659dada0a8d9437ca8010299088fcbc0ade94d6b33b7e16265e6320f254aaec8470fd01"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-19 04:30:04.051515) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-19 04:30:04.051876) ========= 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.eWc9x9yS6v/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.rHKmgx805d/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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.005359 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-19 04:30:04.102567) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-19 04:30:04.103054) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-19 04:30:04.103532) ========= ========= Total master_lag: 0.20 =========