builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0291 starttime: 1496395497.86 results: success (0) buildid: 20170602013225 builduid: 2302c5d6f9fd4dd7a8944f465da666be revision: 4f0bd6c5b03dafa6025621da30e1bc87008d027b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-02 02:24:57.856993) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-02 02:24:57.857426) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-02 02:24:57.857719) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-02 02:24:57.883176) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 02:24:57.883519) ========= 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.P7wUXkt8wZ/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.ktMLZb66l6/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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-02 02:24:57-- 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% 37.2M=0s 2017-06-02 02:24:58 (37.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.140141 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 02:24:58.047133) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 02:24:58.047470) ========= 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.P7wUXkt8wZ/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.ktMLZb66l6/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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.037333 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 02:24:58.105704) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-02 02:24:58.106093) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 4f0bd6c5b03dafa6025621da30e1bc87008d027b --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 4f0bd6c5b03dafa6025621da30e1bc87008d027b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.P7wUXkt8wZ/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.ktMLZb66l6/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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-02 02:24:58,204 truncating revision to first 12 chars 2017-06-02 02:24:58,204 Setting DEBUG logging. 2017-06-02 02:24:58,204 attempt 1/10 2017-06-02 02:24:58,204 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/4f0bd6c5b03d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-02 02:24:59,671 unpacking tar archive at: mozilla-beta-4f0bd6c5b03d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.748539 ========= master_lag: 0.81 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-02 02:25:00.659762) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-02 02:25:00.660116) ========= script_repo_revision: 4f0bd6c5b03dafa6025621da30e1bc87008d027b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-02 02:25:00.660510) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-02 02:25:00.660786) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-02 02:25:00.973564) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-06-02 02:25:00.973945) ========= /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.P7wUXkt8wZ/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.ktMLZb66l6/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 02:25:01 INFO - MultiFileLogger online at 20170602 02:25:01 in /builds/slave/test 02:25:01 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 02:25:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:25:01 INFO - {'append_to_log': False, 02:25:01 INFO - 'base_work_dir': '/builds/slave/test', 02:25:01 INFO - 'blob_upload_branch': 'Mozilla-Beta', 02:25:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:25:01 INFO - 'branch': 'Mozilla-Beta', 02:25:01 INFO - 'buildbot_json_path': 'buildprops.json', 02:25:01 INFO - 'config_files': ('talos/mac_config.py',), 02:25:01 INFO - 'default_actions': ('clobber', 02:25:01 INFO - 'read-buildbot-config', 02:25:01 INFO - 'download-and-extract', 02:25:01 INFO - 'populate-webroot', 02:25:01 INFO - 'create-virtualenv', 02:25:01 INFO - 'install', 02:25:01 INFO - 'run-tests'), 02:25:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:25:01 INFO - 'download_minidump_stackwalk': True, 02:25:01 INFO - 'download_symbols': 'ondemand', 02:25:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:25:01 INFO - 'tooltool.py': '/tools/tooltool.py', 02:25:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:25:01 INFO - '/tools/misc-python/virtualenv.py')}, 02:25:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:25:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:25:01 INFO - 'gecko_profile': False, 02:25:01 INFO - 'gecko_profile_interval': 0, 02:25:01 INFO - 'installer_path': 'installer.exe', 02:25:01 INFO - 'log_level': 'info', 02:25:01 INFO - 'log_name': 'talos', 02:25:01 INFO - 'log_to_console': True, 02:25:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:25:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:25:01 INFO - 'opt_config_files': (), 02:25:01 INFO - 'pip_index': False, 02:25:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:25:01 INFO - 'cmd': ('bash', 02:25:01 INFO - '-c', 02:25:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:25:01 INFO - 'enabled': True, 02:25:01 INFO - 'halt_on_failure': False, 02:25:01 INFO - 'name': 'check_screen_resolution'},), 02:25:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:25:01 INFO - 'cmd': ('bash', 02:25:01 INFO - '-c', 02:25:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:25:01 INFO - 'enabled': True, 02:25:01 INFO - 'halt_on_failure': False, 02:25:01 INFO - 'name': 'check_screen_resolution'},), 02:25:01 INFO - 'run_cmd_checks_enabled': True, 02:25:01 INFO - 'suite': 'dromaeojs-e10s', 02:25:01 INFO - 'system_bits': '32', 02:25:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:25:01 INFO - 'title': 't-yosemite-r7-0291', 02:25:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:25:01 INFO - 'use_talos_json': True, 02:25:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:25:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:25:01 INFO - 'work_dir': 'build'} 02:25:01 INFO - [mozharness: 2017-06-02 09:25:01.180364Z] Running clobber step. 02:25:01 INFO - Running pre-action listener: _resource_record_pre_action 02:25:01 INFO - Running main action method: clobber 02:25:01 INFO - rmtree: /builds/slave/test/build 02:25:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:25:05 INFO - Running post-action listener: _resource_record_post_action 02:25:05 INFO - [mozharness: 2017-06-02 09:25:05.821943Z] Finished clobber step (success) 02:25:05 INFO - [mozharness: 2017-06-02 09:25:05.822073Z] Running read-buildbot-config step. 02:25:05 INFO - Running pre-action listener: _resource_record_pre_action 02:25:05 INFO - Running main action method: read_buildbot_config 02:25:05 INFO - Using buildbot properties: 02:25:05 INFO - { 02:25:05 INFO - "project": "", 02:25:05 INFO - "product": "firefox", 02:25:05 INFO - "script_repo_revision": "production", 02:25:05 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 02:25:05 INFO - "repository": "", 02:25:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 02:25:05 INFO - "buildid": "20170602013225", 02:25:05 INFO - "pgo_build": "False", 02:25:05 INFO - "basedir": "/builds/slave/test", 02:25:05 INFO - "buildnumber": 10, 02:25:05 INFO - "slavename": "t-yosemite-r7-0291", 02:25:05 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 02:25:05 INFO - "platform": "yosemite_r7_devedition", 02:25:05 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 02:25:05 INFO - "branch": "mozilla-beta", 02:25:05 INFO - "repo_path": "releases/mozilla-beta", 02:25:05 INFO - "revision": "4f0bd6c5b03dafa6025621da30e1bc87008d027b", 02:25:05 INFO - "stage_platform": "macosx64-devedition", 02:25:05 INFO - "builduid": "2302c5d6f9fd4dd7a8944f465da666be", 02:25:05 INFO - "slavebuilddir": "test" 02:25:05 INFO - } 02:25:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/GmSTE1R7TYCSbLYmhzPe-Q/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 02:25:05 INFO - Running post-action listener: _resource_record_post_action 02:25:05 INFO - [mozharness: 2017-06-02 09:25:05.825387Z] Finished read-buildbot-config step (success) 02:25:05 INFO - [mozharness: 2017-06-02 09:25:05.825495Z] Running download-and-extract step. 02:25:05 INFO - Running pre-action listener: _resource_record_pre_action 02:25:05 INFO - Running main action method: download_and_extract 02:25:05 INFO - mkdir: /builds/slave/test/build/tests 02:25:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:25:05 INFO - trying https://queue.taskcluster.net/v1/task/GmSTE1R7TYCSbLYmhzPe-Q/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 02:25:05 INFO - Downloading https://queue.taskcluster.net/v1/task/GmSTE1R7TYCSbLYmhzPe-Q/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 02:25:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GmSTE1R7TYCSbLYmhzPe-Q/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 02:25:07 INFO - Downloaded 1287 bytes. 02:25:07 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 02:25:07 INFO - Using the following test package requirements: 02:25:07 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 02:25:07 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 02:25:07 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 02:25:07 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 02:25:07 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 02:25:07 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 02:25:07 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 02:25:07 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 02:25:07 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 02:25:07 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 02:25:07 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 02:25:07 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 02:25:07 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 02:25:07 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 02:25:07 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 02:25:07 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 02:25:07 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 02:25:07 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 02:25:07 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 02:25:07 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 02:25:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GmSTE1R7TYCSbLYmhzPe-Q/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 02:25:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GmSTE1R7TYCSbLYmhzPe-Q/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 02:25:07 INFO - Fetch https://queue.taskcluster.net/v1/task/GmSTE1R7TYCSbLYmhzPe-Q/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 02:25:08 INFO - Expected file size: 51963685 02:25:08 INFO - Obtained file size: 51963685 02:25:24 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 02:25:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GmSTE1R7TYCSbLYmhzPe-Q/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 02:25:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GmSTE1R7TYCSbLYmhzPe-Q/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 02:25:24 INFO - Fetch https://queue.taskcluster.net/v1/task/GmSTE1R7TYCSbLYmhzPe-Q/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 02:25:26 INFO - Expected file size: 51963685 02:25:26 INFO - Obtained file size: 51963685 02:25:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GmSTE1R7TYCSbLYmhzPe-Q/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 02:25:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GmSTE1R7TYCSbLYmhzPe-Q/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 02:25:41 INFO - Fetch https://queue.taskcluster.net/v1/task/GmSTE1R7TYCSbLYmhzPe-Q/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 02:25:42 INFO - Expected file size: 14404461 02:25:42 INFO - Obtained file size: 14404461 02:25:42 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')]} 02:25:42 INFO - trying https://queue.taskcluster.net/v1/task/GmSTE1R7TYCSbLYmhzPe-Q/artifacts/public/build/firefox-54.0.en-US.mac.dmg 02:25:42 INFO - Downloading https://queue.taskcluster.net/v1/task/GmSTE1R7TYCSbLYmhzPe-Q/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 02:25:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GmSTE1R7TYCSbLYmhzPe-Q/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 02:25:45 INFO - Downloaded 74891001 bytes. 02:25:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GmSTE1R7TYCSbLYmhzPe-Q/artifacts/public/build/firefox-54.0.en-US.mac.dmg 02:25:45 INFO - mkdir: /builds/slave/test/properties 02:25:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:25:45 INFO - Writing to file /builds/slave/test/properties/build_url 02:25:45 INFO - Contents: 02:25:45 INFO - build_url:https://queue.taskcluster.net/v1/task/GmSTE1R7TYCSbLYmhzPe-Q/artifacts/public/build/firefox-54.0.en-US.mac.dmg 02:25:45 INFO - Running post-action listener: _resource_record_post_action 02:25:45 INFO - Running post-action listener: set_extra_try_arguments 02:25:45 INFO - [mozharness: 2017-06-02 09:25:45.721688Z] Finished download-and-extract step (success) 02:25:45 INFO - [mozharness: 2017-06-02 09:25:45.721812Z] Running populate-webroot step. 02:25:45 INFO - Running pre-action listener: _resource_record_pre_action 02:25:45 INFO - Running main action method: populate_webroot 02:25:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:25:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:25:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:25:45 INFO - '--tppagecycles', 02:25:45 INFO - '7'], 02:25:45 INFO - 'tests': ['tsvgm']}}, 02:25:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:25:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:25:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:25:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:25:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:25:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:25:45 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:25:45 INFO - 'talos_options': ['--disable-e10s'], 02:25:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:25:45 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:25:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:25:45 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:25:45 INFO - 'talos_options': ['--disable-e10s'], 02:25:45 INFO - 'tests': ['damp', 'tps']}, 02:25:45 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:25:45 INFO - 'tests': ['damp', 'tps']}, 02:25:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:25:45 INFO - 'tests': ['dromaeo_dom']}, 02:25:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:25:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:25:45 INFO - 'tests': ['basic_compositor_video']}, 02:25:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:25:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:25:45 INFO - 'tests': ['a11yr', 02:25:45 INFO - 'ts_paint', 02:25:45 INFO - 'tpaint', 02:25:45 INFO - 'sessionrestore', 02:25:45 INFO - 'sessionrestore_no_auto_restore', 02:25:45 INFO - 'tabpaint']}, 02:25:45 INFO - 'other-e10s': {'tests': ['a11yr', 02:25:45 INFO - 'ts_paint', 02:25:45 INFO - 'tpaint', 02:25:45 INFO - 'sessionrestore', 02:25:45 INFO - 'sessionrestore_no_auto_restore', 02:25:45 INFO - 'tabpaint']}, 02:25:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:25:45 INFO - 'ts_paint', 02:25:45 INFO - 'tpaint', 02:25:45 INFO - 'sessionrestore', 02:25:45 INFO - 'sessionrestore_no_auto_restore', 02:25:45 INFO - 'tabpaint']}, 02:25:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:25:45 INFO - 'ts_paint', 02:25:45 INFO - 'tpaint', 02:25:45 INFO - 'sessionrestore', 02:25:45 INFO - 'sessionrestore_no_auto_restore', 02:25:45 INFO - 'tabpaint']}, 02:25:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:25:45 INFO - 'tests': ['a11yr', 02:25:45 INFO - 'ts_paint', 02:25:45 INFO - 'tpaint', 02:25:45 INFO - 'sessionrestore', 02:25:45 INFO - 'sessionrestore_no_auto_restore', 02:25:45 INFO - 'tabpaint']}, 02:25:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:25:45 INFO - 'tests': ['a11yr', 02:25:45 INFO - 'ts_paint', 02:25:45 INFO - 'tpaint', 02:25:45 INFO - 'sessionrestore', 02:25:45 INFO - 'sessionrestore_no_auto_restore', 02:25:45 INFO - 'tabpaint']}, 02:25:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:25:45 INFO - 'tests': ['tsvgx', 02:25:45 INFO - 'tsvgr_opacity', 02:25:45 INFO - 'tart', 02:25:45 INFO - 'tscrollx', 02:25:45 INFO - 'cart', 02:25:45 INFO - 'tsvg_static']}, 02:25:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:25:45 INFO - 'tsvgr_opacity', 02:25:45 INFO - 'tart', 02:25:45 INFO - 'tscrollx', 02:25:45 INFO - 'cart', 02:25:45 INFO - 'tsvg_static']}, 02:25:45 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:25:45 INFO - 'talos_options': ['--disable-e10s'], 02:25:45 INFO - 'tests': ['tp5o']}, 02:25:45 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:25:45 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:25:45 INFO - 'talos_options': ['--disable-e10s', 02:25:45 INFO - '--xperf_path', 02:25:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:25:45 INFO - 'tests': ['tp5n']}, 02:25:45 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:25:45 INFO - 'talos_options': ['--xperf_path', 02:25:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:25:45 INFO - 'tests': ['tp5n']}}, 02:25:45 INFO - 'talos.zip': {'path': '', 02:25:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:25:45 INFO - Running post-action listener: _resource_record_post_action 02:25:45 INFO - [mozharness: 2017-06-02 09:25:45.740065Z] Finished populate-webroot step (success) 02:25:45 INFO - [mozharness: 2017-06-02 09:25:45.740198Z] Running create-virtualenv step. 02:25:45 INFO - Running pre-action listener: _resource_record_pre_action 02:25:45 INFO - Running main action method: create_virtualenv 02:25:45 INFO - Creating virtualenv /builds/slave/test/build/venv 02:25:45 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 02:25:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:25:45 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 02:25:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:25:45 INFO - Using real prefix '/tools/python27' 02:25:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:25:46 INFO - Installing distribute.............................................................................................................................................................................................done. 02:25:49 INFO - Installing pip.................done. 02:25:49 INFO - Return code: 0 02:25:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:25:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:25:49 INFO - Reading from file tmpfile_stdout 02:25:49 INFO - Output received: 02:25:49 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) 02:25:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:25:49 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')]} 02:25:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:25:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:25:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:25:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1035edb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1c2551a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033cbd30>, '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 0x1032f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1033c0be8>, '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.P7wUXkt8wZ/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.ktMLZb66l6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 02:25:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:25:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:25:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P7wUXkt8wZ/Render', 02:25:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:25:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:25:50 INFO - 'HOME': '/Users/cltbld', 02:25:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:25:50 INFO - 'LOGNAME': 'cltbld', 02:25:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:25:50 INFO - 'MOZ_NO_REMOTE': '1', 02:25:50 INFO - 'NO_EM_RESTART': '1', 02:25:50 INFO - 'PAGER': '/bin/cat', 02:25:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:25:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:25:50 INFO - 'PWD': '/builds/slave/test', 02:25:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:25:50 INFO - 'SHELL': '/bin/bash', 02:25:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ktMLZb66l6/Listeners', 02:25:50 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 02:25:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:25:50 INFO - 'USER': 'cltbld', 02:25:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:25:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:25:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:25:50 INFO - 'XPC_FLAGS': '0x0', 02:25:50 INFO - 'XPC_SERVICE_NAME': '0', 02:25:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:25:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:25:51 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 02:25:51 INFO - Cleaning up... 02:25:51 INFO - Return code: 0 02:25:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:25:51 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')]} 02:25:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:25:51 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')]} 02:25:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:25:51 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 0x1035edb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1c2551a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033cbd30>, '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 0x1032f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1033c0be8>, '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.P7wUXkt8wZ/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.ktMLZb66l6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 02:25:51 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 02:25:51 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 02:25:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P7wUXkt8wZ/Render', 02:25:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:25:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:25:51 INFO - 'HOME': '/Users/cltbld', 02:25:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:25:51 INFO - 'LOGNAME': 'cltbld', 02:25:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:25:51 INFO - 'MOZ_NO_REMOTE': '1', 02:25:51 INFO - 'NO_EM_RESTART': '1', 02:25:51 INFO - 'PAGER': '/bin/cat', 02:25:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:25:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:25:51 INFO - 'PWD': '/builds/slave/test', 02:25:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:25:51 INFO - 'SHELL': '/bin/bash', 02:25:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ktMLZb66l6/Listeners', 02:25:51 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 02:25:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:25:51 INFO - 'USER': 'cltbld', 02:25:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:25:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:25:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:25:51 INFO - 'XPC_FLAGS': '0x0', 02:25:51 INFO - 'XPC_SERVICE_NAME': '0', 02:25:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:25:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:25:51 INFO - Downloading/unpacking psutil>=3.1.1 02:25: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 02:25: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 02:25: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 02:25: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 02:25:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:25:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:25:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:25:53 INFO - Installing collected packages: psutil 02:25:53 INFO - Running setup.py install for psutil 02:25:54 INFO - building 'psutil._psutil_osx' extension 02:25:54 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 02:25:54 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 02:25:54 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 02:25:54 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 02:25:54 INFO - building 'psutil._psutil_posix' extension 02:25:54 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 02:25:54 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:25:54 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:25:54 INFO - ^ 02:25:54 INFO - 1 warning generated. 02:25:54 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 02:25:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:25:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:25:55 INFO - Successfully installed psutil 02:25:55 INFO - Cleaning up... 02:25:55 INFO - Return code: 0 02:25:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:25: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')]} 02:25:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:25: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')]} 02:25:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:25: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1035edb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1c2551a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033cbd30>, '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 0x1032f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1033c0be8>, '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.P7wUXkt8wZ/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.ktMLZb66l6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 02:25: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:25: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 mozsystemmonitor==0.3 02:25:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P7wUXkt8wZ/Render', 02:25:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:25:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:25:55 INFO - 'HOME': '/Users/cltbld', 02:25:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:25:55 INFO - 'LOGNAME': 'cltbld', 02:25:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:25:55 INFO - 'MOZ_NO_REMOTE': '1', 02:25:55 INFO - 'NO_EM_RESTART': '1', 02:25:55 INFO - 'PAGER': '/bin/cat', 02:25:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:25:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:25:55 INFO - 'PWD': '/builds/slave/test', 02:25:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:25:55 INFO - 'SHELL': '/bin/bash', 02:25:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ktMLZb66l6/Listeners', 02:25:55 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 02:25:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:25:55 INFO - 'USER': 'cltbld', 02:25:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:25:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:25:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:25:55 INFO - 'XPC_FLAGS': '0x0', 02:25:55 INFO - 'XPC_SERVICE_NAME': '0', 02:25:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:25:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:25:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:25:55 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 02:25:55 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 02:25:55 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 02:25:55 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 02:25:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:25:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:25:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:25:56 INFO - Installing collected packages: mozsystemmonitor 02:25:56 INFO - Running setup.py install for mozsystemmonitor 02:25:57 INFO - Successfully installed mozsystemmonitor 02:25:57 INFO - Cleaning up... 02:25:57 INFO - Return code: 0 02:25:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:25:57 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')]} 02:25:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:25:57 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')]} 02:25:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:25:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1035edb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1c2551a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033cbd30>, '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 0x1032f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1033c0be8>, '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.P7wUXkt8wZ/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.ktMLZb66l6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 02:25:57 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 02:25:57 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 02:25:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P7wUXkt8wZ/Render', 02:25:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:25:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:25:57 INFO - 'HOME': '/Users/cltbld', 02:25:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:25:57 INFO - 'LOGNAME': 'cltbld', 02:25:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:25:57 INFO - 'MOZ_NO_REMOTE': '1', 02:25:57 INFO - 'NO_EM_RESTART': '1', 02:25:57 INFO - 'PAGER': '/bin/cat', 02:25:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:25:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:25:57 INFO - 'PWD': '/builds/slave/test', 02:25:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:25:57 INFO - 'SHELL': '/bin/bash', 02:25:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ktMLZb66l6/Listeners', 02:25:57 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 02:25:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:25:57 INFO - 'USER': 'cltbld', 02:25:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:25:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:25:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:25:57 INFO - 'XPC_FLAGS': '0x0', 02:25:57 INFO - 'XPC_SERVICE_NAME': '0', 02:25:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:25:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:25:57 INFO - Downloading/unpacking jsonschema==2.5.1 02:25:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:25:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:25:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:25:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:25:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:25:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:25:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:25:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:25:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:25:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:25:58 INFO - Downloading functools32-3.2.3-2.tar.gz 02:25:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:25:59 INFO - warning: no files found matching '*.txt' 02:25:59 INFO - no previously-included directories found matching 'build' 02:25:59 INFO - no previously-included directories found matching 'dist' 02:25:59 INFO - no previously-included directories found matching '.git*' 02:25:59 INFO - Installing collected packages: jsonschema, functools32 02:25:59 INFO - Running setup.py install for functools32 02:25:59 INFO - warning: no files found matching '*.txt' 02:25:59 INFO - no previously-included directories found matching 'build' 02:25:59 INFO - no previously-included directories found matching 'dist' 02:25:59 INFO - no previously-included directories found matching '.git*' 02:25:59 INFO - Successfully installed jsonschema functools32 02:25:59 INFO - Cleaning up... 02:25:59 INFO - Return code: 0 02:25:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:25:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:25:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:25:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:25:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:25:59 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 0x1035edb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1c2551a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033cbd30>, '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 0x1032f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1033c0be8>, '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.P7wUXkt8wZ/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.ktMLZb66l6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 02:25:59 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 02:25:59 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 02:25:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P7wUXkt8wZ/Render', 02:25:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:25:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:25:59 INFO - 'HOME': '/Users/cltbld', 02:25:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:25:59 INFO - 'LOGNAME': 'cltbld', 02:25:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:25:59 INFO - 'MOZ_NO_REMOTE': '1', 02:25:59 INFO - 'NO_EM_RESTART': '1', 02:25:59 INFO - 'PAGER': '/bin/cat', 02:25:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:25:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:25:59 INFO - 'PWD': '/builds/slave/test', 02:25:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:25:59 INFO - 'SHELL': '/bin/bash', 02:25:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ktMLZb66l6/Listeners', 02:25:59 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 02:25:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:25:59 INFO - 'USER': 'cltbld', 02:25:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:25:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:25:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:25:59 INFO - 'XPC_FLAGS': '0x0', 02:25:59 INFO - 'XPC_SERVICE_NAME': '0', 02:25:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:25:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:25:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:25:59 INFO - Cleaning up... 02:25:59 INFO - Return code: 0 02:25:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:25:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:25:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:25:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:25:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:25:59 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 0x1035edb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1c2551a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033cbd30>, '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 0x1032f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1033c0be8>, '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.P7wUXkt8wZ/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.ktMLZb66l6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 02:25:59 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 02:25:59 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 02:25:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P7wUXkt8wZ/Render', 02:25:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:25:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:25:59 INFO - 'HOME': '/Users/cltbld', 02:25:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:25:59 INFO - 'LOGNAME': 'cltbld', 02:25:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:25:59 INFO - 'MOZ_NO_REMOTE': '1', 02:25:59 INFO - 'NO_EM_RESTART': '1', 02:25:59 INFO - 'PAGER': '/bin/cat', 02:25:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:25:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:25:59 INFO - 'PWD': '/builds/slave/test', 02:25:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:25:59 INFO - 'SHELL': '/bin/bash', 02:25:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ktMLZb66l6/Listeners', 02:25:59 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 02:25:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:25:59 INFO - 'USER': 'cltbld', 02:25:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:25:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:25:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:25:59 INFO - 'XPC_FLAGS': '0x0', 02:25:59 INFO - 'XPC_SERVICE_NAME': '0', 02:25:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:25:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:25:59 INFO - Downloading/unpacking blobuploader==1.2.4 02:25:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:25:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:25:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:25:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:26:01 INFO - Downloading blobuploader-1.2.4.tar.gz 02:26:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:26:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:26:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:26:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:26:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:26:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:26:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:26:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:26:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:26:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:26:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:26:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:26:02 INFO - Downloading docopt-0.6.1.tar.gz 02:26:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:26:02 INFO - Installing collected packages: blobuploader, requests, docopt 02:26:02 INFO - Running setup.py install for blobuploader 02:26:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:26:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:26:02 INFO - Running setup.py install for requests 02:26:02 INFO - Running setup.py install for docopt 02:26:03 INFO - Successfully installed blobuploader requests docopt 02:26:03 INFO - Cleaning up... 02:26:03 INFO - Return code: 0 02:26:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:26:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:26:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:26:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:26:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:26:03 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 0x1035edb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1c2551a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033cbd30>, '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 0x1032f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1033c0be8>, '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.P7wUXkt8wZ/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.ktMLZb66l6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 02:26:03 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 02:26:03 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 02:26:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P7wUXkt8wZ/Render', 02:26:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:26:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:26:03 INFO - 'HOME': '/Users/cltbld', 02:26:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:26:03 INFO - 'LOGNAME': 'cltbld', 02:26:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:26:03 INFO - 'MOZ_NO_REMOTE': '1', 02:26:03 INFO - 'NO_EM_RESTART': '1', 02:26:03 INFO - 'PAGER': '/bin/cat', 02:26:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:26:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:26:03 INFO - 'PWD': '/builds/slave/test', 02:26:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:26:03 INFO - 'SHELL': '/bin/bash', 02:26:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ktMLZb66l6/Listeners', 02:26:03 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 02:26:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:26:03 INFO - 'USER': 'cltbld', 02:26:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:26:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:26:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:26:03 INFO - 'XPC_FLAGS': '0x0', 02:26:03 INFO - 'XPC_SERVICE_NAME': '0', 02:26:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:26:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:26:03 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-dXWj8Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:26:03 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-P6_y72-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:26:03 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-9r71In-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:26:03 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-6IN3BZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:26:03 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-EEkWPK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:26:03 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-cngS_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:26:03 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-ubIKXN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:26:04 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-9_siza-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:26:04 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-ML7UdF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:26:04 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-QnWYHs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:26:04 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-6SlWTK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:26:04 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-qvzK9a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:26:04 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-eWTdlY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:26:04 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-F71Ylw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:26:04 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-nSXnXp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:26:04 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-u5vmjk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:26:05 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-17nlIv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:26:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:26:05 INFO - Running setup.py install for manifestparser 02:26:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:26:05 INFO - Running setup.py install for mozcrash 02:26:05 INFO - Running setup.py install for mozdebug 02:26:05 INFO - Running setup.py install for mozdevice 02:26:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:26:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:26:05 INFO - Running setup.py install for mozfile 02:26:05 INFO - Running setup.py install for mozhttpd 02:26:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:26:05 INFO - Running setup.py install for mozinfo 02:26:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:26:06 INFO - Running setup.py install for mozInstall 02:26:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:26:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:26:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:26:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:26:06 INFO - Running setup.py install for mozleak 02:26:06 INFO - Running setup.py install for mozlog 02:26:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:26:06 INFO - Running setup.py install for moznetwork 02:26:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:26:06 INFO - Running setup.py install for mozprocess 02:26:06 INFO - Running setup.py install for mozprofile 02:26:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:26:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:26:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:26:07 INFO - Running setup.py install for mozrunner 02:26:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:26:07 INFO - Running setup.py install for mozscreenshot 02:26:07 INFO - Running setup.py install for moztest 02:26:07 INFO - Running setup.py install for mozversion 02:26:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:26:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:26:07 INFO - Cleaning up... 02:26:07 INFO - Return code: 0 02:26:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:26:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:26:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:26:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:26:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:26:07 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 0x1035edb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1c2551a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033cbd30>, '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 0x1032f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1033c0be8>, '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.P7wUXkt8wZ/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.ktMLZb66l6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 02:26:07 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 02:26:07 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 02:26:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P7wUXkt8wZ/Render', 02:26:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:26:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:26:07 INFO - 'HOME': '/Users/cltbld', 02:26:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:26:07 INFO - 'LOGNAME': 'cltbld', 02:26:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:26:07 INFO - 'MOZ_NO_REMOTE': '1', 02:26:07 INFO - 'NO_EM_RESTART': '1', 02:26:07 INFO - 'PAGER': '/bin/cat', 02:26:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:26:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:26:07 INFO - 'PWD': '/builds/slave/test', 02:26:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:26:07 INFO - 'SHELL': '/bin/bash', 02:26:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ktMLZb66l6/Listeners', 02:26:07 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 02:26:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:26:07 INFO - 'USER': 'cltbld', 02:26:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:26:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:26:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:26:07 INFO - 'XPC_FLAGS': '0x0', 02:26:07 INFO - 'XPC_SERVICE_NAME': '0', 02:26:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:26:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:26:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:26:07 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-aSwWqL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:26:08 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)) 02:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:26:08 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-r_v8nk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:26:08 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)) 02:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:26:08 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-SOvGO6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:26:08 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)) 02:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:26:08 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-rc9NKv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:26:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 02:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:26:08 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-CKasRD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:26:08 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)) 02:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:26:08 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-Ze4kKo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:26:08 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)) 02:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:26:08 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-gCNNAh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:26:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:26:08 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-TzXuw8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:26:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:26:08 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-QNtxd9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:26:08 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)) 02:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:26:08 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-ja_fbm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:26:09 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)) 02:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:26:09 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-jzCUtc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:26:09 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)) 02:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:26:09 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-IZp4V4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:26:09 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)) 02:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:26:09 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-cejq4P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:26:09 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)) 02:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:26:09 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-42nT4N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:26:09 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)) 02:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:26:09 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-08phVR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:26:09 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)) 02:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:26:09 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-EvJzBw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:26:09 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)) 02:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:26:09 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-mCwyZi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:26:09 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)) 02:26:09 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)) 02:26:09 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)) 02:26:09 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)) 02:26:09 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:26:09 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:26:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:26:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:26:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:26:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:26:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:26:11 INFO - Downloading blessings-1.6.tar.gz 02:26:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:26:11 INFO - Installing collected packages: blessings 02:26:11 INFO - Running setup.py install for blessings 02:26:11 INFO - Successfully installed blessings 02:26:11 INFO - Cleaning up... 02:26:11 INFO - Return code: 0 02:26:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:26:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:26:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:26:11 INFO - Reading from file tmpfile_stdout 02:26:11 INFO - Current package versions: 02:26:11 INFO - blessings == 1.6 02:26:11 INFO - blobuploader == 1.2.4 02:26:11 INFO - docopt == 0.6.1 02:26:11 INFO - functools32 == 3.2.3-2 02:26:11 INFO - jsonschema == 2.5.1 02:26:11 INFO - manifestparser == 1.1 02:26:11 INFO - mozInstall == 1.12 02:26:11 INFO - mozcrash == 1.0 02:26:11 INFO - mozdebug == 0.1 02:26:11 INFO - mozdevice == 0.49 02:26:11 INFO - mozfile == 1.2 02:26:11 INFO - mozhttpd == 0.7 02:26:11 INFO - mozinfo == 0.9 02:26:11 INFO - mozleak == 0.1 02:26:11 INFO - mozlog == 3.4 02:26:11 INFO - moznetwork == 0.27 02:26:11 INFO - mozprocess == 0.25 02:26:11 INFO - mozprofile == 0.28 02:26:11 INFO - mozrunner == 6.13 02:26:11 INFO - mozscreenshot == 0.1 02:26:11 INFO - mozsystemmonitor == 0.3 02:26:11 INFO - moztest == 0.8 02:26:11 INFO - mozversion == 1.4 02:26:11 INFO - psutil == 3.1.1 02:26:11 INFO - requests == 1.2.3 02:26:11 INFO - wsgiref == 0.1.2 02:26:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:26:11 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')]} 02:26:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:26:11 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')]} 02:26:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:26:11 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 0x1035edb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1c2551a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033cbd30>, '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 0x1032f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1033c0be8>, '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.P7wUXkt8wZ/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.ktMLZb66l6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 02:26:11 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 02:26:11 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 02:26:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P7wUXkt8wZ/Render', 02:26:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:26:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:26:11 INFO - 'HOME': '/Users/cltbld', 02:26:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:26:11 INFO - 'LOGNAME': 'cltbld', 02:26:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:26:11 INFO - 'MOZ_NO_REMOTE': '1', 02:26:11 INFO - 'NO_EM_RESTART': '1', 02:26:11 INFO - 'PAGER': '/bin/cat', 02:26:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:26:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:26:11 INFO - 'PWD': '/builds/slave/test', 02:26:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:26:11 INFO - 'SHELL': '/bin/bash', 02:26:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ktMLZb66l6/Listeners', 02:26:11 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 02:26:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:26:11 INFO - 'USER': 'cltbld', 02:26:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:26:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:26:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:26:11 INFO - 'XPC_FLAGS': '0x0', 02:26:11 INFO - 'XPC_SERVICE_NAME': '0', 02:26:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:26:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:26:11 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)) 02:26:11 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)) 02:26:11 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)) 02:26:11 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)) 02:26:11 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)) 02:26:11 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)) 02:26:11 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)) 02:26:11 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)) 02:26:11 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)) 02:26:11 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)) 02:26:11 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)) 02:26:11 INFO - Cleaning up... 02:26:12 INFO - Return code: 0 02:26:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:26:12 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')]} 02:26:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:26:12 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')]} 02:26:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:26:12 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 0x1035edb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1c2551a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033cbd30>, '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 0x1032f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1033c0be8>, '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.P7wUXkt8wZ/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.ktMLZb66l6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 02:26:12 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 02:26:12 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 02:26:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P7wUXkt8wZ/Render', 02:26:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:26:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:26:12 INFO - 'HOME': '/Users/cltbld', 02:26:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:26:12 INFO - 'LOGNAME': 'cltbld', 02:26:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:26:12 INFO - 'MOZ_NO_REMOTE': '1', 02:26:12 INFO - 'NO_EM_RESTART': '1', 02:26:12 INFO - 'PAGER': '/bin/cat', 02:26:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:26:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:26:12 INFO - 'PWD': '/builds/slave/test', 02:26:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:26:12 INFO - 'SHELL': '/bin/bash', 02:26:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ktMLZb66l6/Listeners', 02:26:12 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 02:26:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:26:12 INFO - 'USER': 'cltbld', 02:26:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:26:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:26:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:26:12 INFO - 'XPC_FLAGS': '0x0', 02:26:12 INFO - 'XPC_SERVICE_NAME': '0', 02:26:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:26:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:26:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:26:12 INFO - Cleaning up... 02:26:12 INFO - Return code: 0 02:26:12 INFO - Running post-action listener: _resource_record_post_action 02:26:12 INFO - Running post-action listener: _start_resource_monitoring 02:26:12 INFO - Starting resource monitoring. 02:26:12 INFO - [mozharness: 2017-06-02 09:26:12.296821Z] Finished create-virtualenv step (success) 02:26:12 INFO - [mozharness: 2017-06-02 09:26:12.297764Z] Running install step. 02:26:12 INFO - Running pre-action listener: _resource_record_pre_action 02:26:12 INFO - Running main action method: install 02:26:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:26:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:26:12 INFO - Reading from file tmpfile_stdout 02:26:12 INFO - Detecting whether we're running mozinstall >=1.0... 02:26:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:26:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:26:12 INFO - Reading from file tmpfile_stdout 02:26:12 INFO - Output received: 02:26:12 INFO - Usage: mozinstall [options] installer 02:26:12 INFO - Options: 02:26:12 INFO - -h, --help show this help message and exit 02:26:12 INFO - -d DEST, --destination=DEST 02:26:12 INFO - Directory to install application into. [default: 02:26:12 INFO - "/builds/slave/test"] 02:26:12 INFO - --app=APP Application being installed. [default: firefox] 02:26:12 INFO - mkdir: /builds/slave/test/build/application 02:26:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:26:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 02:26:32 INFO - Reading from file tmpfile_stdout 02:26:32 INFO - Output received: 02:26:32 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:26:32 INFO - Running post-action listener: _resource_record_post_action 02:26:32 INFO - [mozharness: 2017-06-02 09:26:32.382921Z] Finished install step (success) 02:26:32 INFO - [mozharness: 2017-06-02 09:26:32.383059Z] Running run-tests step. 02:26:32 INFO - Running pre-action listener: _resource_record_pre_action 02:26:32 INFO - Running main action method: run_tests 02:26:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:26:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:26:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:26:32 INFO - 2017-06-02 02:26:32.397 screenresolution[1628:7335] starting screenresolution argv=screenresolution get 02:26:32 INFO - 2017-06-02 02:26:32.400 screenresolution[1628:7335] Display 0: 1600x1200x32@60 02:26:32 INFO - 2017-06-02 02:26:32.406 screenresolution[1629:7337] starting screenresolution argv=screenresolution list 02:26:32 INFO - Available Modes on Display 0 02:26:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:26:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:26:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:26:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:26:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:26:32 INFO - Intel Iris: 02:26:32 INFO - Chipset Model: Intel Iris 02:26:32 INFO - Type: GPU 02:26:32 INFO - Bus: Built-In 02:26:32 INFO - VRAM (Dynamic, Max): 1536 MB 02:26:32 INFO - Vendor: Intel (0x8086) 02:26:32 INFO - Device ID: 0x0a2e 02:26:32 INFO - Revision ID: 0x0009 02:26:32 INFO - Displays: 02:26:32 INFO - Display: 02:26:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:26:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:26:32 INFO - Display Serial Number: 1600x1200 60 02:26:32 INFO - Main Display: Yes 02:26:32 INFO - Mirror: Off 02:26:32 INFO - Online: Yes 02:26:32 INFO - Rotation: Supported 02:26:32 INFO - Return code: 0 02:26:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:26:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:26:32 INFO - Python 2.7.3 02:26:32 INFO - Return code: 0 02:26:32 INFO - grabbing minidump binary from tooltool 02:26: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')]} 02:26:32 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033cbd30>, '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 0x1032f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1033c0be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:26:32 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:26:32 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 02:26:32 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 02:26:32 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:26:32 INFO - Return code: 0 02:26:32 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:26:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:26:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:26:32 INFO - ENV: RUST_BACKTRACE is now 1 02:26:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:26:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:26:32 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/GmSTE1R7TYCSbLYmhzPe-Q/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0291', '--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 02:26:32 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/GmSTE1R7TYCSbLYmhzPe-Q/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0291 --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 02:26:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P7wUXkt8wZ/Render', 02:26:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:26:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:26:32 INFO - 'HOME': '/Users/cltbld', 02:26:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:26:32 INFO - 'LOGNAME': 'cltbld', 02:26:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:26:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:26:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:26:32 INFO - 'MOZ_NO_REMOTE': '1', 02:26:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:26:32 INFO - 'NO_EM_RESTART': '1', 02:26:32 INFO - 'PAGER': '/bin/cat', 02:26:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:26:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:26:32 INFO - 'PWD': '/builds/slave/test', 02:26:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:26:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:26:32 INFO - 'RUST_BACKTRACE': '1', 02:26:32 INFO - 'SHELL': '/bin/bash', 02:26:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ktMLZb66l6/Listeners', 02:26:32 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 02:26:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:26:32 INFO - 'USER': 'cltbld', 02:26:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:26:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:26:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:26:32 INFO - 'XPC_FLAGS': '0x0', 02:26:32 INFO - 'XPC_SERVICE_NAME': '0', 02:26:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:26:32 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/GmSTE1R7TYCSbLYmhzPe-Q/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0291', '--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 02:26:32 INFO - mozversion application_buildid: 20170602013225 02:26:32 INFO - mozversion application_changeset: 4f0bd6c5b03dafa6025621da30e1bc87008d027b 02:26:32 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:26:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:26:32 INFO - mozversion application_name: Firefox 02:26:32 INFO - mozversion application_remotingname: firefox-dev 02:26:32 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 02:26:32 INFO - mozversion application_vendor: Mozilla 02:26:32 INFO - mozversion application_version: 54.0 02:26:32 INFO - mozversion platform_buildid: 20170602013225 02:26:32 INFO - mozversion platform_changeset: 4f0bd6c5b03dafa6025621da30e1bc87008d027b 02:26:32 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 02:26:32 INFO - mozversion platform_version: 54.0 02:26:32 INFO - using testdate: 1496395592 02:26:32 INFO - actual date: 1496395592 02:26:32 INFO - starting webserver on 'localhost:49226' 02:26:32 INFO - SUITE-START | Running 2 tests 02:26:32 INFO - TEST-START | dromaeo_css 02:26:32 INFO - operating with platform_type : mac_ 02:26:32 INFO - Initialising browser for dromaeo_css test... 02:26:33 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/tmp_GvROv/profile http://localhost:49226/getInfo.html) 02:26:35 INFO - PROCESS | 1639 | 2017-06-02 02:26:35.981 plugin-container[1642:7519] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d43, name = 'com.apple.tsm.portname' 02:26:35 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:26:35 INFO - PROCESS | 1639 | 2017-06-02 02:26:35.982 plugin-container[1642:7519] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x921b, name = 'com.apple.CFPasteboardClient' 02:26:35 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:26:35 INFO - PROCESS | 1639 | 2017-06-02 02:26:35.982 plugin-container[1642:7519] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:26:36 INFO - PROCESS | 1639 | __metrics Screen width/height:1600/1200 02:26:36 INFO - PROCESS | 1639 | colorDepth:24 02:26:36 INFO - PROCESS | 1639 | Browser inner width/height: 1024/689 02:26:36 INFO - PROCESS | 1639 | __metrics 02:26:36 ERROR - PROCESS | 1639 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3381: TypeError: this.tabs is undefined 02:26:36 INFO - TEST-INFO | 1639: exit 0 02:26:36 INFO - Browser initialized. 02:26:36 INFO - Running cycle 1/1 for dromaeo_css test... 02:26:36 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/tmp_GvROv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:26:38 INFO - PROCESS | 1643 | 2017-06-02 02:26:38.990 plugin-container[1644:7647] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4343, name = 'com.apple.tsm.portname' 02:26:38 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:26:38 INFO - PROCESS | 1643 | 2017-06-02 02:26:38.991 plugin-container[1644:7647] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 02:26:38 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:26:38 INFO - PROCESS | 1643 | 2017-06-02 02:26:38.991 plugin-container[1644:7647] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:26:48 INFO - PROCESS | 1643 | RSS: Main: 203014144 02:26:48 INFO - PROCESS | 1643 | 02:29:51 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49226/tests/dromaeo/cssquery-ext.html) 02:29:53 INFO - PROCESS | 1643 | RSS: Main: 216166400 02:29:53 INFO - PROCESS | 1643 | 02:32:55 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-ext.html (next: http://localhost:49226/tests/dromaeo/cssquery-jquery.html) 02:32:56 INFO - PROCESS | 1643 | RSS: Main: 228675584 02:32:56 INFO - PROCESS | 1643 | 02:35:58 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49226/tests/dromaeo/cssquery-mootools.html) 02:35:59 INFO - PROCESS | 1643 | RSS: Main: 232361984 02:35:59 INFO - PROCESS | 1643 | 02:39:00 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49226/tests/dromaeo/cssquery-prototype.html) 02:39:01 INFO - PROCESS | 1643 | RSS: Main: 236040192 02:39:01 INFO - PROCESS | 1643 | 02:42:04 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49226/tests/dromaeo/cssquery-yui.html) 02:42:05 INFO - PROCESS | 1643 | RSS: Main: 238243840 02:42:05 INFO - PROCESS | 1643 | 02:45:08 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-yui.html (next: http://localhost:49226/tests/dromaeo/cssquery-dojo.html) 02:45:08 INFO - PROCESS | 1643 | RSS: Main: 236281856 02:45:08 INFO - PROCESS | 1643 | 02:45:08 INFO - PROCESS | 1643 | __start_tp_report 02:45:08 INFO - PROCESS | 1643 | _x_x_mozilla_page_load 02:45:08 INFO - PROCESS | 1643 | _x_x_mozilla_page_load_details 02:45:08 INFO - PROCESS | 1643 | |i|pagename|runs| 02:45:08 INFO - PROCESS | 1643 | |0;dojo.html;3487.773294735842;3487.825608719565;3509.192885636304;3520.4895290182917;3537.3632746105723;5958.821235362935;6032.0951857221435;6037.879242415149;6041.24484439445;6073.028315469526;5971.910421343683;5994.910076348859;6023.668698221596;6036.426539478743;6083.513318934486;5414.756335964881;5456.208849716789;5473.2063850741615;5473.589480788937;5529.861753456155;4854.737768180281;5144.717040562768;5520.91718624221;5523.917141242885;5555;5302.09864323066;5323.547498462635;5507.284053073095;5526.944730552683;5527.364353099405;4459.821607135711;5422.864428389283;5427;5441.646292990943;5444.074507333763;5349.197620356938;5377.865553361158;5405.648632838853;5417.052015897241;5495.890082198333;6102.298235702907;6113.052476866093;6126.846828829271;6133.233345831771;6138.447539721446;6223.097650840648;6303.621782693053;6373.585716928431;6390.616563006234;6411.839704007437;6333.651649159295;6334;6349.746010159588;6360.586561873463;6379.649119298437;5079.8984020319385;5125.282460455539;5526.005319042571;5719.28508936383;6068.787592434243;6483.773067942694;6510.18622672166;6533.24867640218;6536.901946470807;6540.247871494839;5259.947400526023;6134.957077260912;6269.279032911182;6274.780382686584;6279.183706118175;6457.3865482779065;6458;6463.353664633499;6463.353664633499;6463.838404039937;6364.109024736541;6369.9681501592195;6382.138411314506;6382.20222472191;6395.840103997437;5846.7076646167525;6255.843603909848;6280.05799130125;6309.463695585835;6326.145970294044;4558.179527685049;4931;4938.530839570235;4946.703197808143;4976.129177393942;4441.933370999438;4448.911021779546;4454.198244316054;4465.531119232499;4489.9775501122285;4984.5513903748115;5684.6589204647025;5689.317281926113;5692.57305702074;5894.440028197314;6328.145700330397;6372.649504277217;6374.235091789015;6391.872162556723;6396.968015159894;5718.599698021182;5722.685252311163;5746.396628354046;5755.482006619424;5771.422857714195;5397.136458166675;5418.431064738224;5471.015217260931;5544.140658197985;5571.220029195916;6646.136002319861;6649.534532582673;6653.500987425962;6656.301088385747;6693.564918280296;6419.133416988647;6442.710078046405;6451.774187903401;6458.644774537258;6489.026646003137;6280.685965701788;6387.329330420239;6552.57408268461;6581;6594.934050659432;6324.430801227912;6325.146105275729;6327.746890124344;6375.6812159392775;6375.936240637534;5257.447967963309;6399.072134540559;6404.71178796946;6413.038044293206;6414.935850641434;5401.432849550898;6146.877062458815;6254.365483246566;6284.24589049317;6343.238811342669;6253.656048917172;6258.499320054294;6260.40526150015;6293.37066293367;6312.968435157978;6206.06908963659;6278.0896769967185;6293.458102764852;6298.149749783904;6299.181106456132;6311.0217916223055;6338.968305158629;6343.080253363144;6375.968120159369;6384.6169229846355;6042.0936859469675;6087.8782424350375;6115.449609535163;6139.2325959255095;6141.986572215535 02:45:08 INFO - PROCESS | 1643 | |1;ext.html;705.8459418850182;817.860963636182;840.0465471689632;978.4276198423926;980.9509524523781;39941.80029099854;41006.79496602517;43090.569094309045;43223.3516497252;43330.56669433305;33384.49923251147;40400.78797636068;40545.59454405455;40627.57803476879;40656.98357541063;8181.345492360611;8310.459820111673;10966.013058824705;11216.66350009501;11463.312201267909;10971.177161712863;11077.778444431107;11087.33475991443;11097.278676886017;11101.333919964827;11118;11139.721506962309;11155.27490713101;11170.882911708804;11177.161712871526;10211.948940255288;10446.947765261162;11064.114870810316;11099.611513596985;11101.111911047097;9342.57958391874;10817.891821081847;10819.350838949687;10853.34879907208;10876.18428617849;34173.487397688805;34362.82818585916;34402.31195376103;34437.794677186175;34470.482942755625;3878.9030274243087;3896.7467114637457;3898.1813819098024;3919.176972835708;3953.940690889639;25666.358341041436;26766;26787.598186027226;26794.928202871863;26873.596896046576;3044.3911217756536;3109.455845227076;3131.3424180922034;3196.568463257454;3318.1372843060735;362478.1876090629;363406;363492.54761178605;365080;366298.16850915574;37417.43873841895;40258.99999999941;42169.15661686749;44705.87058905845;44750.32874506885;45895;45932.08135837331;46216.844578885124;46268.768656157175;46288.76855615768;13273.734525309439;13312.800307995389;13346.532871349456;13406.798898016537;13432;11428.94285528567;11439.485223164978;11451.141164412702;11472.196946213686;11497.597584084684;10330.845037324447;11618;11740.58907938218;11858.347790871412;11860.525578976743;4551.8179272829375;4742.288656701522;5489.780408783684;5568.164775283659;5592.217089607459;1096.6620722718271;1109.944502774858;1152.9308241505537;1205.6684411786828;1212.2302338015425;20653.17387304491;26883.865580671973;27225.72774272232;27319.224250424057;27343.45313093727;940.3229674634252;1161.9186656933935;1190.2323001663879;1242.8073648584484;1297.863724308953;15263.931524793157;16077.305301006407;16424.6715065698;16434.342626294816;16585.50243492697;478085;486438;486874;487608;488454;60483.09275360873;60551.69724151439;60647;60846.47883802852;61014;1258.4903114238748;1284.4091717809724;1458.9343479543445;1484.7253258147493;1498.655309278872;25814.483710326065;26129.346766330993;30078;30656.693433066277;31521.21196970094;45562.31656525088;46000.079998399175;46467.53532464767;49020.019599608524;49065.754671226416;525581;528629;530025;531095;533262;32163.839180803945;32414.51378229281;32434.351312973136;32452;32585.511217331285;38910.80544597259;38980.63567775114;39237.607623923395;39726.60273397229;40151.99620009523;27216.72783272142;27613.171604851887;27918.3020424491;27985;28748;27567.89728410841;27826;28447.862085516346;28496.57255141133;28592.714072859006;27714.722852771214;30454.695453045188;32161;32758.853440129482;33463;29074.854625726737;29150.56274155932;29403.41193176167;29463.705362946097;29872.954446594264;19618.076384722826;22694.886525567268;23279.53440931206;23720.288391348287;23838.404039899142 02:45:08 INFO - PROCESS | 1643 | |2;jquery.html;388.35339160298105;412.6410023279747;415.4764996104907;429.67774169372973;455.8330673475902;18003.189856456458;20459.465540084515;22087.779122208773;22165.335039948786;22267.331980040584;21813.01841417136;22281.108755649755;22506.987185576647;22628.43428914278;22647.20734774283;7023.122109736283;7215.639218039103;7222.602756848371;7296.270372962715;7298.744543940963;6868.484863635223;7553.924460755405;7906.327962123227;7962.124166341698;7985.800354991114;7828.491148075357;7907.762767116967;7917.485363451386;8018.879716804253;8022.638981245858;7804.921950780476;7976;8041.1154772975215;8090.190980901922;8133.837323253531;7592.658330375147;7757.7672669819995;7798;7810;7817.609119544;23446.765532344798;24362.147324843547;26451.73548264512;27200.31999200016;27332.863335683393;2872.113332039591;3075.984620076885;3076.4769989101906;3088.4286407014774;3165.7309128724087;16007.27967241477;16466.341346346133;16588.15895706096;16737.330506779836;17406.47780566585;2052.9735132433784;2159.9352019439443;2173.185390199186;2193.396815875647;2210.712607361049;129649.35175324157;165342;172321.99999999875;173906;176804;16896.40862569829;20821.37535873926;21243.78756212418;21653.566928661337;21989.67015494769;31827.840860795546;32228.67771322257;32410;32454.70181192773;32501.51247731286;9099.58956361765;9604.66383676568;10353.809311929073;10413.5834566618;10462.581496740197;8532.744017679479;8539.829203415897;8558.01582817984;8713.564321784012;8768.693095741619;8111.9594402027615;9685.70942871715;9784.804303914025;9785.902140978642;9961.252906032076;4049.6760259179196;4134.937975930364;4157.127003329335;4159.086820062745;4163.250614889288;1011.8482227665762;1035.7928414317166;1045.7333379988054;1082.4641802307908;1089.536946797608;15764.68470630581;18276.268949241883;19855.702164467544;20936.162553497994;21044;928.2805825485303;1021.4331046269392;1026.312370711625;1061.2359101446946;1065.6749691344214;8081.555514446762;10860.348379097122;10986;10993.560257589606;11051.060659843843;764433;780933.095334531;786980;788017.0599146967;819287;145045.27477362758;148619.25690371695;149528;151525;152556;1171.56297150972;1184.413715210976;1212.5452955141823;1217.15407791584;1222.7554489102038;17331.306747730454;18263.178156983235;18271.451856444328;18291.54271143232;18382.540436489195;55437.891242175734;58811.52971175755;58989.820203596544;59789;60027;71339.64330178316;72844.27155728375;76544.2345576537;81780;83845;11460;11554.653360399201;11626.941865290619;11964.461599227881;11978.880211198126;13041.152325098838;13236.404361803548;13430.865691342962;13587.320633968064;13986;10061.1889859823;11417.942910285396;11577.942110289394;11994.580189693319;12072.45673944657;10447.84328235062;11053.778924421627;11140.721481963015;11157.107431405306;11187.944060279646;10586.206034547286;10677.359358438518;11612.361320127475;11939.283642981449;12106.576269830513;11765.294082355085;12012.579559715368;12038.638840834788;12353.3823308836;12374.81437778416;11668.358240296866;11718.062554995751;11845.23006004607;11959.820602691141;12068.577599783965 02:45:08 INFO - PROCESS | 1643 | |3;mootools.html;1567.3338830996825;1644.8601868841147;1652.9173541322923;1655.8013038435386;1657.7430498272768;10330.845037324447;10839.024487796098;10858.077063449606;11376.203665743402;11642.534298628067;11351.659450216486;11517.193796434252;11527.481263343148;11534.711632209199;11557.248778829371;2210.347947355534;2249.1003598560624;2251.0995601759346;2251.4596496840722;2257;2245.1805091141778;2314.1553333033407;2330.8135349172026;2348.635961425982;2363.267387109993;2275.9089636414606;2277.9886100569474;2278.553118769585;2289.0271634555247;2323.326235391743;2232.732072151336;2286.4626812699003;2291.6218823894205;2297.9080836766593;2298.287530865429;2266.150193677371;2277.202978957368;2280.2133264023887;2282.3038973113194;2286.9199578014855;11374.090072794157;11406.828897566544;11410.201285909907;11433.14251431146;11434.428278586121;5313.362396512443;5451.2640793492765;5468.6171967962255;5480.890382192374;5493.725313734337;3157.7947433416753;3215.5498230247786;3329.20099176196;3393.8630558762843;3395.6264810870784;1127.9041281491084;1146.4955419615426;1180.6044974933407;1183.1540448579221;1192.5527927027365;13816.723665526633;14146.646333841634;15141.621459463491;15666;15713.371465141381;4203.390508376268;4265.914681706348;4366.257736184856;4380.408644832971;4402.515723270438;10707.411092389837;11540.538378464935;11951.22317049389;11961.521539138512;11978;5412.107002344569;5428.83713488596;5434.157705555645;5435.211894275308;5463;4944.60443164546;4979.103761322925;5005.1241032819835;5010.69935803853;5035.79856805731;3037.830435282388;3642.2715456908722;3676.319880822003;3706.1105334720014;3724.776513409204;840.5335039053261;889.2574700125467;903.2187158108189;916.834969705446;916.9449833010041;847.169773621854;909.3043821476575;919.3794188922451;995.8157740817971;1052.0478966535297;6645.1361323027695;6735.663216839139;6852.314768523208;6979.790606281819;7070.823229419306;1047.9266451348485;1066.1630619963291;1071.8124328242682;1092.7268182954263;1093.1637297467444;4191.392248124005;4204.453421055243;4211;4227.281362168439;4340.60934515895;13745.518906838213;14325.78511322331;14360.138391696531;14384.85615143856;14402.135871847722;13205.603831885059;14174.716505669829;14253.358598863077;14258.073225240534;14289.285535723173;8887.244584210286;10263.12763415118;10468.52891619879;10557;10568.313059651098;5066.36670416198;5886.970565147147;6798.388145066968;6957.860842783217;7111.82220444493;11956;11976.940115299716;12020.33888136161;12075.094367922617;12345.876541234473;12390.938045309716;12680.556180533637;12714.682132946751;12715.982721382083;13004.414801334153;9380.95309523448;9477.24182065447;9492.715218543455;9546.474943878153;9571.569279382584;10394.792104157723;10583.62957296491;10588.10001149911;10604.8939510607;10612.946935265583;8908.376413651242;8996.370254082152;9041.276697864287;9085.13691199335;9143.63425462974;8824.470531767858;8847.380683352361;8883.845100136941;8901.421407608745;8935.151160639729;6823.454123669995;8077.878831817645;8141.185881411811;8178.83642327162;8215.096339402542;5981.282246130492;6044.546659000681;6059.72731227087;6118.785842495491;6128.434316434285;5430.945690543044;6042.607230530001;6079.270487541523;6159.4456498915315;6221.906671399842 02:45:08 INFO - PROCESS | 1643 | |4;prototype.html;330.8891521340351;382.37290843017456;387.1231660289443;393.2861855731848;396.5063495947545;5004.349434573501;5022.020705962339;5190.688558686476;5386.8383948481605;5395.602583894129;4721.575058244757;4762.952150526888;4820.39745031871;4842.709437433757;5250.737463126838;3397.5073614325906;3529.6293889141593;3780.962190378102;4107.938380924288;4173.059527319796;3749.793761343125;3957.7229593928437;4172.08214192877;4183.288840897055;4211.452511173543;3568.92862142757;3765.8870233893026;4122.917541649165;4146.232946904832;4169.854055108071;3486.9887732557527;3891.5135608048995;4047.1500984793083;4118.423420721101;4171.249175148472;3393.6606339366112;3477.426224672926;3636.3999940009867;3650.324689932371;3689.5756987946193;4825.252085926687;5128.2564028216075;5155.53600175986;5166.095933211673;5169.870753231162;4023.97988010061;4182.330827067685;4646.210144275447;4804.1352556539805;4810.687305325143;4744;5560.972195139039;5586.217929489874;5609.382967873531;5618.606697531174;3729.533808273966;4054.013533368628;4089.3661482470266;4124.1133156371225;4132.40080188374;5062.797488100508;5066.144859927076;5973.296556135224;6028.517718582503;6030.517558595302;4432.224360736858;4519.502854685982;4599.678022538457;4941.604671626261;5040.160341475335;4483.461984561874;4557.931631025537;4822.95177048232;4857.514248575114;5017.698938063655;4000.5399379071196;5100.724818795301;5262.815801446931;5274.314339135888;5290.9682611891085;5446.537044351196;5505.421930697219;5574.188388773665;5598.384177740445;5686.886262274731;1509.0492989416598;1554.3627112883664;1593.39451008616;1607.6977648105071;1618;1087.7606926476162;1206.4872429217723;1211.7576484703095;1214.7184720120358;1219.7499512599895;4593.655475839326;4604.7927843247135;4656.837010704609;4976.576990955737;5122.797197205163;5256;5554.361248456398;5849.678267695318;5879.265091863517;5901.3508514063415;4577.496475387705;4590.770461476913;4686.601638860667;4710.642655543608;4906.754662266872;2753.251685179925;2805.495010898058;2923.7633210593913;3017.351269477073;3053.9389212216074;6035.66803825785;6067;6071.2714474263375;6082.965895797725;6092.421219984094;5117.923231151536;5216.347956505361;5588.720563971785;5635.830925072254;6024.819255422344;5120.799500312305;5858.853528661817;5907.9704601476715;6034.2373033752665;6088.726007329769;5147.794088236428;5585.636933599303;5627.0434026214825;5735.598508104393;5874.441928016831;4999.55013046227;5212.661177023481;5700.116481945305;5716.456936591017;5827.883442331215;5584.4136365681015;5817.534597232296;6015.699215039318;6080.452759251689;6109.991851344478;4092.9944327280014;4370.950971766817;4403.075354175659;4546.726636681659;4560.566746159109;5209.9001094348805;5255.634964266019;5668.773249069991;5786.652800831964;5815;4882.241533533542;5102.800841802099;5437.700926449083;5626.861792518816;5631.63394379364;4353.629941454885;5252.606054546;5324.254604355471;5628.634138780966;5676.545876329967;4605.447346318463;5421.83734487966;5452.700101494456;5479.315085614298;5521.668699878021;5082.644214904921;5206.427292997691;5652.491275785199;5689.601727879174;5711.8000869969355;5260.473952604709;5276.81531146408;5286.36563612369;5368.436314186954;5444.277861069465 02:45:08 INFO - PROCESS | 1643 | |5;yui.html;603.3604379357884;743.8512297540493;767.973120940767;794.3446656508379;845.2688424512796;15886.443974460883;16888.733344999127;16899.577510562212;16932.661346773057;16963.915180424097;14892.627684307898;15489.380424783023;15739.685206295868;16027.919860400682;17223.569410764707;1000.7440661969237;1022.1107636356343;1024.9641262555813;1025.646152077532;1029.9948500257487;971.2230215827345;1024.7540590258322;1025.8153532364172;1026.1021606094666;1026.363654534186;1075.1345167140448;1091.6888686724285;1093.907017903479;1094.8412480190368;1096.2271598523023;1058.7194393485695;1085.7502774361901;1088.4666513408451;1088.9618863339786;1091.6233899304727;820.0405525535139;820.5240960243104;821.6179476543437;824.47233770387;825.9876101858417;16832.915835420867;17174.313027478885;17247.482575522754;17360.479185624452;17576.29694812206;14222.857771422361;14267.357968891425;14268.500602478864;14487.855121448756;14606.853931460655;11693.766124677459;11839.763204735944;11907;11916;12428.627141185689;3590.2281009582803;3764.153065560227;3815.3895376739865;3872.845086196577;3896.279188350164;115687.42156289164;118877.40561297224;119461.40269298598;120679;121361;10575.524101415454;10698.465076746132;10866.293690910066;11428.657140285639;11442.599509017144;22610.321690349316;22857.31428057161;22871.656925146137;22883;22974;7332.670029848669;7848.686052557948;7860.685572577033;7948.880766788503;8191.631376588068;3868.883933482;4351.347297905276;4437.866863994085;4540.919402155283;4603.378543896598;3451.4132597458492;3522.577290725078;3532.699720523733;3544.0962554548423;3544.929101417957;556.5714399912046;624.4442446222865;638.5944924972583;655.1155939481662;664.6510581944422;666.0142988377229;671.278375746075;693.7329128285545;707.8265824872836;737.3290305821677;10213.904275102213;10416.791664166825;10553.366797992145;10795;10804.243702940876;662.2946561911644;671.5299290496672;684.658070182438;714.7498375568509;718.8741970155202;5042.924356134661;5104.795808167632;5123.564497017721;5126.666766660155;5130.179171332644;105197;105276.42085368726;106396;106866.46566767116;108643.91356086497;77830;90441.54779226062;93570;94482;96662.06675866443;66987.6602467948;67582;70621;72988.17529561697;74260.62869685725;10582.8412573813;11148.163887708291;11184.273022253528;11225.326480411202;11340.319580825177;39639.20721585494;41419.58580414157;48906.5109348902;51893.7405312971;52229.73885130702;58618;59571.70214148902;63275;65811.67094164499;66104;12468.563600273947;12625;12804.935975320064;13017.088803783645;13298.86701133015;14394.856051439352;14465.855341446872;14501.854981450473;14798.186099764616;15258.389664413298;12377.504899803907;13202;13505.527306544223;13760.65598360049;14215.949020308202;13156.473741050251;13351.732965340445;13874.65313367174;14384;14463.421463141356;13043.869561304266;13434.596962091153;13636;13871.167729936236;13886.722265554834;9000.954995224982;9179.586918588813;9190.35667503295;9281.67514137002;9384.812303754023;9677.9032209677;11322.81056009282;11390.031847293072;11395.487203075716;11422.600208992646 02:45:08 INFO - PROCESS | 1643 | __end_tp_report 02:45:08 INFO - PROCESS | 1643 | __start_cc_report 02:45:08 INFO - PROCESS | 1643 | _x_x_mozilla_cycle_collect,372 02:45:08 INFO - PROCESS | 1643 | __end_cc_report 02:45:08 INFO - PROCESS | 1643 | __startTimestamp1496396708291__endTimestamp 02:45:08 INFO - PROCESS | 1643 | 02:45:08 INFO - PROCESS | 1643 | ------- Summary: start ------- 02:45:08 INFO - PROCESS | 1643 | Number of tests: 6 02:45:08 INFO - PROCESS | 1643 | 02:45:08 INFO - PROCESS | 1643 | [#0] dojo.html Cycles:165 Average:5881.35 Median:6138.45 stddev:689.00 (11.2%) stddev-sans-first:665.04 02:45:08 INFO - PROCESS | 1643 | Values: 3487.8 3487.8 3509.2 3520.5 3537.4 5958.8 6032.1 6037.9 6041.2 6073.0 5971.9 5994.9 6023.7 6036.4 6083.5 5414.8 5456.2 5473.2 5473.6 5529.9 4854.7 5144.7 5520.9 5523.9 5555.0 5302.1 5323.5 5507.3 5526.9 5527.4 4459.8 5422.9 5427.0 5441.6 5444.1 5349.2 5377.9 5405.6 5417.1 5495.9 6102.3 6113.1 6126.8 6133.2 6138.4 6223.1 6303.6 6373.6 6390.6 6411.8 6333.7 6334.0 6349.7 6360.6 6379.6 5079.9 5125.3 5526.0 5719.3 6068.8 6483.8 6510.2 6533.2 6536.9 6540.2 5259.9 6135.0 6269.3 6274.8 6279.2 6457.4 6458.0 6463.4 6463.4 6463.8 6364.1 6370.0 6382.1 6382.2 6395.8 5846.7 6255.8 6280.1 6309.5 6326.1 4558.2 4931.0 4938.5 4946.7 4976.1 4441.9 4448.9 4454.2 4465.5 4490.0 4984.6 5684.7 5689.3 5692.6 5894.4 6328.1 6372.6 6374.2 6391.9 6397.0 5718.6 5722.7 5746.4 5755.5 5771.4 5397.1 5418.4 5471.0 5544.1 5571.2 6646.1 6649.5 6653.5 6656.3 6693.6 6419.1 6442.7 6451.8 6458.6 6489.0 6280.7 6387.3 6552.6 6581.0 6594.9 6324.4 6325.1 6327.7 6375.7 6375.9 5257.4 6399.1 6404.7 6413.0 6414.9 5401.4 6146.9 6254.4 6284.2 6343.2 6253.7 6258.5 6260.4 6293.4 6313.0 6206.1 6278.1 6293.5 6298.1 6299.2 6311.0 6339.0 6343.1 6376.0 6384.6 6042.1 6087.9 6115.4 6139.2 6142.0 02:45:08 INFO - PROCESS | 1643 | 02:45:08 INFO - PROCESS | 1643 | [#1] ext.html Cycles:180 Average:58507.82 Median:16749.54 stddev:123959.16 (740.1%) stddev-sans-first:124230.93 02:45:08 INFO - PROCESS | 1643 | Values: 705.8 817.9 840.0 978.4 981.0 39941.8 41006.8 43090.6 43223.4 43330.6 33384.5 40400.8 40545.6 40627.6 40657.0 8181.3 8310.5 10966.0 11216.7 11463.3 10971.2 11077.8 11087.3 11097.3 11101.3 11118.0 11139.7 11155.3 11170.9 11177.2 10211.9 10446.9 11064.1 11099.6 11101.1 9342.6 10817.9 10819.4 10853.3 10876.2 34173.5 34362.8 34402.3 34437.8 34470.5 3878.9 3896.7 3898.2 3919.2 3953.9 25666.4 26766.0 26787.6 26794.9 26873.6 3044.4 3109.5 3131.3 3196.6 3318.1 362478.2 363406.0 363492.5 365080.0 366298.2 37417.4 40259.0 42169.2 44705.9 44750.3 45895.0 45932.1 46216.8 46268.8 46288.8 13273.7 13312.8 13346.5 13406.8 13432.0 11428.9 11439.5 11451.1 11472.2 11497.6 10330.8 11618.0 11740.6 11858.3 11860.5 4551.8 4742.3 5489.8 5568.2 5592.2 1096.7 1109.9 1152.9 1205.7 1212.2 20653.2 26883.9 27225.7 27319.2 27343.5 940.3 1161.9 1190.2 1242.8 1297.9 15263.9 16077.3 16424.7 16434.3 16585.5 478085.0 486438.0 486874.0 487608.0 488454.0 60483.1 60551.7 60647.0 60846.5 61014.0 1258.5 1284.4 1458.9 1484.7 1498.7 25814.5 26129.3 30078.0 30656.7 31521.2 45562.3 46000.1 46467.5 49020.0 49065.8 525581.0 528629.0 530025.0 531095.0 533262.0 32163.8 32414.5 32434.4 32452.0 32585.5 38910.8 38980.6 39237.6 39726.6 40152.0 27216.7 27613.2 27918.3 27985.0 28748.0 27567.9 27826.0 28447.9 28496.6 28592.7 27714.7 30454.7 32161.0 32758.9 33463.0 29074.9 29150.6 29403.4 29463.7 29873.0 19618.1 22694.9 23279.5 23720.3 23838.4 02:45:08 INFO - PROCESS | 1643 | 02:45:08 INFO - PROCESS | 1643 | [#2] jquery.html Cycles:180 Average:44048.75 Median:12003.21 stddev:131163.02 (1092.7%) stddev-sans-first:131489.99 02:45:08 INFO - PROCESS | 1643 | Values: 388.4 412.6 415.5 429.7 455.8 18003.2 20459.5 22087.8 22165.3 22267.3 21813.0 22281.1 22507.0 22628.4 22647.2 7023.1 7215.6 7222.6 7296.3 7298.7 6868.5 7553.9 7906.3 7962.1 7985.8 7828.5 7907.8 7917.5 8018.9 8022.6 7804.9 7976.0 8041.1 8090.2 8133.8 7592.7 7757.8 7798.0 7810.0 7817.6 23446.8 24362.1 26451.7 27200.3 27332.9 2872.1 3076.0 3076.5 3088.4 3165.7 16007.3 16466.3 16588.2 16737.3 17406.5 2053.0 2159.9 2173.2 2193.4 2210.7 129649.4 165342.0 172322.0 173906.0 176804.0 16896.4 20821.4 21243.8 21653.6 21989.7 31827.8 32228.7 32410.0 32454.7 32501.5 9099.6 9604.7 10353.8 10413.6 10462.6 8532.7 8539.8 8558.0 8713.6 8768.7 8112.0 9685.7 9784.8 9785.9 9961.3 4049.7 4134.9 4157.1 4159.1 4163.3 1011.8 1035.8 1045.7 1082.5 1089.5 15764.7 18276.3 19855.7 20936.2 21044.0 928.3 1021.4 1026.3 1061.2 1065.7 8081.6 10860.3 10986.0 10993.6 11051.1 764433.0 780933.1 786980.0 788017.1 819287.0 145045.3 148619.3 149528.0 151525.0 152556.0 1171.6 1184.4 1212.5 1217.2 1222.8 17331.3 18263.2 18271.5 18291.5 18382.5 55437.9 58811.5 58989.8 59789.0 60027.0 71339.6 72844.3 76544.2 81780.0 83845.0 11460.0 11554.7 11626.9 11964.5 11978.9 13041.2 13236.4 13430.9 13587.3 13986.0 10061.2 11417.9 11577.9 11994.6 12072.5 10447.8 11053.8 11140.7 11157.1 11187.9 10586.2 10677.4 11612.4 11939.3 12106.6 11765.3 12012.6 12038.6 12353.4 12374.8 11668.4 11718.1 11845.2 11959.8 12068.6 02:45:08 INFO - PROCESS | 1643 | 02:45:08 INFO - PROCESS | 1643 | [#3] mootools.html Cycles:180 Average:6755.20 Median:2318.74 stddev:4334.11 (186.9%) stddev-sans-first:4328.74 02:45:08 INFO - PROCESS | 1643 | Values: 1567.3 1644.9 1652.9 1655.8 1657.7 10330.8 10839.0 10858.1 11376.2 11642.5 11351.7 11517.2 11527.5 11534.7 11557.2 2210.3 2249.1 2251.1 2251.5 2257.0 2245.2 2314.2 2330.8 2348.6 2363.3 2275.9 2278.0 2278.6 2289.0 2323.3 2232.7 2286.5 2291.6 2297.9 2298.3 2266.2 2277.2 2280.2 2282.3 2286.9 11374.1 11406.8 11410.2 11433.1 11434.4 5313.4 5451.3 5468.6 5480.9 5493.7 3157.8 3215.5 3329.2 3393.9 3395.6 1127.9 1146.5 1180.6 1183.2 1192.6 13816.7 14146.6 15141.6 15666.0 15713.4 4203.4 4265.9 4366.3 4380.4 4402.5 10707.4 11540.5 11951.2 11961.5 11978.0 5412.1 5428.8 5434.2 5435.2 5463.0 4944.6 4979.1 5005.1 5010.7 5035.8 3037.8 3642.3 3676.3 3706.1 3724.8 840.5 889.3 903.2 916.8 916.9 847.2 909.3 919.4 995.8 1052.0 6645.1 6735.7 6852.3 6979.8 7070.8 1047.9 1066.2 1071.8 1092.7 1093.2 4191.4 4204.5 4211.0 4227.3 4340.6 13745.5 14325.8 14360.1 14384.9 14402.1 13205.6 14174.7 14253.4 14258.1 14289.3 8887.2 10263.1 10468.5 10557.0 10568.3 5066.4 5887.0 6798.4 6957.9 7111.8 11956.0 11976.9 12020.3 12075.1 12345.9 12390.9 12680.6 12714.7 12716.0 13004.4 9381.0 9477.2 9492.7 9546.5 9571.6 10394.8 10583.6 10588.1 10604.9 10612.9 8908.4 8996.4 9041.3 9085.1 9143.6 8824.5 8847.4 8883.8 8901.4 8935.2 6823.5 8077.9 8141.2 8178.8 8215.1 5981.3 6044.5 6059.7 6118.8 6128.4 5430.9 6042.6 6079.3 6159.4 6221.9 02:45:08 INFO - PROCESS | 1643 | 02:45:08 INFO - PROCESS | 1643 | [#4] prototype.html Cycles:180 Average:4619.64 Median:5031.09 stddev:1330.55 (26.4%) stddev-sans-first:1294.76 02:45:08 INFO - PROCESS | 1643 | Values: 330.9 382.4 387.1 393.3 396.5 5004.3 5022.0 5190.7 5386.8 5395.6 4721.6 4763.0 4820.4 4842.7 5250.7 3397.5 3529.6 3781.0 4107.9 4173.1 3749.8 3957.7 4172.1 4183.3 4211.5 3568.9 3765.9 4122.9 4146.2 4169.9 3487.0 3891.5 4047.2 4118.4 4171.2 3393.7 3477.4 3636.4 3650.3 3689.6 4825.3 5128.3 5155.5 5166.1 5169.9 4024.0 4182.3 4646.2 4804.1 4810.7 4744.0 5561.0 5586.2 5609.4 5618.6 3729.5 4054.0 4089.4 4124.1 4132.4 5062.8 5066.1 5973.3 6028.5 6030.5 4432.2 4519.5 4599.7 4941.6 5040.2 4483.5 4557.9 4823.0 4857.5 5017.7 4000.5 5100.7 5262.8 5274.3 5291.0 5446.5 5505.4 5574.2 5598.4 5686.9 1509.0 1554.4 1593.4 1607.7 1618.0 1087.8 1206.5 1211.8 1214.7 1219.7 4593.7 4604.8 4656.8 4976.6 5122.8 5256.0 5554.4 5849.7 5879.3 5901.4 4577.5 4590.8 4686.6 4710.6 4906.8 2753.3 2805.5 2923.8 3017.4 3053.9 6035.7 6067.0 6071.3 6083.0 6092.4 5117.9 5216.3 5588.7 5635.8 6024.8 5120.8 5858.9 5908.0 6034.2 6088.7 5147.8 5585.6 5627.0 5735.6 5874.4 4999.6 5212.7 5700.1 5716.5 5827.9 5584.4 5817.5 6015.7 6080.5 6110.0 4093.0 4371.0 4403.1 4546.7 4560.6 5209.9 5255.6 5668.8 5786.7 5815.0 4882.2 5102.8 5437.7 5626.9 5631.6 4353.6 5252.6 5324.3 5628.6 5676.5 4605.4 5421.8 5452.7 5479.3 5521.7 5082.6 5206.4 5652.5 5689.6 5711.8 5260.5 5276.8 5286.4 5368.4 5444.3 02:45:08 INFO - PROCESS | 1643 | 02:45:08 INFO - PROCESS | 1643 | [#5] yui.html Cycles:180 Average:20803.65 Median:16916.12 stddev:30223.79 (178.7%) stddev-sans-first:30270.51 02:45:08 INFO - PROCESS | 1643 | Values: 603.4 743.9 768.0 794.3 845.3 15886.4 16888.7 16899.6 16932.7 16963.9 14892.6 15489.4 15739.7 16027.9 17223.6 1000.7 1022.1 1025.0 1025.6 1030.0 971.2 1024.8 1025.8 1026.1 1026.4 1075.1 1091.7 1093.9 1094.8 1096.2 1058.7 1085.8 1088.5 1089.0 1091.6 820.0 820.5 821.6 824.5 826.0 16832.9 17174.3 17247.5 17360.5 17576.3 14222.9 14267.4 14268.5 14487.9 14606.9 11693.8 11839.8 11907.0 11916.0 12428.6 3590.2 3764.2 3815.4 3872.8 3896.3 115687.4 118877.4 119461.4 120679.0 121361.0 10575.5 10698.5 10866.3 11428.7 11442.6 22610.3 22857.3 22871.7 22883.0 22974.0 7332.7 7848.7 7860.7 7948.9 8191.6 3868.9 4351.3 4437.9 4540.9 4603.4 3451.4 3522.6 3532.7 3544.1 3544.9 556.6 624.4 638.6 655.1 664.7 666.0 671.3 693.7 707.8 737.3 10213.9 10416.8 10553.4 10795.0 10804.2 662.3 671.5 684.7 714.7 718.9 5042.9 5104.8 5123.6 5126.7 5130.2 105197.0 105276.4 106396.0 106866.5 108643.9 77830.0 90441.5 93570.0 94482.0 96662.1 66987.7 67582.0 70621.0 72988.2 74260.6 10582.8 11148.2 11184.3 11225.3 11340.3 39639.2 41419.6 48906.5 51893.7 52229.7 58618.0 59571.7 63275.0 65811.7 66104.0 12468.6 12625.0 12804.9 13017.1 13298.9 14394.9 14465.9 14501.9 14798.2 15258.4 12377.5 13202.0 13505.5 13760.7 14215.9 13156.5 13351.7 13874.7 14384.0 14463.4 13043.9 13434.6 13636.0 13871.2 13886.7 9001.0 9179.6 9190.4 9281.7 9384.8 9677.9 11322.8 11390.0 11395.5 11422.6 02:45:08 INFO - PROCESS | 1643 | -------- Summary: end -------- 02:45:08 INFO - PROCESS | 1643 | 02:45:08 INFO - TEST-INFO | 1643: exit 0 02:45:08 INFO - TEST-OK | dromaeo_css | took 1115772ms 02:45:08 INFO - TEST-START | kraken 02:45:08 INFO - operating with platform_type : mac_ 02:45:08 INFO - Initialising browser for kraken test... 02:45:08 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/tmp2iu_pu/profile http://localhost:49226/getInfo.html) 02:45:11 INFO - PROCESS | 1656 | 2017-06-02 02:45:11.711 plugin-container[1658:10058] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x853f, name = 'com.apple.tsm.portname' 02:45:11 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:45:11 INFO - PROCESS | 1656 | 2017-06-02 02:45:11.711 plugin-container[1658:10058] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0f, name = 'com.apple.CFPasteboardClient' 02:45:11 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:45:11 INFO - PROCESS | 1656 | 2017-06-02 02:45:11.712 plugin-container[1658:10058] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:45:11 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 02:45:11 INFO - PROCESS | 1656 | colorDepth:24 02:45:11 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 02:45:11 INFO - PROCESS | 1656 | __metrics 02:45:11 ERROR - PROCESS | 1656 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3381: TypeError: this.tabs is undefined 02:45:12 INFO - TEST-INFO | 1656: exit 0 02:45:12 INFO - Browser initialized. 02:45:12 INFO - Running cycle 1/1 for kraken test... 02:45:12 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/tmp2iu_pu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:45:14 INFO - PROCESS | 1659 | 2017-06-02 02:45:14.782 plugin-container[1660:10182] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4443, name = 'com.apple.tsm.portname' 02:45:14 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:45:14 INFO - PROCESS | 1659 | 2017-06-02 02:45:14.783 plugin-container[1660:10182] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x853f, name = 'com.apple.CFPasteboardClient' 02:45:14 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:45:14 INFO - PROCESS | 1659 | 2017-06-02 02:45:14.783 plugin-container[1660:10182] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:45:24 INFO - PROCESS | 1659 | RSS: Main: 203124736 02:45:24 INFO - PROCESS | 1659 | 02:45:27 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection) 02:45:28 INFO - PROCESS | 1659 | RSS: Main: 216571904 02:45:28 INFO - PROCESS | 1659 | 02:45:31 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-dft) 02:45:31 INFO - PROCESS | 1659 | RSS: Main: 224514048 02:45:31 INFO - PROCESS | 1659 | 02:45:35 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-fft) 02:45:35 INFO - PROCESS | 1659 | RSS: Main: 228102144 02:45:35 INFO - PROCESS | 1659 | 02:45:37 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator) 02:45:38 INFO - PROCESS | 1659 | RSS: Main: 229429248 02:45:38 INFO - PROCESS | 1659 | 02:45:40 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:45:40 INFO - PROCESS | 1659 | RSS: Main: 229793792 02:45:40 INFO - PROCESS | 1659 | 02:45:43 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom) 02:45:44 INFO - PROCESS | 1659 | RSS: Main: 233701376 02:45:44 INFO - PROCESS | 1659 | 02:45:47 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate) 02:45:48 INFO - PROCESS | 1659 | RSS: Main: 233476096 02:45:48 INFO - PROCESS | 1659 | 02:45:51 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial) 02:45:51 INFO - PROCESS | 1659 | RSS: Main: 233971712 02:45:51 INFO - PROCESS | 1659 | 02:45:53 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:45:54 INFO - PROCESS | 1659 | RSS: Main: 232919040 02:45:54 INFO - PROCESS | 1659 | 02:45:56 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:45:56 INFO - PROCESS | 1659 | RSS: Main: 232620032 02:45:56 INFO - PROCESS | 1659 | 02:45:59 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:45:59 INFO - PROCESS | 1659 | RSS: Main: 232718336 02:45:59 INFO - PROCESS | 1659 | 02:46:02 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:46:03 INFO - PROCESS | 1659 | RSS: Main: 233357312 02:46:03 INFO - PROCESS | 1659 | 02:46:06 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:46:06 INFO - PROCESS | 1659 | RSS: Main: 233402368 02:46:06 INFO - PROCESS | 1659 | 02:46:08 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49226/tests/kraken/driver.html?testName=ai-astar) 02:46:08 INFO - PROCESS | 1659 | RSS: Main: 235053056 02:46:08 INFO - PROCESS | 1659 | 02:46:08 INFO - PROCESS | 1659 | __start_tp_report 02:46:08 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 02:46:08 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 02:46:08 INFO - PROCESS | 1659 | |i|pagename|runs| 02:46:08 INFO - PROCESS | 1659 | |0;ai-astar;156;177;140;119;120;119;128;120;121;119 02:46:08 INFO - PROCESS | 1659 | |1;audio-beat-detection;137;109;105;145;119;109;101;115;153;140 02:46:08 INFO - PROCESS | 1659 | |2;audio-dft;198;197;196;232;193;194;201;200;199;195 02:46:08 INFO - PROCESS | 1659 | |3;audio-fft;84;58;62;88;61;58;58;61;74;80 02:46:08 INFO - PROCESS | 1659 | |4;audio-oscillator;98;57;69;59;73;97;114;102;99;82 02:46:08 INFO - PROCESS | 1659 | |5;imaging-gaussian-blur;89;90;90;80;135;113;89;88;89;89 02:46:08 INFO - PROCESS | 1659 | |6;imaging-darkroom;98;96;96;96;97;120;96;95;96;95 02:46:08 INFO - PROCESS | 1659 | |7;imaging-desaturate;145;93;92;90;91;91;94;131;92;114 02:46:08 INFO - PROCESS | 1659 | |8;json-parse-financial;65;63;63;65;81;102;104;109;110;107 02:46:08 INFO - PROCESS | 1659 | |9;json-stringify-tinderbox;55;65;61;64;63;57;62;85;84;86 02:46:08 INFO - PROCESS | 1659 | |10;stanford-crypto-aes;83;79;93;95;94;102;109;83;81;85 02:46:08 INFO - PROCESS | 1659 | |11;stanford-crypto-ccm;150;152;147;137;136;139;143;141;189;138 02:46:08 INFO - PROCESS | 1659 | |12;stanford-crypto-pbkdf2;163;193;222;167;165;166;163;165;165;166 02:46:08 INFO - PROCESS | 1659 | |13;stanford-crypto-sha256-iterative;67;64;66;66;67;62;64;64;65;65 02:46:08 INFO - PROCESS | 1659 | __end_tp_report 02:46:08 INFO - PROCESS | 1659 | __start_cc_report 02:46:08 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,997 02:46:08 INFO - PROCESS | 1659 | __end_cc_report 02:46:08 INFO - PROCESS | 1659 | __startTimestamp1496396768747__endTimestamp 02:46:08 INFO - PROCESS | 1659 | 02:46:08 INFO - PROCESS | 1659 | ------- Summary: start ------- 02:46:08 INFO - PROCESS | 1659 | Number of tests: 14 02:46:08 INFO - PROCESS | 1659 | 02:46:08 INFO - PROCESS | 1659 | [#0] ai-astar Cycles:10 Average:131.90 Median:124.50 stddev:19.99 (16.1%) stddev-sans-first:19.21 02:46:08 INFO - PROCESS | 1659 | Values: 156.0 177.0 140.0 119.0 120.0 119.0 128.0 120.0 121.0 119.0 02:46:08 INFO - PROCESS | 1659 | 02:46:08 INFO - PROCESS | 1659 | [#1] audio-beat-detection Cycles:10 Average:123.30 Median:128.00 stddev:18.70 (14.6%) stddev-sans-first:19.17 02:46:08 INFO - PROCESS | 1659 | Values: 137.0 109.0 105.0 145.0 119.0 109.0 101.0 115.0 153.0 140.0 02:46:08 INFO - PROCESS | 1659 | 02:46:08 INFO - PROCESS | 1659 | [#2] audio-dft Cycles:10 Average:200.50 Median:198.50 stddev:11.37 (5.7%) stddev-sans-first:12.02 02:46:08 INFO - PROCESS | 1659 | Values: 198.0 197.0 196.0 232.0 193.0 194.0 201.0 200.0 199.0 195.0 02:46:08 INFO - PROCESS | 1659 | 02:46:08 INFO - PROCESS | 1659 | [#3] audio-fft Cycles:10 Average:68.40 Median:68.00 stddev:11.87 (17.5%) stddev-sans-first:11.17 02:46:08 INFO - PROCESS | 1659 | Values: 84.0 58.0 62.0 88.0 61.0 58.0 58.0 61.0 74.0 80.0 02:46:08 INFO - PROCESS | 1659 | 02:46:08 INFO - PROCESS | 1659 | [#4] audio-oscillator Cycles:10 Average:85.00 Median:77.50 stddev:19.74 (25.5%) stddev-sans-first:20.37 02:46:08 INFO - PROCESS | 1659 | Values: 98.0 57.0 69.0 59.0 73.0 97.0 114.0 102.0 99.0 82.0 02:46:08 INFO - PROCESS | 1659 | 02:46:08 INFO - PROCESS | 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:95.20 Median:89.00 stddev:16.30 (18.3%) stddev-sans-first:17.14 02:46:08 INFO - PROCESS | 1659 | Values: 89.0 90.0 90.0 80.0 135.0 113.0 89.0 88.0 89.0 89.0 02:46:08 INFO - PROCESS | 1659 | 02:46:08 INFO - PROCESS | 1659 | [#6] imaging-darkroom Cycles:10 Average:98.50 Median:96.00 stddev:7.60 (7.9%) stddev-sans-first:8.06 02:46:08 INFO - PROCESS | 1659 | Values: 98.0 96.0 96.0 96.0 97.0 120.0 96.0 95.0 96.0 95.0 02:46:08 INFO - PROCESS | 1659 | 02:46:08 INFO - PROCESS | 1659 | [#7] imaging-desaturate Cycles:10 Average:103.30 Median:91.50 stddev:19.86 (21.7%) stddev-sans-first:14.21 02:46:08 INFO - PROCESS | 1659 | Values: 145.0 93.0 92.0 90.0 91.0 91.0 94.0 131.0 92.0 114.0 02:46:08 INFO - PROCESS | 1659 | 02:46:08 INFO - PROCESS | 1659 | [#8] json-parse-financial Cycles:10 Average:86.90 Median:63.00 stddev:21.30 (33.8%) stddev-sans-first:21.07 02:46:08 INFO - PROCESS | 1659 | Values: 65.0 63.0 63.0 65.0 81.0 102.0 104.0 109.0 110.0 107.0 02:46:08 INFO - PROCESS | 1659 | 02:46:08 INFO - PROCESS | 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:68.20 Median:64.50 stddev:11.99 (18.6%) stddev-sans-first:11.73 02:46:08 INFO - PROCESS | 1659 | Values: 55.0 65.0 61.0 64.0 63.0 57.0 62.0 85.0 84.0 86.0 02:46:08 INFO - PROCESS | 1659 | 02:46:08 INFO - PROCESS | 1659 | [#10] stanford-crypto-aes Cycles:10 Average:90.40 Median:84.00 stddev:9.88 (11.8%) stddev-sans-first:10.11 02:46:08 INFO - PROCESS | 1659 | Values: 83.0 79.0 93.0 95.0 94.0 102.0 109.0 83.0 81.0 85.0 02:46:08 INFO - PROCESS | 1659 | 02:46:08 INFO - PROCESS | 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:147.20 Median:145.00 stddev:15.69 (10.8%) stddev-sans-first:16.61 02:46:08 INFO - PROCESS | 1659 | Values: 150.0 152.0 147.0 137.0 136.0 139.0 143.0 141.0 189.0 138.0 02:46:08 INFO - PROCESS | 1659 | 02:46:08 INFO - PROCESS | 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.50 Median:166.00 stddev:19.22 (11.6%) stddev-sans-first:20.01 02:46:08 INFO - PROCESS | 1659 | Values: 163.0 193.0 222.0 167.0 165.0 166.0 163.0 165.0 165.0 166.0 02:46:08 INFO - PROCESS | 1659 | 02:46:08 INFO - PROCESS | 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.00 Median:65.50 stddev:1.56 (2.4%) stddev-sans-first:1.48 02:46:08 INFO - PROCESS | 1659 | Values: 67.0 64.0 66.0 66.0 67.0 62.0 64.0 64.0 65.0 65.0 02:46:08 INFO - PROCESS | 1659 | -------- Summary: end -------- 02:46:08 INFO - PROCESS | 1659 | 02:46:09 INFO - TEST-INFO | 1659: exit 0 02:46:09 INFO - TEST-OK | kraken | took 60727ms 02:46:09 INFO - SUITE-END | took 1176s 02:46:09 INFO - Completed test suite (00:19:36) 02:46:09 INFO - javascript benchmark 02:46:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7750.518708492962, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3487.773294735842, 3487.825608719565, 3509.192885636304, 3520.4895290182917, 3537.3632746105723, 5958.821235362935, 6032.0951857221435, 6037.879242415149, 6041.24484439445, 6073.028315469526, 5971.910421343683, 5994.910076348859, 6023.668698221596, 6036.426539478743, 6083.513318934486, 5414.756335964881, 5456.208849716789, 5473.2063850741615, 5473.589480788937, 5529.861753456155, 4854.737768180281, 5144.717040562768, 5520.91718624221, 5523.917141242885, 5555.0, 5302.09864323066, 5323.547498462635, 5507.284053073095, 5526.944730552683, 5527.364353099405, 4459.821607135711, 5422.864428389283, 5427.0, 5441.646292990943, 5444.074507333763, 5349.197620356938, 5377.865553361158, 5405.648632838853, 5417.052015897241, 5495.890082198333, 6102.298235702907, 6113.052476866093, 6126.846828829271, 6133.233345831771, 6138.447539721446, 6223.097650840648, 6303.621782693053, 6373.585716928431, 6390.616563006234, 6411.839704007437, 6333.651649159295, 6334.0, 6349.746010159588, 6360.586561873463, 6379.649119298437, 5079.8984020319385, 5125.282460455539, 5526.005319042571, 5719.28508936383, 6068.787592434243, 6483.773067942694, 6510.18622672166, 6533.24867640218, 6536.901946470807, 6540.247871494839, 5259.947400526023, 6134.957077260912, 6269.279032911182, 6274.780382686584, 6279.183706118175, 6457.3865482779065, 6458.0, 6463.353664633499, 6463.353664633499, 6463.838404039937, 6364.109024736541, 6369.9681501592195, 6382.138411314506, 6382.20222472191, 6395.840103997437, 5846.7076646167525, 6255.843603909848, 6280.05799130125, 6309.463695585835, 6326.145970294044, 4558.179527685049, 4931.0, 4938.530839570235, 4946.703197808143, 4976.129177393942, 4441.933370999438, 4448.911021779546, 4454.198244316054, 4465.531119232499, 4489.9775501122285, 4984.5513903748115, 5684.6589204647025, 5689.317281926113, 5692.57305702074, 5894.440028197314, 6328.145700330397, 6372.649504277217, 6374.235091789015, 6391.872162556723, 6396.968015159894, 5718.599698021182, 5722.685252311163, 5746.396628354046, 5755.482006619424, 5771.422857714195, 5397.136458166675, 5418.431064738224, 5471.015217260931, 5544.140658197985, 5571.220029195916, 6646.136002319861, 6649.534532582673, 6653.500987425962, 6656.301088385747, 6693.564918280296, 6419.133416988647, 6442.710078046405, 6451.774187903401, 6458.644774537258, 6489.026646003137, 6280.685965701788, 6387.329330420239, 6552.57408268461, 6581.0, 6594.934050659432, 6324.430801227912, 6325.146105275729, 6327.746890124344, 6375.6812159392775, 6375.936240637534, 5257.447967963309, 6399.072134540559, 6404.71178796946, 6413.038044293206, 6414.935850641434, 5401.432849550898, 6146.877062458815, 6254.365483246566, 6284.24589049317, 6343.238811342669, 6253.656048917172, 6258.499320054294, 6260.40526150015, 6293.37066293367, 6312.968435157978, 6206.06908963659, 6278.0896769967185, 6293.458102764852, 6298.149749783904, 6299.181106456132, 6311.0217916223055, 6338.968305158629, 6343.080253363144, 6375.968120159369, 6384.6169229846355, 6042.0936859469675, 6087.8782424350375, 6115.449609535163, 6139.2325959255095, 6141.986572215535], "value": 5837.438043336947, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.8459418850182, 817.860963636182, 840.0465471689632, 978.4276198423926, 980.9509524523781, 39941.80029099854, 41006.79496602517, 43090.569094309045, 43223.3516497252, 43330.56669433305, 33384.49923251147, 40400.78797636068, 40545.59454405455, 40627.57803476879, 40656.98357541063, 8181.345492360611, 8310.459820111673, 10966.013058824705, 11216.66350009501, 11463.312201267909, 10971.177161712863, 11077.778444431107, 11087.33475991443, 11097.278676886017, 11101.333919964827, 11118.0, 11139.721506962309, 11155.27490713101, 11170.882911708804, 11177.161712871526, 10211.948940255288, 10446.947765261162, 11064.114870810316, 11099.611513596985, 11101.111911047097, 9342.57958391874, 10817.891821081847, 10819.350838949687, 10853.34879907208, 10876.18428617849, 34173.487397688805, 34362.82818585916, 34402.31195376103, 34437.794677186175, 34470.482942755625, 3878.9030274243087, 3896.7467114637457, 3898.1813819098024, 3919.176972835708, 3953.940690889639, 25666.358341041436, 26766.0, 26787.598186027226, 26794.928202871863, 26873.596896046576, 3044.3911217756536, 3109.455845227076, 3131.3424180922034, 3196.568463257454, 3318.1372843060735, 362478.1876090629, 363406.0, 363492.54761178605, 365080.0, 366298.16850915574, 37417.43873841895, 40258.99999999941, 42169.15661686749, 44705.87058905845, 44750.32874506885, 45895.0, 45932.08135837331, 46216.844578885124, 46268.768656157175, 46288.76855615768, 13273.734525309439, 13312.800307995389, 13346.532871349456, 13406.798898016537, 13432.0, 11428.94285528567, 11439.485223164978, 11451.141164412702, 11472.196946213686, 11497.597584084684, 10330.845037324447, 11618.0, 11740.58907938218, 11858.347790871412, 11860.525578976743, 4551.8179272829375, 4742.288656701522, 5489.780408783684, 5568.164775283659, 5592.217089607459, 1096.6620722718271, 1109.944502774858, 1152.9308241505537, 1205.6684411786828, 1212.2302338015425, 20653.17387304491, 26883.865580671973, 27225.72774272232, 27319.224250424057, 27343.45313093727, 940.3229674634252, 1161.9186656933935, 1190.2323001663879, 1242.8073648584484, 1297.863724308953, 15263.931524793157, 16077.305301006407, 16424.6715065698, 16434.342626294816, 16585.50243492697, 478085.0, 486438.0, 486874.0, 487608.0, 488454.0, 60483.09275360873, 60551.69724151439, 60647.0, 60846.47883802852, 61014.0, 1258.4903114238748, 1284.4091717809724, 1458.9343479543445, 1484.7253258147493, 1498.655309278872, 25814.483710326065, 26129.346766330993, 30078.0, 30656.693433066277, 31521.21196970094, 45562.31656525088, 46000.079998399175, 46467.53532464767, 49020.019599608524, 49065.754671226416, 525581.0, 528629.0, 530025.0, 531095.0, 533262.0, 32163.839180803945, 32414.51378229281, 32434.351312973136, 32452.0, 32585.511217331285, 38910.80544597259, 38980.63567775114, 39237.607623923395, 39726.60273397229, 40151.99620009523, 27216.72783272142, 27613.171604851887, 27918.3020424491, 27985.0, 28748.0, 27567.89728410841, 27826.0, 28447.862085516346, 28496.57255141133, 28592.714072859006, 27714.722852771214, 30454.695453045188, 32161.0, 32758.853440129482, 33463.0, 29074.854625726737, 29150.56274155932, 29403.41193176167, 29463.705362946097, 29872.954446594264, 19618.076384722826, 22694.886525567268, 23279.53440931206, 23720.288391348287, 23838.404039899142], "value": 18605.718994870815, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.35339160298105, 412.6410023279747, 415.4764996104907, 429.67774169372973, 455.8330673475902, 18003.189856456458, 20459.465540084515, 22087.779122208773, 22165.335039948786, 22267.331980040584, 21813.01841417136, 22281.108755649755, 22506.987185576647, 22628.43428914278, 22647.20734774283, 7023.122109736283, 7215.639218039103, 7222.602756848371, 7296.270372962715, 7298.744543940963, 6868.484863635223, 7553.924460755405, 7906.327962123227, 7962.124166341698, 7985.800354991114, 7828.491148075357, 7907.762767116967, 7917.485363451386, 8018.879716804253, 8022.638981245858, 7804.921950780476, 7976.0, 8041.1154772975215, 8090.190980901922, 8133.837323253531, 7592.658330375147, 7757.7672669819995, 7798.0, 7810.0, 7817.609119544, 23446.765532344798, 24362.147324843547, 26451.73548264512, 27200.31999200016, 27332.863335683393, 2872.113332039591, 3075.984620076885, 3076.4769989101906, 3088.4286407014774, 3165.7309128724087, 16007.27967241477, 16466.341346346133, 16588.15895706096, 16737.330506779836, 17406.47780566585, 2052.9735132433784, 2159.9352019439443, 2173.185390199186, 2193.396815875647, 2210.712607361049, 129649.35175324157, 165342.0, 172321.99999999875, 173906.0, 176804.0, 16896.40862569829, 20821.37535873926, 21243.78756212418, 21653.566928661337, 21989.67015494769, 31827.840860795546, 32228.67771322257, 32410.0, 32454.70181192773, 32501.51247731286, 9099.58956361765, 9604.66383676568, 10353.809311929073, 10413.5834566618, 10462.581496740197, 8532.744017679479, 8539.829203415897, 8558.01582817984, 8713.564321784012, 8768.693095741619, 8111.9594402027615, 9685.70942871715, 9784.804303914025, 9785.902140978642, 9961.252906032076, 4049.6760259179196, 4134.937975930364, 4157.127003329335, 4159.086820062745, 4163.250614889288, 1011.8482227665762, 1035.7928414317166, 1045.7333379988054, 1082.4641802307908, 1089.536946797608, 15764.68470630581, 18276.268949241883, 19855.702164467544, 20936.162553497994, 21044.0, 928.2805825485303, 1021.4331046269392, 1026.312370711625, 1061.2359101446946, 1065.6749691344214, 8081.555514446762, 10860.348379097122, 10986.0, 10993.560257589606, 11051.060659843843, 764433.0, 780933.095334531, 786980.0, 788017.0599146967, 819287.0, 145045.27477362758, 148619.25690371695, 149528.0, 151525.0, 152556.0, 1171.56297150972, 1184.413715210976, 1212.5452955141823, 1217.15407791584, 1222.7554489102038, 17331.306747730454, 18263.178156983235, 18271.451856444328, 18291.54271143232, 18382.540436489195, 55437.891242175734, 58811.52971175755, 58989.820203596544, 59789.0, 60027.0, 71339.64330178316, 72844.27155728375, 76544.2345576537, 81780.0, 83845.0, 11460.0, 11554.653360399201, 11626.941865290619, 11964.461599227881, 11978.880211198126, 13041.152325098838, 13236.404361803548, 13430.865691342962, 13587.320633968064, 13986.0, 10061.1889859823, 11417.942910285396, 11577.942110289394, 11994.580189693319, 12072.45673944657, 10447.84328235062, 11053.778924421627, 11140.721481963015, 11157.107431405306, 11187.944060279646, 10586.206034547286, 10677.359358438518, 11612.361320127475, 11939.283642981449, 12106.576269830513, 11765.294082355085, 12012.579559715368, 12038.638840834788, 12353.3823308836, 12374.81437778416, 11668.358240296866, 11718.062554995751, 11845.23006004607, 11959.820602691141, 12068.577599783965], "value": 11848.680187507696, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1567.3338830996825, 1644.8601868841147, 1652.9173541322923, 1655.8013038435386, 1657.7430498272768, 10330.845037324447, 10839.024487796098, 10858.077063449606, 11376.203665743402, 11642.534298628067, 11351.659450216486, 11517.193796434252, 11527.481263343148, 11534.711632209199, 11557.248778829371, 2210.347947355534, 2249.1003598560624, 2251.0995601759346, 2251.4596496840722, 2257.0, 2245.1805091141778, 2314.1553333033407, 2330.8135349172026, 2348.635961425982, 2363.267387109993, 2275.9089636414606, 2277.9886100569474, 2278.553118769585, 2289.0271634555247, 2323.326235391743, 2232.732072151336, 2286.4626812699003, 2291.6218823894205, 2297.9080836766593, 2298.287530865429, 2266.150193677371, 2277.202978957368, 2280.2133264023887, 2282.3038973113194, 2286.9199578014855, 11374.090072794157, 11406.828897566544, 11410.201285909907, 11433.14251431146, 11434.428278586121, 5313.362396512443, 5451.2640793492765, 5468.6171967962255, 5480.890382192374, 5493.725313734337, 3157.7947433416753, 3215.5498230247786, 3329.20099176196, 3393.8630558762843, 3395.6264810870784, 1127.9041281491084, 1146.4955419615426, 1180.6044974933407, 1183.1540448579221, 1192.5527927027365, 13816.723665526633, 14146.646333841634, 15141.621459463491, 15666.0, 15713.371465141381, 4203.390508376268, 4265.914681706348, 4366.257736184856, 4380.408644832971, 4402.515723270438, 10707.411092389837, 11540.538378464935, 11951.22317049389, 11961.521539138512, 11978.0, 5412.107002344569, 5428.83713488596, 5434.157705555645, 5435.211894275308, 5463.0, 4944.60443164546, 4979.103761322925, 5005.1241032819835, 5010.69935803853, 5035.79856805731, 3037.830435282388, 3642.2715456908722, 3676.319880822003, 3706.1105334720014, 3724.776513409204, 840.5335039053261, 889.2574700125467, 903.2187158108189, 916.834969705446, 916.9449833010041, 847.169773621854, 909.3043821476575, 919.3794188922451, 995.8157740817971, 1052.0478966535297, 6645.1361323027695, 6735.663216839139, 6852.314768523208, 6979.790606281819, 7070.823229419306, 1047.9266451348485, 1066.1630619963291, 1071.8124328242682, 1092.7268182954263, 1093.1637297467444, 4191.392248124005, 4204.453421055243, 4211.0, 4227.281362168439, 4340.60934515895, 13745.518906838213, 14325.78511322331, 14360.138391696531, 14384.85615143856, 14402.135871847722, 13205.603831885059, 14174.716505669829, 14253.358598863077, 14258.073225240534, 14289.285535723173, 8887.244584210286, 10263.12763415118, 10468.52891619879, 10557.0, 10568.313059651098, 5066.36670416198, 5886.970565147147, 6798.388145066968, 6957.860842783217, 7111.82220444493, 11956.0, 11976.940115299716, 12020.33888136161, 12075.094367922617, 12345.876541234473, 12390.938045309716, 12680.556180533637, 12714.682132946751, 12715.982721382083, 13004.414801334153, 9380.95309523448, 9477.24182065447, 9492.715218543455, 9546.474943878153, 9571.569279382584, 10394.792104157723, 10583.62957296491, 10588.10001149911, 10604.8939510607, 10612.946935265583, 8908.376413651242, 8996.370254082152, 9041.276697864287, 9085.13691199335, 9143.63425462974, 8824.470531767858, 8847.380683352361, 8883.845100136941, 8901.421407608745, 8935.151160639729, 6823.454123669995, 8077.878831817645, 8141.185881411811, 8178.83642327162, 8215.096339402542, 5981.282246130492, 6044.546659000681, 6059.72731227087, 6118.785842495491, 6128.434316434285, 5430.945690543044, 6042.607230530001, 6079.270487541523, 6159.4456498915315, 6221.906671399842], "value": 5085.018311956959, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [330.8891521340351, 382.37290843017456, 387.1231660289443, 393.2861855731848, 396.5063495947545, 5004.349434573501, 5022.020705962339, 5190.688558686476, 5386.8383948481605, 5395.602583894129, 4721.575058244757, 4762.952150526888, 4820.39745031871, 4842.709437433757, 5250.737463126838, 3397.5073614325906, 3529.6293889141593, 3780.962190378102, 4107.938380924288, 4173.059527319796, 3749.793761343125, 3957.7229593928437, 4172.08214192877, 4183.288840897055, 4211.452511173543, 3568.92862142757, 3765.8870233893026, 4122.917541649165, 4146.232946904832, 4169.854055108071, 3486.9887732557527, 3891.5135608048995, 4047.1500984793083, 4118.423420721101, 4171.249175148472, 3393.6606339366112, 3477.426224672926, 3636.3999940009867, 3650.324689932371, 3689.5756987946193, 4825.252085926687, 5128.2564028216075, 5155.53600175986, 5166.095933211673, 5169.870753231162, 4023.97988010061, 4182.330827067685, 4646.210144275447, 4804.1352556539805, 4810.687305325143, 4744.0, 5560.972195139039, 5586.217929489874, 5609.382967873531, 5618.606697531174, 3729.533808273966, 4054.013533368628, 4089.3661482470266, 4124.1133156371225, 4132.40080188374, 5062.797488100508, 5066.144859927076, 5973.296556135224, 6028.517718582503, 6030.517558595302, 4432.224360736858, 4519.502854685982, 4599.678022538457, 4941.604671626261, 5040.160341475335, 4483.461984561874, 4557.931631025537, 4822.95177048232, 4857.514248575114, 5017.698938063655, 4000.5399379071196, 5100.724818795301, 5262.815801446931, 5274.314339135888, 5290.9682611891085, 5446.537044351196, 5505.421930697219, 5574.188388773665, 5598.384177740445, 5686.886262274731, 1509.0492989416598, 1554.3627112883664, 1593.39451008616, 1607.6977648105071, 1618.0, 1087.7606926476162, 1206.4872429217723, 1211.7576484703095, 1214.7184720120358, 1219.7499512599895, 4593.655475839326, 4604.7927843247135, 4656.837010704609, 4976.576990955737, 5122.797197205163, 5256.0, 5554.361248456398, 5849.678267695318, 5879.265091863517, 5901.3508514063415, 4577.496475387705, 4590.770461476913, 4686.601638860667, 4710.642655543608, 4906.754662266872, 2753.251685179925, 2805.495010898058, 2923.7633210593913, 3017.351269477073, 3053.9389212216074, 6035.66803825785, 6067.0, 6071.2714474263375, 6082.965895797725, 6092.421219984094, 5117.923231151536, 5216.347956505361, 5588.720563971785, 5635.830925072254, 6024.819255422344, 5120.799500312305, 5858.853528661817, 5907.9704601476715, 6034.2373033752665, 6088.726007329769, 5147.794088236428, 5585.636933599303, 5627.0434026214825, 5735.598508104393, 5874.441928016831, 4999.55013046227, 5212.661177023481, 5700.116481945305, 5716.456936591017, 5827.883442331215, 5584.4136365681015, 5817.534597232296, 6015.699215039318, 6080.452759251689, 6109.991851344478, 4092.9944327280014, 4370.950971766817, 4403.075354175659, 4546.726636681659, 4560.566746159109, 5209.9001094348805, 5255.634964266019, 5668.773249069991, 5786.652800831964, 5815.0, 4882.241533533542, 5102.800841802099, 5437.700926449083, 5626.861792518816, 5631.63394379364, 4353.629941454885, 5252.606054546, 5324.254604355471, 5628.634138780966, 5676.545876329967, 4605.447346318463, 5421.83734487966, 5452.700101494456, 5479.315085614298, 5521.668699878021, 5082.644214904921, 5206.427292997691, 5652.491275785199, 5689.601727879174, 5711.8000869969355, 5260.473952604709, 5276.81531146408, 5286.36563612369, 5368.436314186954, 5444.277861069465], "value": 4236.343304562466, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [603.3604379357884, 743.8512297540493, 767.973120940767, 794.3446656508379, 845.2688424512796, 15886.443974460883, 16888.733344999127, 16899.577510562212, 16932.661346773057, 16963.915180424097, 14892.627684307898, 15489.380424783023, 15739.685206295868, 16027.919860400682, 17223.569410764707, 1000.7440661969237, 1022.1107636356343, 1024.9641262555813, 1025.646152077532, 1029.9948500257487, 971.2230215827345, 1024.7540590258322, 1025.8153532364172, 1026.1021606094666, 1026.363654534186, 1075.1345167140448, 1091.6888686724285, 1093.907017903479, 1094.8412480190368, 1096.2271598523023, 1058.7194393485695, 1085.7502774361901, 1088.4666513408451, 1088.9618863339786, 1091.6233899304727, 820.0405525535139, 820.5240960243104, 821.6179476543437, 824.47233770387, 825.9876101858417, 16832.915835420867, 17174.313027478885, 17247.482575522754, 17360.479185624452, 17576.29694812206, 14222.857771422361, 14267.357968891425, 14268.500602478864, 14487.855121448756, 14606.853931460655, 11693.766124677459, 11839.763204735944, 11907.0, 11916.0, 12428.627141185689, 3590.2281009582803, 3764.153065560227, 3815.3895376739865, 3872.845086196577, 3896.279188350164, 115687.42156289164, 118877.40561297224, 119461.40269298598, 120679.0, 121361.0, 10575.524101415454, 10698.465076746132, 10866.293690910066, 11428.657140285639, 11442.599509017144, 22610.321690349316, 22857.31428057161, 22871.656925146137, 22883.0, 22974.0, 7332.670029848669, 7848.686052557948, 7860.685572577033, 7948.880766788503, 8191.631376588068, 3868.883933482, 4351.347297905276, 4437.866863994085, 4540.919402155283, 4603.378543896598, 3451.4132597458492, 3522.577290725078, 3532.699720523733, 3544.0962554548423, 3544.929101417957, 556.5714399912046, 624.4442446222865, 638.5944924972583, 655.1155939481662, 664.6510581944422, 666.0142988377229, 671.278375746075, 693.7329128285545, 707.8265824872836, 737.3290305821677, 10213.904275102213, 10416.791664166825, 10553.366797992145, 10795.0, 10804.243702940876, 662.2946561911644, 671.5299290496672, 684.658070182438, 714.7498375568509, 718.8741970155202, 5042.924356134661, 5104.795808167632, 5123.564497017721, 5126.666766660155, 5130.179171332644, 105197.0, 105276.42085368726, 106396.0, 106866.46566767116, 108643.91356086497, 77830.0, 90441.54779226062, 93570.0, 94482.0, 96662.06675866443, 66987.6602467948, 67582.0, 70621.0, 72988.17529561697, 74260.62869685725, 10582.8412573813, 11148.163887708291, 11184.273022253528, 11225.326480411202, 11340.319580825177, 39639.20721585494, 41419.58580414157, 48906.5109348902, 51893.7405312971, 52229.73885130702, 58618.0, 59571.70214148902, 63275.0, 65811.67094164499, 66104.0, 12468.563600273947, 12625.0, 12804.935975320064, 13017.088803783645, 13298.86701133015, 14394.856051439352, 14465.855341446872, 14501.854981450473, 14798.186099764616, 15258.389664413298, 12377.504899803907, 13202.0, 13505.527306544223, 13760.65598360049, 14215.949020308202, 13156.473741050251, 13351.732965340445, 13874.65313367174, 14384.0, 14463.421463141356, 13043.869561304266, 13434.596962091153, 13636.0, 13871.167729936236, 13886.722265554834, 9000.954995224982, 9179.586918588813, 9190.35667503295, 9281.67514137002, 9384.812303754023, 9677.9032209677, 11322.81056009282, 11390.031847293072, 11395.487203075716, 11422.600208992646], "value": 7818.44847664748, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1537.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 177.0, 140.0, 119.0, 120.0, 119.0, 128.0, 120.0, 121.0, 119.0], "value": 131.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 109.0, 105.0, 145.0, 119.0, 109.0, 101.0, 115.0, 153.0, 140.0], "value": 123.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 197.0, 196.0, 232.0, 193.0, 194.0, 201.0, 200.0, 199.0, 195.0], "value": 200.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 58.0, 62.0, 88.0, 61.0, 58.0, 58.0, 61.0, 74.0, 80.0], "value": 68.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 57.0, 69.0, 59.0, 73.0, 97.0, 114.0, 102.0, 99.0, 82.0], "value": 85.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 90.0, 80.0, 135.0, 113.0, 89.0, 88.0, 89.0, 89.0], "value": 95.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 96.0, 96.0, 96.0, 97.0, 120.0, 96.0, 95.0, 96.0, 95.0], "value": 98.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 93.0, 92.0, 90.0, 91.0, 91.0, 94.0, 131.0, 92.0, 114.0], "value": 103.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 63.0, 65.0, 81.0, 102.0, 104.0, 109.0, 110.0, 107.0], "value": 86.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 65.0, 61.0, 64.0, 63.0, 57.0, 62.0, 85.0, 84.0, 86.0], "value": 68.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 79.0, 93.0, 95.0, 94.0, 102.0, 109.0, 83.0, 81.0, 85.0], "value": 90.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 152.0, 147.0, 137.0, 136.0, 139.0, 143.0, 141.0, 189.0, 138.0], "value": 147.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 193.0, 222.0, 167.0, 165.0, 166.0, 163.0, 165.0, 165.0, 166.0], "value": 173.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 64.0, 66.0, 66.0, 67.0, 62.0, 64.0, 64.0, 65.0, 65.0], "value": 65.0, "unit": "score"}]}]} 02:46:09 INFO - Return code: 0 02:46:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:46:10 ERROR - # TBPL SUCCESS # 02:46:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:46:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:46:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:46:10 INFO - 2017-06-02 02:46:10.080 screenresolution[1664:10571] starting screenresolution argv=screenresolution get 02:46:10 INFO - 2017-06-02 02:46:10.083 screenresolution[1664:10571] Display 0: 1600x1200x32@60 02:46:10 INFO - 2017-06-02 02:46:10.089 screenresolution[1665:10574] starting screenresolution argv=screenresolution list 02:46:10 INFO - Available Modes on Display 0 02:46:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:46:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:46:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:46:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:46:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:46:10 INFO - Intel Iris: 02:46:10 INFO - Chipset Model: Intel Iris 02:46:10 INFO - Type: GPU 02:46:10 INFO - Bus: Built-In 02:46:10 INFO - VRAM (Dynamic, Max): 1536 MB 02:46:10 INFO - Vendor: Intel (0x8086) 02:46:10 INFO - Device ID: 0x0a2e 02:46:10 INFO - Revision ID: 0x0009 02:46:10 INFO - Displays: 02:46:10 INFO - Display: 02:46:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:46:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:46:10 INFO - Display Serial Number: 1600x1200 60 02:46:10 INFO - Main Display: Yes 02:46:10 INFO - Mirror: Off 02:46:10 INFO - Online: Yes 02:46:10 INFO - Rotation: Supported 02:46:10 INFO - Return code: 0 02:46:10 INFO - Running post-action listener: _resource_record_post_action 02:46:10 INFO - [mozharness: 2017-06-02 09:46:10.323105Z] Finished run-tests step (success) 02:46:10 INFO - Running post-run listener: _resource_record_post_run 02:46:10 INFO - Total resource usage - Wall time: 1197s; CPU: 55.0%; Read bytes: 40040448; Write bytes: 512601088; Read time: 252; Write time: 1655 02:46:10 INFO - TinderboxPrint: CPU usage
55.0% 02:46:10 INFO - TinderboxPrint: I/O read bytes / time
40,040,448 / 252 02:46:10 INFO - TinderboxPrint: I/O write bytes / time
512,601,088 / 1,655 02:46:10 INFO - TinderboxPrint: CPU idle
2,157.0 (45.0%) 02:46:10 INFO - TinderboxPrint: CPU system
971.6 (20.3%) 02:46:10 INFO - TinderboxPrint: CPU user
1,661.2 (34.7%) 02:46:10 INFO - TinderboxPrint: Swap in / out
249,036,800 / 0 02:46:10 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 209873408; Write bytes: 211867648; Read time: 15917; Write time: 460 02:46:10 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 35854336; Write bytes: 299377664; Read time: 226; Write time: 1191 02:46:10 INFO - Running post-run listener: _upload_blobber_files 02:46:10 INFO - Blob upload gear active. 02:46:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:46:10 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 02:46:10 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'] 02:46:10 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 02:46:11 INFO - (blobuploader) - INFO - Open directory for files ... 02:46:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:46:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:46:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:46:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:46:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:46:12 INFO - (blobuploader) - INFO - Done attempting. 02:46:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:46:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:46:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:46:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:46:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:46:13 INFO - (blobuploader) - INFO - Done attempting. 02:46:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:46:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:46:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:46:14 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:46:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:46:14 INFO - (blobuploader) - INFO - Done attempting. 02:46:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:46:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:46:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:46:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:46:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:46:16 INFO - (blobuploader) - INFO - Done attempting. 02:46:16 INFO - (blobuploader) - INFO - Iteration through files over. 02:46:16 INFO - Return code: 0 02:46:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:46:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:46:16 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/33a9bb427ce10715b77b5dc498aa9268124a07f64edfd8a9e95bbeaaf22cd1d9acf90c6c8a443693291389b252e161eb58cd0990b921c5cc7245c140a0795f32", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/575e0d64c132873ac4aef6827d5282ada475dd1c41fc11d6647d4355c0b08c287c116955329def3950966027b8dfe72f5c7f2769f18efb9b9860991bcacf8414", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4783f77a3d8ae55a8e247443223d665401cb528f0b13ddea000ff559987fc1a1a585bf60b0c468e7e25fdb4a3fa5d2afaf46ff673d2647a40bc84ea8e46ba762", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1565b28f743fd3c3b0657be7915d8e3cd9cf9f5bdb7e29fb4573af192409d2a5c6e96dca6f9072b7289ed0921f67b3e0c61a125f425a6ceb1c3573edd8974b7b"} 02:46:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:46:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:46:16 INFO - Contents: 02:46:16 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/33a9bb427ce10715b77b5dc498aa9268124a07f64edfd8a9e95bbeaaf22cd1d9acf90c6c8a443693291389b252e161eb58cd0990b921c5cc7245c140a0795f32", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/575e0d64c132873ac4aef6827d5282ada475dd1c41fc11d6647d4355c0b08c287c116955329def3950966027b8dfe72f5c7f2769f18efb9b9860991bcacf8414", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4783f77a3d8ae55a8e247443223d665401cb528f0b13ddea000ff559987fc1a1a585bf60b0c468e7e25fdb4a3fa5d2afaf46ff673d2647a40bc84ea8e46ba762", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1565b28f743fd3c3b0657be7915d8e3cd9cf9f5bdb7e29fb4573af192409d2a5c6e96dca6f9072b7289ed0921f67b3e0c61a125f425a6ceb1c3573edd8974b7b"} 02:46:16 INFO - Running post-run listener: copy_logs_to_upload_dir 02:46:16 INFO - Copying logs to upload dir... 02:46:16 INFO - mkdir: /builds/slave/test/build/upload/logs 02:46:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.935121 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-06-02 02:46:16.946657) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-02 02:46:16.950267) ========= 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.P7wUXkt8wZ/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.ktMLZb66l6/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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/33a9bb427ce10715b77b5dc498aa9268124a07f64edfd8a9e95bbeaaf22cd1d9acf90c6c8a443693291389b252e161eb58cd0990b921c5cc7245c140a0795f32", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/575e0d64c132873ac4aef6827d5282ada475dd1c41fc11d6647d4355c0b08c287c116955329def3950966027b8dfe72f5c7f2769f18efb9b9860991bcacf8414", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4783f77a3d8ae55a8e247443223d665401cb528f0b13ddea000ff559987fc1a1a585bf60b0c468e7e25fdb4a3fa5d2afaf46ff673d2647a40bc84ea8e46ba762", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1565b28f743fd3c3b0657be7915d8e3cd9cf9f5bdb7e29fb4573af192409d2a5c6e96dca6f9072b7289ed0921f67b3e0c61a125f425a6ceb1c3573edd8974b7b"} build_url:https://queue.taskcluster.net/v1/task/GmSTE1R7TYCSbLYmhzPe-Q/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011143 build_url: 'https://queue.taskcluster.net/v1/task/GmSTE1R7TYCSbLYmhzPe-Q/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/33a9bb427ce10715b77b5dc498aa9268124a07f64edfd8a9e95bbeaaf22cd1d9acf90c6c8a443693291389b252e161eb58cd0990b921c5cc7245c140a0795f32", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/575e0d64c132873ac4aef6827d5282ada475dd1c41fc11d6647d4355c0b08c287c116955329def3950966027b8dfe72f5c7f2769f18efb9b9860991bcacf8414", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4783f77a3d8ae55a8e247443223d665401cb528f0b13ddea000ff559987fc1a1a585bf60b0c468e7e25fdb4a3fa5d2afaf46ff673d2647a40bc84ea8e46ba762", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1565b28f743fd3c3b0657be7915d8e3cd9cf9f5bdb7e29fb4573af192409d2a5c6e96dca6f9072b7289ed0921f67b3e0c61a125f425a6ceb1c3573edd8974b7b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-02 02:46:16.984060) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 02:46:16.984386) ========= 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.P7wUXkt8wZ/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.ktMLZb66l6/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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.005160 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 02:46:17.032726) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-02 02:46:17.033051) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-02 02:46:17.033375) ========= ========= Total master_lag: 0.95 =========