builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0151 starttime: 1496195236.68 results: success (0) revision: 5b2c84104a7f4774fd120ab38d21b6483bcb63c7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-30 18:47:16.676393) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-30 18:47:16.676828) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-30 18:47:16.677127) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-30 18:47:16.700263) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 18:47:16.700575) ========= 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.fn1suntHu1/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.GODvfpuU6O/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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-05-30 18:47:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.13M=0.001s 2017-05-30 18:47:16 (8.13 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.096083 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 18:47:16.819456) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 18:47:16.819814) ========= 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.fn1suntHu1/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.GODvfpuU6O/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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.021880 ========= master_lag: 0.79 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 18:47:17.628120) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-30 18:47:17.628462) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5b2c84104a7f4774fd120ab38d21b6483bcb63c7 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 5b2c84104a7f4774fd120ab38d21b6483bcb63c7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fn1suntHu1/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.GODvfpuU6O/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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-05-30 18:47:17,688 truncating revision to first 12 chars 2017-05-30 18:47:17,688 Setting DEBUG logging. 2017-05-30 18:47:17,688 attempt 1/10 2017-05-30 18:47:17,688 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5b2c84104a7f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-30 18:47:19,173 unpacking tar archive at: mozilla-central-5b2c84104a7f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.752437 ========= master_lag: 1.20 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-30 18:47:20.585873) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-30 18:47:20.586169) ========= script_repo_revision: 5b2c84104a7f4774fd120ab38d21b6483bcb63c7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-30 18:47:20.589267) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-30 18:47:20.589558) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-30 18:47:20.614704) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-05-30 18:47:20.615005) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fn1suntHu1/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.GODvfpuU6O/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 18:47:20 INFO - MultiFileLogger online at 20170530 18:47:20 in /builds/slave/test 18:47:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:47:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:47:20 INFO - {'append_to_log': False, 18:47:20 INFO - 'base_work_dir': '/builds/slave/test', 18:47:20 INFO - 'blob_upload_branch': 'Firefox', 18:47:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:47:20 INFO - 'branch': 'Firefox', 18:47:20 INFO - 'buildbot_json_path': 'buildprops.json', 18:47:20 INFO - 'config_files': ('talos/mac_config.py',), 18:47:20 INFO - 'default_actions': ('clobber', 18:47:20 INFO - 'read-buildbot-config', 18:47:20 INFO - 'download-and-extract', 18:47:20 INFO - 'populate-webroot', 18:47:20 INFO - 'create-virtualenv', 18:47:20 INFO - 'install', 18:47:20 INFO - 'setup-mitmproxy', 18:47:20 INFO - 'run-tests'), 18:47:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:47:20 INFO - 'download_minidump_stackwalk': True, 18:47:20 INFO - 'download_symbols': 'ondemand', 18:47:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:47:20 INFO - 'tooltool.py': '/tools/tooltool.py', 18:47:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:47:20 INFO - '/tools/misc-python/virtualenv.py')}, 18:47:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:47:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:47:20 INFO - 'gecko_profile': False, 18:47:20 INFO - 'gecko_profile_interval': 0, 18:47:20 INFO - 'installer_path': 'installer.exe', 18:47:20 INFO - 'log_level': 'info', 18:47:20 INFO - 'log_name': 'talos', 18:47:20 INFO - 'log_to_console': True, 18:47:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:47:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:47:20 INFO - 'opt_config_files': (), 18:47:20 INFO - 'pip_index': False, 18:47:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:47:20 INFO - 'cmd': ('bash', 18:47:20 INFO - '-c', 18:47:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:47:20 INFO - 'enabled': True, 18:47:20 INFO - 'halt_on_failure': False, 18:47:20 INFO - 'name': 'check_screen_resolution'},), 18:47:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:47:20 INFO - 'cmd': ('bash', 18:47:20 INFO - '-c', 18:47:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:47:20 INFO - 'enabled': True, 18:47:20 INFO - 'halt_on_failure': False, 18:47:20 INFO - 'name': 'check_screen_resolution'},), 18:47:20 INFO - 'run_cmd_checks_enabled': True, 18:47:20 INFO - 'suite': 'dromaeojs-e10s', 18:47:20 INFO - 'system_bits': '32', 18:47:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:47:20 INFO - 'title': 't-yosemite-r7-0151', 18:47:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:47:20 INFO - 'use_talos_json': True, 18:47:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:47:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:47:20 INFO - 'work_dir': 'build'} 18:47:20 INFO - [mozharness: 2017-05-31 01:47:20.764526Z] Running clobber step. 18:47:20 INFO - Running pre-action listener: _resource_record_pre_action 18:47:20 INFO - Running main action method: clobber 18:47:20 INFO - rmtree: /builds/slave/test/build 18:47:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:47:22 INFO - Running post-action listener: _resource_record_post_action 18:47:22 INFO - [mozharness: 2017-05-31 01:47:22.685738Z] Finished clobber step (success) 18:47:22 INFO - [mozharness: 2017-05-31 01:47:22.685906Z] Running read-buildbot-config step. 18:47:22 INFO - Running pre-action listener: _resource_record_pre_action 18:47:22 INFO - Running main action method: read_buildbot_config 18:47:22 INFO - Using buildbot properties: 18:47:22 INFO - { 18:47:22 INFO - "project": "", 18:47:22 INFO - "product": "firefox", 18:47:22 INFO - "who": "ryanvm@gmail.com", 18:47:22 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 18:47:22 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 18:47:22 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 18:47:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 18:47:22 INFO - "stage_platform": "macosx64", 18:47:22 INFO - "basedir": "/builds/slave/test", 18:47:22 INFO - "buildnumber": 82, 18:47:22 INFO - "platform": "yosemite_r7", 18:47:22 INFO - "parent_task_id": "EAsbH3b1T7KMQ5aG8m2ioQ", 18:47:22 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 18:47:22 INFO - "slavebuilddir": "test", 18:47:22 INFO - "taskId": "G70_U6I8R_CzXad814Gx_Q", 18:47:22 INFO - "branch": "mozilla-central", 18:47:22 INFO - "script_repo_revision": "production", 18:47:22 INFO - "revision": "5b2c84104a7f4774fd120ab38d21b6483bcb63c7", 18:47:22 INFO - "slavename": "t-yosemite-r7-0151", 18:47:22 INFO - "repo_path": "mozilla-central" 18:47:22 INFO - } 18:47:22 INFO - Finding installer, test and symbols from parent task. 18:47:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G70_U6I8R_CzXad814Gx_Q'}, attempt #1 18:47:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G70_U6I8R_CzXad814Gx_Q'}, attempt #1 18:47:23 INFO - Task dependencies: bbjq-ag4SKCKm-xt8gdHCA EAsbH3b1T7KMQ5aG8m2ioQ 18:47:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EAsbH3b1T7KMQ5aG8m2ioQ'}, attempt #1 18:47:24 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/EAsbH3b1T7KMQ5aG8m2ioQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:47:24 INFO - Set test_url: https://queue.taskcluster.net/v1/task/EAsbH3b1T7KMQ5aG8m2ioQ/artifacts/public/build/test_packages.json 18:47:24 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/EAsbH3b1T7KMQ5aG8m2ioQ/artifacts/public/build/target.crashreporter-symbols.zip 18:47:24 INFO - Running post-action listener: _resource_record_post_action 18:47:24 INFO - [mozharness: 2017-05-31 01:47:24.405404Z] Finished read-buildbot-config step (success) 18:47:24 INFO - [mozharness: 2017-05-31 01:47:24.405522Z] Running download-and-extract step. 18:47:24 INFO - Running pre-action listener: _resource_record_pre_action 18:47:24 INFO - Running main action method: download_and_extract 18:47:24 INFO - mkdir: /builds/slave/test/build/tests 18:47:24 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')]} 18:47:24 INFO - trying https://queue.taskcluster.net/v1/task/EAsbH3b1T7KMQ5aG8m2ioQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 18:47:24 INFO - Downloading https://queue.taskcluster.net/v1/task/EAsbH3b1T7KMQ5aG8m2ioQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:47:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EAsbH3b1T7KMQ5aG8m2ioQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 18:47:25 INFO - Downloaded 1325 bytes. 18:47:25 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:47:25 INFO - Using the following test package requirements: 18:47:25 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:47:25 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 18:47:25 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:47:25 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:47:25 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 18:47:25 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:47:25 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 18:47:25 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:47:25 INFO - u'jsshell-mac.zip'], 18:47:25 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:47:25 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 18:47:25 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:47:25 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:47:25 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 18:47:25 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:47:25 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 18:47:25 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:47:25 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 18:47:25 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:47:25 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 18:47:25 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:47:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EAsbH3b1T7KMQ5aG8m2ioQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:47:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EAsbH3b1T7KMQ5aG8m2ioQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:47:25 INFO - Fetch https://queue.taskcluster.net/v1/task/EAsbH3b1T7KMQ5aG8m2ioQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:47:27 INFO - Content-Length response header: 57631222 18:47:27 INFO - Bytes received: 57631222 18:47:45 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 18:47:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EAsbH3b1T7KMQ5aG8m2ioQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:47:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EAsbH3b1T7KMQ5aG8m2ioQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:47:45 INFO - Fetch https://queue.taskcluster.net/v1/task/EAsbH3b1T7KMQ5aG8m2ioQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:47:46 INFO - Content-Length response header: 57631222 18:47:46 INFO - Bytes received: 57631222 18:48:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EAsbH3b1T7KMQ5aG8m2ioQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 18:48:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EAsbH3b1T7KMQ5aG8m2ioQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:48:03 INFO - Fetch https://queue.taskcluster.net/v1/task/EAsbH3b1T7KMQ5aG8m2ioQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 18:48:05 INFO - Content-Length response header: 14447425 18:48:05 INFO - Bytes received: 14447425 18:48:06 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')]} 18:48:06 INFO - trying https://queue.taskcluster.net/v1/task/EAsbH3b1T7KMQ5aG8m2ioQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:48:06 INFO - Downloading https://queue.taskcluster.net/v1/task/EAsbH3b1T7KMQ5aG8m2ioQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 18:48:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EAsbH3b1T7KMQ5aG8m2ioQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 18:48:08 INFO - Downloaded 67883169 bytes. 18:48:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EAsbH3b1T7KMQ5aG8m2ioQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:48:08 INFO - mkdir: /builds/slave/test/properties 18:48:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:48:08 INFO - Writing to file /builds/slave/test/properties/build_url 18:48:08 INFO - Contents: 18:48:08 INFO - build_url:https://queue.taskcluster.net/v1/task/EAsbH3b1T7KMQ5aG8m2ioQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:48:08 INFO - Running post-action listener: _resource_record_post_action 18:48:08 INFO - Running post-action listener: set_extra_try_arguments 18:48:08 INFO - [mozharness: 2017-05-31 01:48:08.205981Z] Finished download-and-extract step (success) 18:48:08 INFO - [mozharness: 2017-05-31 01:48:08.206126Z] Running populate-webroot step. 18:48:08 INFO - Running pre-action listener: _resource_record_pre_action 18:48:08 INFO - Running main action method: populate_webroot 18:48:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:48:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:48:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:48:08 INFO - '--tppagecycles', 18:48:08 INFO - '7'], 18:48:08 INFO - 'tests': ['tsvgm']}}, 18:48:08 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:48:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:48:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:48:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:48:08 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:48:08 INFO - 'talos_options': ['--disable-e10s'], 18:48:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:48:08 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:48:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:48:08 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:48:08 INFO - 'talos_options': ['--disable-e10s'], 18:48:08 INFO - 'tests': ['damp', 'tps']}, 18:48:08 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:48:08 INFO - 'tests': ['damp', 'tps']}, 18:48:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:48:08 INFO - 'tests': ['dromaeo_dom']}, 18:48:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:48:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:48:08 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:48:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:48:08 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:48:08 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:48:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:48:08 INFO - 'tests': ['a11yr', 18:48:08 INFO - 'ts_paint', 18:48:08 INFO - 'tpaint', 18:48:08 INFO - 'sessionrestore', 18:48:08 INFO - 'sessionrestore_no_auto_restore', 18:48:08 INFO - 'tabpaint']}, 18:48:08 INFO - 'other-e10s': {'tests': ['a11yr', 18:48:08 INFO - 'ts_paint', 18:48:08 INFO - 'tpaint', 18:48:08 INFO - 'sessionrestore', 18:48:08 INFO - 'sessionrestore_no_auto_restore', 18:48:08 INFO - 'tabpaint']}, 18:48:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:48:08 INFO - 'ts_paint', 18:48:08 INFO - 'tpaint', 18:48:08 INFO - 'sessionrestore', 18:48:08 INFO - 'sessionrestore_no_auto_restore', 18:48:08 INFO - 'tabpaint']}, 18:48:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:48:08 INFO - 'ts_paint', 18:48:08 INFO - 'tpaint', 18:48:08 INFO - 'sessionrestore', 18:48:08 INFO - 'sessionrestore_no_auto_restore', 18:48:08 INFO - 'tabpaint']}, 18:48:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:48:08 INFO - 'tests': ['a11yr', 18:48:08 INFO - 'ts_paint', 18:48:08 INFO - 'tpaint', 18:48:08 INFO - 'sessionrestore', 18:48:08 INFO - 'sessionrestore_no_auto_restore', 18:48:08 INFO - 'tabpaint']}, 18:48:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:48:08 INFO - 'tests': ['a11yr', 18:48:08 INFO - 'ts_paint', 18:48:08 INFO - 'tpaint', 18:48:08 INFO - 'sessionrestore', 18:48:08 INFO - 'sessionrestore_no_auto_restore', 18:48:08 INFO - 'tabpaint']}, 18:48:08 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 18:48:08 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 18:48:08 INFO - 'bloom_basic_ref']}, 18:48:08 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 18:48:08 INFO - 'talos_options': ['--mitmproxy', 18:48:08 INFO - 'mitmproxy-recording-1.mp', 18:48:08 INFO - '--firstNonBlankPaint'], 18:48:08 INFO - 'tests': ['Quantum_1']}, 18:48:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:48:08 INFO - 'tests': ['tsvgx', 18:48:08 INFO - 'tsvgr_opacity', 18:48:08 INFO - 'tart', 18:48:08 INFO - 'tscrollx', 18:48:08 INFO - 'cart', 18:48:08 INFO - 'tsvg_static']}, 18:48:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:48:08 INFO - 'tsvgr_opacity', 18:48:08 INFO - 'tart', 18:48:08 INFO - 'tscrollx', 18:48:08 INFO - 'cart', 18:48:08 INFO - 'tsvg_static']}, 18:48:08 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:48:08 INFO - 'talos_options': ['--disable-e10s'], 18:48:08 INFO - 'tests': ['tp5o']}, 18:48:08 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:48:08 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:48:08 INFO - 'talos_options': ['--disable-e10s', 18:48:08 INFO - '--xperf_path', 18:48:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:48:08 INFO - 'tests': ['tp5n']}, 18:48:08 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:48:08 INFO - 'talos_options': ['--xperf_path', 18:48:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:48:08 INFO - 'tests': ['tp5n']}}, 18:48:08 INFO - 'talos.zip': {'path': '', 18:48:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:48:08 INFO - Running post-action listener: _resource_record_post_action 18:48:08 INFO - [mozharness: 2017-05-31 01:48:08.227966Z] Finished populate-webroot step (success) 18:48:08 INFO - [mozharness: 2017-05-31 01:48:08.228115Z] Running create-virtualenv step. 18:48:08 INFO - Running pre-action listener: _resource_record_pre_action 18:48:08 INFO - Running main action method: create_virtualenv 18:48:08 INFO - Creating virtualenv /builds/slave/test/build/venv 18:48:08 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 18:48:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:48:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:48:08 INFO - Using real prefix '/tools/python27' 18:48:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:48:09 INFO - Installing distribute.............................................................................................................................................................................................done. 18:48:11 INFO - Installing pip.................done. 18:48:11 INFO - Return code: 0 18:48:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:48:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:48:12 INFO - Reading from file tmpfile_stdout 18:48:12 INFO - Output received: 18:48:12 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) 18:48:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:48: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')]} 18:48:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48: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')]} 18:48:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e693c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e697768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3d94d06e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e61cc60>, '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 0x10e3b1cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3dabe8>, '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.fn1suntHu1/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.GODvfpuU6O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 18:48: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', 'pip>=1.5'] in /builds/slave/test/build 18:48: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 pip>=1.5 18:48:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fn1suntHu1/Render', 18:48:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:48:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:48:12 INFO - 'HOME': '/Users/cltbld', 18:48:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:48:12 INFO - 'LOGNAME': 'cltbld', 18:48:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:12 INFO - 'MOZ_NO_REMOTE': '1', 18:48:12 INFO - 'NO_EM_RESTART': '1', 18:48:12 INFO - 'PAGER': '/bin/cat', 18:48:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:48:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:12 INFO - 'PWD': '/builds/slave/test', 18:48:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:48:12 INFO - 'SHELL': '/bin/bash', 18:48:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GODvfpuU6O/Listeners', 18:48:12 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 18:48:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:48:12 INFO - 'USER': 'cltbld', 18:48:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:48:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:48:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:12 INFO - 'XPC_FLAGS': '0x0', 18:48:12 INFO - 'XPC_SERVICE_NAME': '0', 18:48:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:48:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:12 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 18:48:12 INFO - Cleaning up... 18:48:12 INFO - Return code: 0 18:48:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:48: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')]} 18:48:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48: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')]} 18:48:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:13 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 0x10e693c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e697768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3d94d06e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e61cc60>, '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 0x10e3b1cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3dabe8>, '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.fn1suntHu1/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.GODvfpuU6O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 18:48:13 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 18:48:13 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 18:48:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fn1suntHu1/Render', 18:48:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:48:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:48:13 INFO - 'HOME': '/Users/cltbld', 18:48:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:48:13 INFO - 'LOGNAME': 'cltbld', 18:48:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:13 INFO - 'MOZ_NO_REMOTE': '1', 18:48:13 INFO - 'NO_EM_RESTART': '1', 18:48:13 INFO - 'PAGER': '/bin/cat', 18:48:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:48:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:13 INFO - 'PWD': '/builds/slave/test', 18:48:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:48:13 INFO - 'SHELL': '/bin/bash', 18:48:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GODvfpuU6O/Listeners', 18:48:13 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 18:48:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:48:13 INFO - 'USER': 'cltbld', 18:48:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:48:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:48:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:13 INFO - 'XPC_FLAGS': '0x0', 18:48:13 INFO - 'XPC_SERVICE_NAME': '0', 18:48:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:48:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:13 INFO - Downloading/unpacking psutil>=3.1.1 18:48:13 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 18:48:13 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 18:48:13 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 18:48:13 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 18:48:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:48:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:48:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:48:15 INFO - Installing collected packages: psutil 18:48:15 INFO - Running setup.py install for psutil 18:48:15 INFO - building 'psutil._psutil_osx' extension 18:48:15 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 18:48:16 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 18:48:16 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 18:48:16 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 18:48:16 INFO - building 'psutil._psutil_posix' extension 18:48:16 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 18:48:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:48:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:48:16 INFO - ^ 18:48:16 INFO - 1 warning generated. 18:48:16 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 18:48:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:48:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:48:16 INFO - Successfully installed psutil 18:48:16 INFO - Cleaning up... 18:48:16 INFO - Return code: 0 18:48:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:48:16 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')]} 18:48:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:16 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')]} 18:48:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:16 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 0x10e693c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e697768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3d94d06e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e61cc60>, '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 0x10e3b1cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3dabe8>, '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.fn1suntHu1/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.GODvfpuU6O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 18:48:16 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 18:48:16 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 18:48:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fn1suntHu1/Render', 18:48:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:48:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:48:16 INFO - 'HOME': '/Users/cltbld', 18:48:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:48:16 INFO - 'LOGNAME': 'cltbld', 18:48:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:16 INFO - 'MOZ_NO_REMOTE': '1', 18:48:16 INFO - 'NO_EM_RESTART': '1', 18:48:16 INFO - 'PAGER': '/bin/cat', 18:48:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:48:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:16 INFO - 'PWD': '/builds/slave/test', 18:48:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:48:16 INFO - 'SHELL': '/bin/bash', 18:48:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GODvfpuU6O/Listeners', 18:48:16 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 18:48:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:48:16 INFO - 'USER': 'cltbld', 18:48:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:48:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:48:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:16 INFO - 'XPC_FLAGS': '0x0', 18:48:16 INFO - 'XPC_SERVICE_NAME': '0', 18:48:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:48:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:48:17 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 18:48:17 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 18:48:17 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 18:48:17 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 18:48:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:48:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:48:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:48:18 INFO - Installing collected packages: mozsystemmonitor 18:48:18 INFO - Running setup.py install for mozsystemmonitor 18:48:18 INFO - Successfully installed mozsystemmonitor 18:48:18 INFO - Cleaning up... 18:48:18 INFO - Return code: 0 18:48:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:48:18 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')]} 18:48:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:18 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')]} 18:48:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:18 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 0x10e693c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e697768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3d94d06e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e61cc60>, '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 0x10e3b1cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3dabe8>, '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.fn1suntHu1/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.GODvfpuU6O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 18:48:18 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 18:48:18 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 18:48:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fn1suntHu1/Render', 18:48:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:48:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:48:18 INFO - 'HOME': '/Users/cltbld', 18:48:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:48:18 INFO - 'LOGNAME': 'cltbld', 18:48:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:18 INFO - 'MOZ_NO_REMOTE': '1', 18:48:18 INFO - 'NO_EM_RESTART': '1', 18:48:18 INFO - 'PAGER': '/bin/cat', 18:48:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:48:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:18 INFO - 'PWD': '/builds/slave/test', 18:48:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:48:18 INFO - 'SHELL': '/bin/bash', 18:48:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GODvfpuU6O/Listeners', 18:48:18 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 18:48:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:48:18 INFO - 'USER': 'cltbld', 18:48:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:48:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:48:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:18 INFO - 'XPC_FLAGS': '0x0', 18:48:18 INFO - 'XPC_SERVICE_NAME': '0', 18:48:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:48:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:18 INFO - Downloading/unpacking jsonschema==2.5.1 18:48:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:48:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:48:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:48:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:48:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:48:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:48:20 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 18:48:20 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 18:48:20 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 18:48:20 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 18:48:20 INFO - Downloading functools32-3.2.3-2.tar.gz 18:48:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:48:20 INFO - warning: no files found matching '*.txt' 18:48:20 INFO - no previously-included directories found matching 'build' 18:48:20 INFO - no previously-included directories found matching 'dist' 18:48:20 INFO - no previously-included directories found matching '.git*' 18:48:20 INFO - Installing collected packages: jsonschema, functools32 18:48:20 INFO - Running setup.py install for functools32 18:48:20 INFO - warning: no files found matching '*.txt' 18:48:20 INFO - no previously-included directories found matching 'build' 18:48:20 INFO - no previously-included directories found matching 'dist' 18:48:20 INFO - no previously-included directories found matching '.git*' 18:48:20 INFO - Successfully installed jsonschema functools32 18:48:20 INFO - Cleaning up... 18:48:20 INFO - Return code: 0 18:48:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:48:20 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')]} 18:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:20 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')]} 18:48:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:20 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 0x10e693c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e697768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3d94d06e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e61cc60>, '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 0x10e3b1cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3dabe8>, '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.fn1suntHu1/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.GODvfpuU6O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 18:48:20 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 18:48:20 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 18:48:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fn1suntHu1/Render', 18:48:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:48:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:48:20 INFO - 'HOME': '/Users/cltbld', 18:48:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:48:20 INFO - 'LOGNAME': 'cltbld', 18:48:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:20 INFO - 'MOZ_NO_REMOTE': '1', 18:48:20 INFO - 'NO_EM_RESTART': '1', 18:48:20 INFO - 'PAGER': '/bin/cat', 18:48:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:48:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:20 INFO - 'PWD': '/builds/slave/test', 18:48:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:48:20 INFO - 'SHELL': '/bin/bash', 18:48:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GODvfpuU6O/Listeners', 18:48:20 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 18:48:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:48:20 INFO - 'USER': 'cltbld', 18:48:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:48:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:48:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:20 INFO - 'XPC_FLAGS': '0x0', 18:48:20 INFO - 'XPC_SERVICE_NAME': '0', 18:48:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:48:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:48:21 INFO - Cleaning up... 18:48:21 INFO - Return code: 0 18:48:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:48:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:21 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 0x10e693c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e697768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3d94d06e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e61cc60>, '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 0x10e3b1cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3dabe8>, '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.fn1suntHu1/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.GODvfpuU6O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 18:48:21 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 18:48:21 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 18:48:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fn1suntHu1/Render', 18:48:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:48:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:48:21 INFO - 'HOME': '/Users/cltbld', 18:48:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:48:21 INFO - 'LOGNAME': 'cltbld', 18:48:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:21 INFO - 'MOZ_NO_REMOTE': '1', 18:48:21 INFO - 'NO_EM_RESTART': '1', 18:48:21 INFO - 'PAGER': '/bin/cat', 18:48:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:48:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:21 INFO - 'PWD': '/builds/slave/test', 18:48:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:48:21 INFO - 'SHELL': '/bin/bash', 18:48:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GODvfpuU6O/Listeners', 18:48:21 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 18:48:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:48:21 INFO - 'USER': 'cltbld', 18:48:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:48:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:48:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:21 INFO - 'XPC_FLAGS': '0x0', 18:48:21 INFO - 'XPC_SERVICE_NAME': '0', 18:48:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:48:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:21 INFO - Downloading/unpacking blobuploader==1.2.4 18:48:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:48:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:48:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:48:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:48:22 INFO - Downloading blobuploader-1.2.4.tar.gz 18:48:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:48:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:48:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:48:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:48:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:48:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:48:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:48:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:48:23 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 18:48:23 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 18:48:23 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 18:48:23 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 18:48:23 INFO - Downloading docopt-0.6.1.tar.gz 18:48:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:48:23 INFO - Installing collected packages: blobuploader, requests, docopt 18:48:23 INFO - Running setup.py install for blobuploader 18:48:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:48:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:48:23 INFO - Running setup.py install for requests 18:48:24 INFO - Running setup.py install for docopt 18:48:24 INFO - Successfully installed blobuploader requests docopt 18:48:24 INFO - Cleaning up... 18:48:24 INFO - Return code: 0 18:48:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:48:24 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')]} 18:48:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:24 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')]} 18:48:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:24 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 0x10e693c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e697768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3d94d06e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e61cc60>, '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 0x10e3b1cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3dabe8>, '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.fn1suntHu1/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.GODvfpuU6O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 18:48:24 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 18:48:24 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 18:48:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fn1suntHu1/Render', 18:48:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:48:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:48:24 INFO - 'HOME': '/Users/cltbld', 18:48:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:48:24 INFO - 'LOGNAME': 'cltbld', 18:48:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:24 INFO - 'MOZ_NO_REMOTE': '1', 18:48:24 INFO - 'NO_EM_RESTART': '1', 18:48:24 INFO - 'PAGER': '/bin/cat', 18:48:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:48:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:24 INFO - 'PWD': '/builds/slave/test', 18:48:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:48:24 INFO - 'SHELL': '/bin/bash', 18:48:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GODvfpuU6O/Listeners', 18:48:24 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 18:48:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:48:24 INFO - 'USER': 'cltbld', 18:48:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:48:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:48:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:24 INFO - 'XPC_FLAGS': '0x0', 18:48:24 INFO - 'XPC_SERVICE_NAME': '0', 18:48:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:48:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:48:24 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-6cUPAG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:48:25 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-8YzS0J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:48:25 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-x7ZSh4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:48:25 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-nxf9Sv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:48:25 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-fGygvY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:48:25 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-J0ur_1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:48:25 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-mlfrOo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:48:25 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-dKAco4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:48:25 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-HiEYBt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:48:25 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-nmyHVH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:48:25 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-rQ51rj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:48:26 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-rDSsBG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:48:26 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-fAhHxf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:48:26 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-m2vP4F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:48:26 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-FmNRG0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:48:26 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-MGk1OF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:48:26 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-1zzkZz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:48:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:48:26 INFO - Running setup.py install for manifestparser 18:48:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:48:26 INFO - Running setup.py install for mozcrash 18:48:27 INFO - Running setup.py install for mozdebug 18:48:27 INFO - Running setup.py install for mozdevice 18:48:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:48:27 INFO - Running setup.py install for mozfile 18:48:27 INFO - Running setup.py install for mozhttpd 18:48:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:48:27 INFO - Running setup.py install for mozinfo 18:48:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:48:27 INFO - Running setup.py install for mozInstall 18:48:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:48:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:48:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:48:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:48:27 INFO - Running setup.py install for mozleak 18:48:28 INFO - Running setup.py install for mozlog 18:48:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:48:28 INFO - Running setup.py install for moznetwork 18:48:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:48:28 INFO - Running setup.py install for mozprocess 18:48:28 INFO - Running setup.py install for mozprofile 18:48:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:48:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:48:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:48:28 INFO - Running setup.py install for mozrunner 18:48:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:48:28 INFO - Running setup.py install for mozscreenshot 18:48:29 INFO - Running setup.py install for moztest 18:48:29 INFO - Running setup.py install for mozversion 18:48:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:48:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:48:29 INFO - Cleaning up... 18:48:29 INFO - Return code: 0 18:48:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:48:29 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')]} 18:48:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:29 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')]} 18:48:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:29 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 0x10e693c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e697768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3d94d06e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e61cc60>, '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 0x10e3b1cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3dabe8>, '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.fn1suntHu1/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.GODvfpuU6O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 18:48:29 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 18:48:29 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 18:48:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fn1suntHu1/Render', 18:48:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:48:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:48:29 INFO - 'HOME': '/Users/cltbld', 18:48:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:48:29 INFO - 'LOGNAME': 'cltbld', 18:48:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:29 INFO - 'MOZ_NO_REMOTE': '1', 18:48:29 INFO - 'NO_EM_RESTART': '1', 18:48:29 INFO - 'PAGER': '/bin/cat', 18:48:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:48:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:29 INFO - 'PWD': '/builds/slave/test', 18:48:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:48:29 INFO - 'SHELL': '/bin/bash', 18:48:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GODvfpuU6O/Listeners', 18:48:29 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 18:48:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:48:29 INFO - 'USER': 'cltbld', 18:48:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:48:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:48:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:29 INFO - 'XPC_FLAGS': '0x0', 18:48:29 INFO - 'XPC_SERVICE_NAME': '0', 18:48:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:48:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:48:29 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-dSS5kc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:48:29 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)) 18:48:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:48:29 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-C0ud4s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:48:29 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)) 18:48:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:48:29 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-syrdG2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:48:29 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)) 18:48:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:48:29 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-yDy1AY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:48:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:48:30 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-TW0hQ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:48:30 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)) 18:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:48:30 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-L_JFBm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:48:30 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)) 18:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:48:30 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-jH8Bwh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:48:30 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)) 18:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:48:30 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-qMX2aB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:48:30 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)) 18:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:48:30 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-icnUQo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:48:30 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)) 18:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:48:30 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-HqqPPK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:48:30 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)) 18:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:48:30 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-Zaa_Fo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:48:30 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)) 18:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:48:30 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-LDCT4v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:48:30 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)) 18:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:48:30 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-u_LpQz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:48:30 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)) 18:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:48:30 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-TUUwq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:48:31 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)) 18:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:48:31 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-JFKtvN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:48:31 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)) 18:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:48:31 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-y8pg0c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:48:31 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)) 18:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:48:31 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-9ZGImk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:48:31 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)) 18:48:31 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)) 18:48:31 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)) 18:48:31 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)) 18:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:48:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:48:31 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 18:48:31 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 18:48:31 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 18:48:31 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 18:48:32 INFO - Downloading blessings-1.6.tar.gz 18:48:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:48:32 INFO - Installing collected packages: blessings 18:48:32 INFO - Running setup.py install for blessings 18:48:33 INFO - Successfully installed blessings 18:48:33 INFO - Cleaning up... 18:48:33 INFO - Return code: 0 18:48:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:48:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:48:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:48:33 INFO - Reading from file tmpfile_stdout 18:48:33 INFO - Current package versions: 18:48:33 INFO - blessings == 1.6 18:48:33 INFO - blobuploader == 1.2.4 18:48:33 INFO - docopt == 0.6.1 18:48:33 INFO - functools32 == 3.2.3-2 18:48:33 INFO - jsonschema == 2.5.1 18:48:33 INFO - manifestparser == 1.1 18:48:33 INFO - mozInstall == 1.12 18:48:33 INFO - mozcrash == 1.0 18:48:33 INFO - mozdebug == 0.1 18:48:33 INFO - mozdevice == 0.51 18:48:33 INFO - mozfile == 1.2 18:48:33 INFO - mozhttpd == 0.7 18:48:33 INFO - mozinfo == 0.9 18:48:33 INFO - mozleak == 0.1 18:48:33 INFO - mozlog == 3.4 18:48:33 INFO - moznetwork == 0.27 18:48:33 INFO - mozprocess == 0.25 18:48:33 INFO - mozprofile == 0.28 18:48:33 INFO - mozrunner == 6.13 18:48:33 INFO - mozscreenshot == 0.1 18:48:33 INFO - mozsystemmonitor == 0.3 18:48:33 INFO - moztest == 0.8 18:48:33 INFO - mozversion == 1.4 18:48:33 INFO - psutil == 3.1.1 18:48:33 INFO - requests == 1.2.3 18:48:33 INFO - wsgiref == 0.1.2 18:48:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:48:33 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')]} 18:48:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:33 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')]} 18:48:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:33 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 0x10e693c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e697768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3d94d06e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e61cc60>, '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 0x10e3b1cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3dabe8>, '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.fn1suntHu1/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.GODvfpuU6O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 18:48:33 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 18:48:33 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 18:48:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fn1suntHu1/Render', 18:48:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:48:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:48:33 INFO - 'HOME': '/Users/cltbld', 18:48:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:48:33 INFO - 'LOGNAME': 'cltbld', 18:48:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:33 INFO - 'MOZ_NO_REMOTE': '1', 18:48:33 INFO - 'NO_EM_RESTART': '1', 18:48:33 INFO - 'PAGER': '/bin/cat', 18:48:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:48:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:33 INFO - 'PWD': '/builds/slave/test', 18:48:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:48:33 INFO - 'SHELL': '/bin/bash', 18:48:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GODvfpuU6O/Listeners', 18:48:33 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 18:48:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:48:33 INFO - 'USER': 'cltbld', 18:48:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:48:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:48:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:33 INFO - 'XPC_FLAGS': '0x0', 18:48:33 INFO - 'XPC_SERVICE_NAME': '0', 18:48:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:48:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:33 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)) 18:48:33 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)) 18:48:33 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)) 18:48:33 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)) 18:48:33 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)) 18:48:33 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)) 18:48:33 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)) 18:48:33 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)) 18:48:33 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)) 18:48:33 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:48:33 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 18:48:33 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 18:48:33 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 18:48:33 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 18:48:35 INFO - Downloading marionette_driver-1.4.0.tar.gz 18:48:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 18:48:35 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)) 18:48:35 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)) 18:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:48:35 INFO - Installing collected packages: marionette-driver 18:48:35 INFO - Running setup.py install for marionette-driver 18:48:35 INFO - Successfully installed marionette-driver 18:48:35 INFO - Cleaning up... 18:48:35 INFO - Return code: 0 18:48:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:48:35 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')]} 18:48:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:35 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')]} 18:48:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:35 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 0x10e693c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e697768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3d94d06e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e61cc60>, '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 0x10e3b1cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3dabe8>, '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.fn1suntHu1/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.GODvfpuU6O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 18:48:35 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 18:48:35 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 18:48:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fn1suntHu1/Render', 18:48:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:48:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:48:35 INFO - 'HOME': '/Users/cltbld', 18:48:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:48:35 INFO - 'LOGNAME': 'cltbld', 18:48:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:35 INFO - 'MOZ_NO_REMOTE': '1', 18:48:35 INFO - 'NO_EM_RESTART': '1', 18:48:35 INFO - 'PAGER': '/bin/cat', 18:48:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:48:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:35 INFO - 'PWD': '/builds/slave/test', 18:48:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:48:35 INFO - 'SHELL': '/bin/bash', 18:48:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GODvfpuU6O/Listeners', 18:48:35 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 18:48:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:48:35 INFO - 'USER': 'cltbld', 18:48:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:48:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:48:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:35 INFO - 'XPC_FLAGS': '0x0', 18:48:35 INFO - 'XPC_SERVICE_NAME': '0', 18:48:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:48:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:48:35 INFO - Cleaning up... 18:48:35 INFO - Return code: 0 18:48:35 INFO - Running post-action listener: _resource_record_post_action 18:48:35 INFO - Running post-action listener: _start_resource_monitoring 18:48:35 INFO - Starting resource monitoring. 18:48:35 INFO - [mozharness: 2017-05-31 01:48:35.754906Z] Finished create-virtualenv step (success) 18:48:35 INFO - [mozharness: 2017-05-31 01:48:35.755873Z] Running install step. 18:48:35 INFO - Running pre-action listener: _resource_record_pre_action 18:48:35 INFO - Running main action method: install 18:48:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:48:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:48:36 INFO - Reading from file tmpfile_stdout 18:48:36 INFO - Detecting whether we're running mozinstall >=1.0... 18:48:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:48:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:48:36 INFO - Reading from file tmpfile_stdout 18:48:36 INFO - Output received: 18:48:36 INFO - Usage: mozinstall [options] installer 18:48:36 INFO - Options: 18:48:36 INFO - -h, --help show this help message and exit 18:48:36 INFO - -d DEST, --destination=DEST 18:48:36 INFO - Directory to install application into. [default: 18:48:36 INFO - "/builds/slave/test"] 18:48:36 INFO - --app=APP Application being installed. [default: firefox] 18:48:36 INFO - mkdir: /builds/slave/test/build/application 18:48:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:48:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:48:57 INFO - Reading from file tmpfile_stdout 18:48:57 INFO - Output received: 18:48:57 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:48:57 INFO - Running post-action listener: _resource_record_post_action 18:48:57 INFO - [mozharness: 2017-05-31 01:48:57.557818Z] Finished install step (success) 18:48:57 INFO - [mozharness: 2017-05-31 01:48:57.557972Z] Running setup-mitmproxy step. 18:48:57 INFO - Running pre-action listener: _resource_record_pre_action 18:48:57 INFO - Running main action method: setup_mitmproxy 18:48:57 INFO - Skipping: mitmproxy is not required 18:48:57 INFO - Running post-action listener: _resource_record_post_action 18:48:57 INFO - [mozharness: 2017-05-31 01:48:57.558667Z] Finished setup-mitmproxy step (success) 18:48:57 INFO - [mozharness: 2017-05-31 01:48:57.558779Z] Running run-tests step. 18:48:57 INFO - Running pre-action listener: _resource_record_pre_action 18:48:57 INFO - Running main action method: run_tests 18:48:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:48:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:48:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:48:57 INFO - 2017-05-30 18:48:57.573 screenresolution[2790:39040] starting screenresolution argv=screenresolution get 18:48:57 INFO - 2017-05-30 18:48:57.576 screenresolution[2790:39040] Display 0: 1600x1200x32@60 18:48:57 INFO - 2017-05-30 18:48:57.582 screenresolution[2791:39042] starting screenresolution argv=screenresolution list 18:48:57 INFO - Available Modes on Display 0 18:48:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:48:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:48:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:48:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:48:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:48:57 INFO - Intel Iris: 18:48:57 INFO - Chipset Model: Intel Iris 18:48:57 INFO - Type: GPU 18:48:57 INFO - Bus: Built-In 18:48:57 INFO - VRAM (Dynamic, Max): 1536 MB 18:48:57 INFO - Vendor: Intel (0x8086) 18:48:57 INFO - Device ID: 0x0a2e 18:48:57 INFO - Revision ID: 0x0009 18:48:57 INFO - Displays: 18:48:57 INFO - Display: 18:48:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:48:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:48:57 INFO - Display Serial Number: 1600x1200 60 18:48:57 INFO - Main Display: Yes 18:48:57 INFO - Mirror: Off 18:48:57 INFO - Online: Yes 18:48:57 INFO - Rotation: Supported 18:48:57 INFO - Return code: 0 18:48:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:48:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:48:57 INFO - Python 2.7.3 18:48:57 INFO - Return code: 0 18:48:57 INFO - grabbing minidump binary from tooltool 18:48: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')]} 18:48:57 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e61cc60>, '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 0x10e3b1cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3dabe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:48:57 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:48:57 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 18:48:57 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:48:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:48:57 INFO - Return code: 0 18:48:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:48:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:48:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:48:57 INFO - ENV: RUST_BACKTRACE is now 1 18:48:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:48:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:48:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EAsbH3b1T7KMQ5aG8m2ioQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0151', '--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 18:48:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EAsbH3b1T7KMQ5aG8m2ioQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0151 --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 18:48:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fn1suntHu1/Render', 18:48:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:48:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:48:57 INFO - 'HOME': '/Users/cltbld', 18:48:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:48:57 INFO - 'LOGNAME': 'cltbld', 18:48:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:48:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:48:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:57 INFO - 'MOZ_NO_REMOTE': '1', 18:48:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:48:57 INFO - 'NO_EM_RESTART': '1', 18:48:57 INFO - 'PAGER': '/bin/cat', 18:48:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:48:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:57 INFO - 'PWD': '/builds/slave/test', 18:48:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:48:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:48:57 INFO - 'RUST_BACKTRACE': '1', 18:48:57 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:48:57 INFO - 'SHELL': '/bin/bash', 18:48:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GODvfpuU6O/Listeners', 18:48:57 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 18:48:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:48:57 INFO - 'USER': 'cltbld', 18:48:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:48:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:48:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:57 INFO - 'XPC_FLAGS': '0x0', 18:48:57 INFO - 'XPC_SERVICE_NAME': '0', 18:48:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:48:57 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EAsbH3b1T7KMQ5aG8m2ioQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0151', '--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 18:48:58 INFO - mozversion application_buildid: 20170530173123 18:48:58 INFO - mozversion application_changeset: 5b2c84104a7f4774fd120ab38d21b6483bcb63c7 18:48:58 INFO - mozversion application_display_name: Nightly 18:48:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:48:58 INFO - mozversion application_name: Firefox 18:48:58 INFO - mozversion application_remotingname: firefox 18:48:58 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:48:58 INFO - mozversion application_vendor: Mozilla 18:48:58 INFO - mozversion application_version: 55.0a1 18:48:58 INFO - mozversion platform_buildid: 20170530173123 18:48:58 INFO - mozversion platform_changeset: 5b2c84104a7f4774fd120ab38d21b6483bcb63c7 18:48:58 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:48:58 INFO - mozversion platform_version: 55.0a1 18:48:58 INFO - using testdate: 1496195338 18:48:58 INFO - actual date: 1496195338 18:48:58 INFO - starting webserver on 'localhost:58417' 18:48:58 INFO - SUITE-START | Running 2 tests 18:48:58 INFO - TEST-START | dromaeo_css 18:48:58 INFO - operating with platform_type : mac_ 18:48:58 INFO - Initialising browser for dromaeo_css test... 18:48:58 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:58417/getInfo.html -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmp1P0z5i/profile 18:48:58 INFO - TEST-INFO | started process 2801 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:58417/getInfo.html -foreground) 18:49:32 INFO - TEST-INFO | 2801: killed by SIGTERM 18:49:32 INFO - Browser initialized. 18:49:32 INFO - Running cycle 1/1 for dromaeo_css test... 18:49:32 INFO - TEST-INFO | started process 2807 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmp1P0z5i/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:49:34 INFO - PID 2807 | 2017-05-30 18:49:34.483 plugin-container[2808:39522] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 18:49:34 INFO - PID 2807 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:49:34 INFO - PID 2807 | 2017-05-30 18:49:34.484 plugin-container[2808:39522] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d17, name = 'com.apple.CFPasteboardClient' 18:49:34 INFO - PID 2807 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:49:34 INFO - PID 2807 | 2017-05-30 18:49:34.484 plugin-container[2808:39522] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:49:34 INFO - PID 2807 | 2017-05-30 18:49:34.922 plugin-container[2809:39568] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x943f, name = 'com.apple.tsm.portname' 18:49:34 INFO - PID 2807 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:49:34 INFO - PID 2807 | 2017-05-30 18:49:34.923 plugin-container[2809:39568] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 18:49:34 INFO - PID 2807 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:49:34 INFO - PID 2807 | 2017-05-30 18:49:34.923 plugin-container[2809:39568] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:49:44 INFO - PID 2807 | RSS: Main: 268873728 18:49:44 INFO - PID 2807 | 18:52:46 INFO - PID 2807 | Cycle 1(1): loaded http://localhost:58417/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58417/tests/dromaeo/cssquery-ext.html) 18:52:48 INFO - PID 2807 | RSS: Main: 280805376 18:52:48 INFO - PID 2807 | 18:55:50 INFO - PID 2807 | Cycle 1(1): loaded http://localhost:58417/tests/dromaeo/cssquery-ext.html (next: http://localhost:58417/tests/dromaeo/cssquery-jquery.html) 18:55:51 INFO - PID 2807 | RSS: Main: 290385920 18:55:51 INFO - PID 2807 | 18:58:53 INFO - PID 2807 | Cycle 1(1): loaded http://localhost:58417/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58417/tests/dromaeo/cssquery-mootools.html) 18:58:53 INFO - PID 2807 | RSS: Main: 288854016 18:58:53 INFO - PID 2807 | 19:01:55 INFO - PID 2807 | Cycle 1(1): loaded http://localhost:58417/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58417/tests/dromaeo/cssquery-prototype.html) 19:01:56 INFO - PID 2807 | RSS: Main: 292462592 19:01:56 INFO - PID 2807 | 19:04:58 INFO - PID 2807 | Cycle 1(1): loaded http://localhost:58417/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58417/tests/dromaeo/cssquery-yui.html) 19:04:59 INFO - PID 2807 | RSS: Main: 292532224 19:04:59 INFO - PID 2807 | 19:08:01 INFO - PID 2807 | Cycle 1(1): loaded http://localhost:58417/tests/dromaeo/cssquery-yui.html (next: http://localhost:58417/tests/dromaeo/cssquery-dojo.html) 19:08:01 INFO - PID 2807 | RSS: Main: 292470784 19:08:01 INFO - PID 2807 | 19:08:01 INFO - PID 2807 | __start_tp_report 19:08:01 INFO - PID 2807 | _x_x_mozilla_page_load 19:08:01 INFO - PID 2807 | _x_x_mozilla_page_load_details 19:08:01 INFO - PID 2807 | |i|pagename|runs| 19:08:01 INFO - PID 2807 | |0;dojo.html;3544.2557063016766;3556.608773034966;3600.0099972507574;3608.9097772555683;3610.6750392464673;5059.2916991621205;6156.846078848031;6170.47550958168;6212.3166451690395;6236.314005459401;6183.196184496021;6187.412195841388;6268.153799237101;6303.936960630393;6319.210098737658;5084.79660813567;5319.867003324909;5490.396056433789;5499.230107784903;5507.256520369759;5553.6390134641115;5634.295713035871;5643.322801263834;5647.548196144319;5696.031674615326;5441.29263195786;5489.6980666063355;5520.806771762969;5529.087700529408;5547.001539722889;5547.77808887648;5560;5560.916586251209;5562.9443705563235;5609.5792815539;5434.565234781208;5466.316710411199;5516.255305533742;5535.833924982217;5540.556755459554;6164.815055548341;6168.599041062362;6221.160143380631;6235.625862448268;6275.560710750249;6686.498512611525;6732.091167692348;6734.259231484532;6755.3582409671;6770.221424536143;6855.314468553105;6860.451163906925;6867.759628412981;6868.210155832094;6869.587824730532;6277.309495955441;6294.0558916162945;6326.557141000178;6363.96818015907;6368.203974503233;5784.855378615568;6175.752969881244;6966.582005079711;6975.267596902354;6977.40692041172;5137.948620513747;6290.685465726787;6461.386168314003;6517.804465866032;6607.108040414579;6761.188657361148;6826.419754320839;6828.0440738296675;6832.282610325944;6841.486888483384;6475.384838440341;6537.8365540860905;6599.373059559431;6723.260441351447;6741.393274605309;6613.702383392759;6731;6731.461483081342;6739.629320387327;6746.763863264762;5294.814681486129;5298.125809241509;5330.253764472977;5360.3299587551555;5360.812371566976;4690.929636055462;4734.857954261378;4755.976220118877;4765.666403351732;4819.566239038503;5250.317458730417;6266.185395898504;6334.588251763621;6357.713902874381;6421.775237866652;6769.898451523231;6772.796816095525;6774.627395493295;6782.186137663413;6818.522703410714;6305;6352.428281454691;6353.809385718436;6368.267649220399;6371.171747672773;5881.852953676107;6002.219711437572;6022.2171117754415;6081.087836824512;6087.1173679816175;6930.826729331706;6982.406495447944;6987.336203060701;6998.335158160068;6999.47503937217;6568.343165683394;6804.11546498952;6816.863662726619;6834.350736680008;6853.417459515997;6125.540584456095;6852.314768523108;6898.965505172442;6940.236573976757;7001.614911179934;6629.46964242869;6733.494987876027;6733.629650369081;6743.797686069426;6747.730090796314;6642.767503137367;6692.966535167293;6845.212800527804;6863.450923926173;6869.381755642016;6470.061841033118;6555.311692272243;6558.901616475852;6576.243731970693;6582.539222254396;6533.477321814338;6554.147960765261;6572.474202063728;6591.670416479061;6596;5426.67439953604;6356.968215158895;6441.1626488556185;6444.097826304415;6470.547061705635;6353;6450.41946224842;6492.902606460812;6549.0176473529355;6554.410103090745;6259.624422534663;6339.683015849281;6346.143270658402;6349.111124442674;6360.141380913517 19:08:01 INFO - PID 2807 | |1;ext.html;775.5811861594739;826.7933016745814;849.3460035772455;878.9165029322212;1020.0564477857985;38404.84785456434;39022.8048859756;39071.21857562847;39142.60857391425;39182;32374.67625323746;39015.41476877842;39284.21431571374;39455;39544.40683389751;8147.837043259131;8383.706570270042;8388.496690198608;8388.538630375328;8393.24460798528;8260.967379077616;8405.495670259805;8407.285380742644;8408.033076196254;8424.115467875878;6553.311902250267;7720.034995625547;8364.539950302731;8411.663533458684;8412.284955778736;8325.83348333036;8353.540555269457;8368.037675667316;8390.202930721633;8396.118407567239;8167.795805104861;8168.836623267561;8229.423940324179;8230.09468958414;8250.504969701837;29487;36607.90176294716;36860.63139368599;36923.63076369229;37050.814745926094;3558.6085530591613;3594.370985077627;3595.8921232363073;3598.082488965297;3601.549806274216;30220.546691799642;30623.846880765675;30647.233819154477;30658.84670576655;30681.386372272653;2580.909668161605;3090.103869877733;3101.6433110192393;3103.906882793497;3126.5935428394164;237006;239570;273597;294964;295849;37923.81038094792;46655.76672116685;46885.76557117193;46925.061498769835;46950;49240;49309.753451232515;49493.752531237114;54718.726406367714;55122;14565.344559494848;14759.557213283619;14761.852381476048;14766.630834229016;14779.704405911822;11760.176787624785;11864.22882512634;11867.228630139014;11879.58421455245;11880.465379057963;14037.947153963289;14167.433302667983;14198.716025679636;14199.36102875373;14308.356123974447;6007.48936340416;6046.727897244635;6059.666718330534;6063.72713227897;6105.145279660851;924.8993697500088;968.6874285343399;981.8183636027358;1031.9277650564388;1040.308195050283;23484.765152348256;24116.51766964686;24131.396715082265;24207;24314.756852431605;1078.3475997021847;1080.9405482698528;1113.1206346985791;1116.776644671069;1116.8771435142128;9563.665271715456;12239.69400764988;12341.950934170696;12342.506299747909;12426.813597796041;320501;320621;321955;322173;322676;53125.73437132867;53430.732846336294;53493.732531337875;53526;53580.73209634005;1413.3993052952453;1447.8061455483103;1474.609228554434;1487.1671863756114;1511.9924400377927;23499.17752878641;23605.881970590723;23717.525649486568;23719.40701482408;23841.88079059663;35897.28205435824;36212.818935905154;36359.63640363669;36364.454533181495;36388.45417318795;273628;273823;273963;274237;274829;34479.96560103201;35492;35496.7576134834;35585.64414355823;35673.82163089168;44558.66324010285;45252.77373613111;45382.546174537834;45420.545794541635;45589.86025349393;26519;29382;29627.703722962495;31102;31406;31022.689773102884;31204.21989450191;31310.686893130776;31411.685883140875;31448.528272076393;30760.69239307668;37454.62545374512;37752.24495510129;37763.86708398753;37794.24411511809;29735.85132074326;29934.95227667021;31480.27860776057;31745.04764857058;31836.04491865248;24565.017399303826;24583.50832983366;24893.189430591738;24894.12870549522;25038 19:08:01 INFO - PID 2807 | |2;jquery.html;384.9268638958599;416.6375253529428;429.8351467523012;438.8793081902476;440.2427824142475;21295;22249.332520024403;22363.552728945433;23154.768452315515;24353.75646243537;24084.916178771997;24541;24608.50782984339;24625.138120165797;24631.876840615816;5236.450172731866;6347.238331400238;6407.743690252408;6409.134766806492;6410.775622853201;5867.442592953663;6351.745930162811;6396.328385519523;6405.807825765234;6415.807525774234;6056.666883321415;6128.877422451571;6130.632162070267;6132.938670613304;6134.662593557353;6077.848053798646;6092.147099406086;6128.3258841527395;6143.477804386633;6148.631082135042;5475.206095116226;5477.370102438231;5483.424240454735;5488.121900495941;5493.615446918718;24398.634020489706;28764.568531472043;29107.56338654922;29269.56095658567;29595.66819493128;2146.3024517032027;2168.414528077426;2181.2583721534597;2183.8908054597205;2187.9671804922937;18424.723629145574;19040.61918761631;19047.047647617565;19069.427917162506;19089.04554772256;2122.1299267300487;2124.883131427771;2165.8483906126576;2191.9780802191935;2202.1411649456722;131499;136424.31787841098;165983.17008415;199895;203646;24968.12611558623;25084.498310033698;25096.870640821206;25445.363865903502;25483.74516254851;42090.789546052074;42459;42490.93772655646;42509;42594.361084583754;11508.712282193012;11645.941770291094;11714.414279286002;11733.590522448587;11815.291082534903;8805.911940880638;9216.493092879957;9251.352405331561;9350.766230844283;9354.485503297248;10502;12040.39798010096;12075.818862717066;12093.213941093802;12153;4653.557911998355;4738.505961715594;4757.619390448755;4779.351123862262;4784.378030855966;930.9534523273944;967.0909345215475;967.4582233949011;968.8664262812526;978.6036655154674;17743.43706904702;20760.273390431263;21519.78480215209;21794.673079903812;22132.336029918806;989.6734077754323;997.4563862694848;1013.4628646817199;1026.6047571684956;1029.2177944762034;10526.842097368546;10677.252592318464;10910.5090270938;10956.28784129029;11049.944750276198;532713;533369.3331533396;537615;547669;554321;142554;143324;144905.27547362196;147339.2633036828;147347;1042.7341028037954;1160.1356989043218;1169.637412402141;1176.7117056321256;1197.503036239968;19711;23201.88399057994;23514.412139696644;23587.29238122859;23609.64585531184;68993.65503172453;69380;69531.65234173796;70583;71667.64166179136;90587.54706226691;90836;90962.54518727364;91199;91235;15645.139517326656;16141.59646008859;16294.674106517567;16318;16343.182840857671;17638.294468221127;18123.36568220106;18231.726524102414;18236.17937192803;18273.90863045676;12319.322437266037;14707.92646036763;14738.55784326472;14825.481108161162;14895.85104148988;12837.10140290208;13011.739765204833;13049.412776425273;13205.669858253235;13299.734005320033;14874.553763387115;14961.700765984837;14977.951543392293;15096.471623493126;15180.620484487976;15495.380184792482;15805.209739513208;15882.967607105502;15981.44064957721;15991.68016639684;14377.353019114375;14609.415623374945;14692.971491995459;14729.852701473277;14730.77903831422 19:08:01 INFO - PID 2807 | |3;mootools.html;1353.4721458631132;1398.524501669432;1413.0391333892962;1426.2512181104912;1432.6203556057644;9256.814863702723;11707;12109.333986630732;12131.514739410424;12453.003759699224;11988.520459181644;12193;12198.390080495961;12200.389980500962;12225.877741222585;2530.2029860593852;2587.4954383895147;2589.2879458148973;2591.261490475215;2591.520568694788;2480.6899137607797;2514.182890560566;2515.0819950717946;2519.937001574966;2521.9117330893423;2436.122995721548;2461.237016524883;2467.8889449974704;2469.2098528470897;2470.8146888983397;2417.8186636002283;2446.54738873309;2456.1280745335507;2460.987695061513;2462.5936720441196;2407.807375409963;2426.466177440978;2440.3771491958005;2453.7914277286272;2520.8991640334366;11756;11851.111166662535;11869.169158158933;11872.287662740264;11889.583864564698;6055.364186760415;6702;6704.463642908556;6707.228668703113;6722.058911752359;4020.9396859047138;4186.1418409226035;4261.74429534229;4264.808083636244;4278.679099067551;1055.5513906589745;1238.2570457725383;1240.665020444475;1261.9558315459008;1265.5254279645133;15561.377544898303;15948.760768588596;16576;16577.751333730004;16687.666246675;5433.157860531624;5448.42791506894;5587.38538760736;5662.66024038559;5665.206871038058;11849.407529623484;11861.5848445304;11875.821862672066;11878.584249551224;11905.404729763477;5634.971825140848;6041.848953776191;6438.678066096676;6481.578697384655;6556.967215163893;6068.6358818471035;6183.659898705524;6200.348963358872;6214.844628884314;6263.937360626426;3976.940345894814;4184.8744537663915;4339.717918335298;4399.49405818335;4472.217361961643;1155.9711007224719;1168.9596259329203;1255.2468518888777;1258.389681004712;1278.8465384153776;746.1568427676691;776.1656219563991;793.4208028139491;796.2037962037962;797.2545669798757;6756.121704178524;6964.955256711533;7072.165463492639;7078.539894906815;7164.283571642898;897.9102089790969;933.9906600934039;945.6595625574788;946.4274114160971;947.4078700811992;3941.1329507508303;4335.978320108442;4415.757133357632;4536.432945881765;4594.5864872161665;14972.850271497146;15160.241987900781;15418.691626167414;15446.691066178613;15585.220738963006;12325.938370308091;14679.412823487155;15381.307841147176;15427.5371738848;15569.844301557067;8598.312135029182;8764.079771623889;8784.472931623995;8817.515036673045;8818.839384350875;6237.844053898689;6975.686094125878;7096.290370962863;7272.672729727069;7395.963020184865;12989.610311690665;13043.869561304646;13082.542111026069;13366.665833354244;13370.732585348043;13153.342332883509;13225.47098116103;13338.399772010476;13450.192988420335;13515.256660883746;9440.33917625787;9580.66467673628;9622.759431014001;9627.951860240653;9632;10694.091002264895;10715.821259661276;10804.945975270073;10829.783404332027;10874.94562527214;9142.862857057282;9144.405613635092;9268.907310926805;9273.582688778886;9289.953550232205;9031.50326732036;9041.508151154985;9143.542822858963;9186.127317904788;9200.539973001458;8156.143604921398;8275.83448331042;8323.41736078493;8334.291585215324;8350.373721970755;5475.589330800126;5881.882362352643;6017.458428741435;6058.5758996871555;6224.875502490016;5411.972940135274;6085.269767627913;6247.31279559239;6267.717952692231;6318.27339855904 19:08:01 INFO - PID 2807 | |4;prototype.html;363.91993761372487;384.2142817937318;395.4720448201651;405.50123348281596;410.19396885120733;6332.176817013793;6503.739850405984;6546.247181574115;6547.773756922639;6599.6370199639005;5190.948090519094;6242.812715618538;6387.584806987543;6387.90418143727;6389.233292004954;4579.816807327719;4624.260118381059;4634.72191668501;4653.464851542065;4672.135654903836;4575.611073058794;4649.51180126087;4668.008852431173;4669.953300466986;4681.438227412716;4088.693347998897;4615.538446155391;4616;4620.676552641316;4622.699524530912;4336.3278691802825;4622.907541849144;4631.096936097462;4639.095376401603;4658.673892827504;3628.727845411605;3941.192055628589;3949.2299001694682;3960.108975480494;3970.7617542947514;5201.765920533585;5407.945920540823;6279.968600157015;6316.263027667383;6322.936770632281;4653.767311634439;5504.091824848895;5515.179990603091;5526.889462210734;5541.612087153901;5501.449855014466;6083.148359229712;6806.285340039274;6897.965510172417;6934.237233904267;4096;4101.52832424272;4462.397576327219;4464.8883777905485;4486.791943760194;6750.966245168841;6933.653317334113;7098;7195.071376107383;7338.889916651201;3750.549934007882;3907.765534067965;4209.810898332911;4236.533981262059;4499.392582001454;4577.382053422745;4586.312053191981;4595.195840727859;4602.861914142581;4607.101615185074;4745.83389581363;5280.445553216857;5376.462353764592;5472.480114389126;5476.424975377608;6746.156730408699;6753.966230168916;6829.897551536731;6853.339728984339;6878.759243426556;1351.7364113997676;1534.9386024558892;1552.472159465787;1560.2510794818324;1563.2730780187267;993.8409854423257;1017.4658304390107;1023.2223510132353;1024.5184763161303;1029.4389557691077;5358.410574836765;6367.968160159169;6446.290908000116;6461.7738379157445;6474.902876456857;7141.250168732312;7147.499675022803;7155.320244576861;7186.6047367395295;7193.424526037905;5443.129099344086;6370.77702280418;6431.678416079178;6444.80665580024;6461.289258181596;3229.951550726741;3248.456982933088;3254.772165948361;3485.7908525488047;3713.535808023997;7302.744403945836;7333.863251196073;7342.229065947999;7356.705731770669;7367.963160184165;6005.6396616203165;7279.216591934904;7299.649564830524;7309.597972111585;7327.377172940253;6777.49168812341;7247.637618119073;7282.198958114604;7284.380827629605;7348.228436014356;6218.782342617987;6791.694373753292;7152.033190872552;7160.355567998905;7165.892511612435;6648.634325112165;6994.930050699428;7023.508354415142;7031.437485001085;7033.472489563201;6692.063111164538;7008.649567521502;7333.193348731742;7372.668229929559;7373.5575865448245;3948.874570747323;4015.036391266017;4472.619827314715;4485.730856148642;4520.88697782558;6815.182178138655;6957.339052789976;7124.430045596444;7179.210286868545;7200.351968322876;6029.698515074317;6786.321367863175;6822.420094291842;6833.077534532775;6879.6560171992205;5728.885422291614;6020.819375418744;6772.695228714818;6850.143732033496;6867.622280774605;5546.694931778791;6671.366220209073;6688.1909597968;6695;6713.261541230363;5729.627574207663;6837.3162683731225;6838.008488769001;6839.794806155824;6849.801284775244;5505.284313039279;6584.308647592125;6658.7336506539195;6676.165479315086;6686.297938716365 19:08:01 INFO - PID 2807 | |5;yui.html;795.1293333799495;813.6745301879243;825.4139560911752;840.1556435782038;842.3513894301389;13507.864921350783;14060.64848378791;14719;15263.313150908209;16969.575760605992;15995.200239988013;17870.01714905683;17895.28418863244;17928.37250696226;17992.280308787635;973.5035132082636;983.9311248212592;998.1765043838826;998.9860291803825;1002.5087707023579;1007.2143727892255;1007.7984403119369;1008.2186305613135;1009.6112996496329;1012.2458768217688;948.7628092976756;1034.7413146713322;1036.896310368961;1037.5486663301488;1055.519738518972;1030.654730665228;1031.4378663628265;1038.7247379444452;1052.884182739902;1053.394298278489;743.3124359967063;744.1107876088076;745.3813334932048;747.5589402252642;766.4481573267238;17178.484645460656;17451.73822392665;17512.299508019663;17581.7362739559;18104.90947545254;17753.644927101384;17787.73318400225;17900.55248618782;18237.817621823746;18253.726194107097;12753.61739147827;13949;14890.925545372313;14961.551153465414;14966.17686027279;3470.8091054991964;3587.264610754789;3588.4796704477703;3614.493970844084;3618.2763447310645;144653.27673361672;148393;148558.51441485554;149156;149242.50757492395;13521.59435216945;13621.931890340684;13697.863021369658;13705.45178192881;13832.30838458073;26423.603645945324;26640.333991650365;26921;26940.461190776074;27090.86454567754;6559.44244739193;6778.491613129035;7395.963020184865;7701.3068823806125;7796.883046754303;3674.761140525857;3752.75607085544;3757.5678796938155;3759.793211373401;3759.9436008459893;4147.958520414757;4207.474065741782;4214.936775948363;4246.447961765012;4246.4691913510815;821.7263241974915;898.8606765951288;923.4320892650971;931.6878845586668;935.0742764662934;638.8498702804837;653.8430776613578;655.2791928878196;661.6460193796296;674.9291324410964;9724.756881077888;9939.65211217604;11664.6500604982;12196.207246528948;12210.938945305394;622.6793201501231;633.6894921488438;639.9840003999844;641.1952998986243;664.0802488553388;5603.8666580386935;5682.431756824284;5736.856578585569;5746.597738158289;5752.482276595043;119846.60306793815;123941;143223;143423;145950;124461;126922;127796.45126220556;129976;130454;107495;107692;107809;107937;109405;10873.891261087289;11087.889121108685;11125.554977800797;11154.219204655596;11318.377489238172;39478.802605986784;45987;46337.76831115823;46434;47387.052258955315;80092.59953700428;82860;85396;85811.57094214739;89164.5541772313;17026.74459883078;17190.914045429694;17803.46589602314;17836.643267134325;18053;17233.482995510156;19183.8081619182;19195;19327.226910923968;19509.02454877222;16563.83436165623;16569.25438355301;17530.824691752918;17591.736123957893;17616.471505854846;17400.955942643483;17407.303707851544;18121.728174077136;18143.911365318123;18456.907715461337;16585.004899706055;16688.749668755223;16726.498205053867;16800.663986719952;16876;9875.753106172117;10583.788324233627;10845.945770271099;11762.117841162119;12321.876781232431;12605.93697031509;13185.73628527405;13341.466341346237;13602.86397136016;14016.929915350358 19:08:01 INFO - PID 2807 | __end_tp_report 19:08:01 INFO - PID 2807 | __start_cc_report 19:08:01 INFO - PID 2807 | _x_x_mozilla_cycle_collect,447 19:08:01 INFO - PID 2807 | __end_cc_report 19:08:01 INFO - PID 2807 | __startTimestamp1496196481537__endTimestamp 19:08:01 INFO - PID 2807 | 19:08:01 INFO - PID 2807 | ------- Summary: start ------- 19:08:01 INFO - PID 2807 | Number of tests: 6 19:08:01 INFO - PID 2807 | 19:08:01 INFO - PID 2807 | [#0] dojo.html Cycles:165 Average:6181.47 Median:6360.14 stddev:735.37 (11.6%) stddev-sans-first:707.92 19:08:01 INFO - PID 2807 | Values: 3544.3 3556.6 3600.0 3608.9 3610.7 5059.3 6156.8 6170.5 6212.3 6236.3 6183.2 6187.4 6268.2 6303.9 6319.2 5084.8 5319.9 5490.4 5499.2 5507.3 5553.6 5634.3 5643.3 5647.5 5696.0 5441.3 5489.7 5520.8 5529.1 5547.0 5547.8 5560.0 5560.9 5562.9 5609.6 5434.6 5466.3 5516.3 5535.8 5540.6 6164.8 6168.6 6221.2 6235.6 6275.6 6686.5 6732.1 6734.3 6755.4 6770.2 6855.3 6860.5 6867.8 6868.2 6869.6 6277.3 6294.1 6326.6 6364.0 6368.2 5784.9 6175.8 6966.6 6975.3 6977.4 5137.9 6290.7 6461.4 6517.8 6607.1 6761.2 6826.4 6828.0 6832.3 6841.5 6475.4 6537.8 6599.4 6723.3 6741.4 6613.7 6731.0 6731.5 6739.6 6746.8 5294.8 5298.1 5330.3 5360.3 5360.8 4690.9 4734.9 4756.0 4765.7 4819.6 5250.3 6266.2 6334.6 6357.7 6421.8 6769.9 6772.8 6774.6 6782.2 6818.5 6305.0 6352.4 6353.8 6368.3 6371.2 5881.9 6002.2 6022.2 6081.1 6087.1 6930.8 6982.4 6987.3 6998.3 6999.5 6568.3 6804.1 6816.9 6834.4 6853.4 6125.5 6852.3 6899.0 6940.2 7001.6 6629.5 6733.5 6733.6 6743.8 6747.7 6642.8 6693.0 6845.2 6863.5 6869.4 6470.1 6555.3 6558.9 6576.2 6582.5 6533.5 6554.1 6572.5 6591.7 6596.0 5426.7 6357.0 6441.2 6444.1 6470.5 6353.0 6450.4 6492.9 6549.0 6554.4 6259.6 6339.7 6346.1 6349.1 6360.1 19:08:01 INFO - PID 2807 | 19:08:01 INFO - PID 2807 | [#1] ext.html Cycles:180 Average:44030.71 Median:34985.98 stddev:75797.26 (216.7%) stddev-sans-first:75940.31 19:08:01 INFO - PID 2807 | Values: 775.6 826.8 849.3 878.9 1020.1 38404.8 39022.8 39071.2 39142.6 39182.0 32374.7 39015.4 39284.2 39455.0 39544.4 8147.8 8383.7 8388.5 8388.5 8393.2 8261.0 8405.5 8407.3 8408.0 8424.1 6553.3 7720.0 8364.5 8411.7 8412.3 8325.8 8353.5 8368.0 8390.2 8396.1 8167.8 8168.8 8229.4 8230.1 8250.5 29487.0 36607.9 36860.6 36923.6 37050.8 3558.6 3594.4 3595.9 3598.1 3601.5 30220.5 30623.8 30647.2 30658.8 30681.4 2580.9 3090.1 3101.6 3103.9 3126.6 237006.0 239570.0 273597.0 294964.0 295849.0 37923.8 46655.8 46885.8 46925.1 46950.0 49240.0 49309.8 49493.8 54718.7 55122.0 14565.3 14759.6 14761.9 14766.6 14779.7 11760.2 11864.2 11867.2 11879.6 11880.5 14037.9 14167.4 14198.7 14199.4 14308.4 6007.5 6046.7 6059.7 6063.7 6105.1 924.9 968.7 981.8 1031.9 1040.3 23484.8 24116.5 24131.4 24207.0 24314.8 1078.3 1080.9 1113.1 1116.8 1116.9 9563.7 12239.7 12342.0 12342.5 12426.8 320501.0 320621.0 321955.0 322173.0 322676.0 53125.7 53430.7 53493.7 53526.0 53580.7 1413.4 1447.8 1474.6 1487.2 1512.0 23499.2 23605.9 23717.5 23719.4 23841.9 35897.3 36212.8 36359.6 36364.5 36388.5 273628.0 273823.0 273963.0 274237.0 274829.0 34480.0 35492.0 35496.8 35585.6 35673.8 44558.7 45252.8 45382.5 45420.5 45589.9 26519.0 29382.0 29627.7 31102.0 31406.0 31022.7 31204.2 31310.7 31411.7 31448.5 30760.7 37454.6 37752.2 37763.9 37794.2 29735.9 29935.0 31480.3 31745.0 31836.0 24565.0 24583.5 24893.2 24894.1 25038.0 19:08:01 INFO - PID 2807 | 19:08:01 INFO - PID 2807 | [#2] jquery.html Cycles:180 Average:39266.42 Median:22306.44 stddev:92848.01 (416.2%) stddev-sans-first:93062.58 19:08:01 INFO - PID 2807 | Values: 384.9 416.6 429.8 438.9 440.2 21295.0 22249.3 22363.6 23154.8 24353.8 24084.9 24541.0 24608.5 24625.1 24631.9 5236.5 6347.2 6407.7 6409.1 6410.8 5867.4 6351.7 6396.3 6405.8 6415.8 6056.7 6128.9 6130.6 6132.9 6134.7 6077.8 6092.1 6128.3 6143.5 6148.6 5475.2 5477.4 5483.4 5488.1 5493.6 24398.6 28764.6 29107.6 29269.6 29595.7 2146.3 2168.4 2181.3 2183.9 2188.0 18424.7 19040.6 19047.0 19069.4 19089.0 2122.1 2124.9 2165.8 2192.0 2202.1 131499.0 136424.3 165983.2 199895.0 203646.0 24968.1 25084.5 25096.9 25445.4 25483.7 42090.8 42459.0 42490.9 42509.0 42594.4 11508.7 11645.9 11714.4 11733.6 11815.3 8805.9 9216.5 9251.4 9350.8 9354.5 10502.0 12040.4 12075.8 12093.2 12153.0 4653.6 4738.5 4757.6 4779.4 4784.4 931.0 967.1 967.5 968.9 978.6 17743.4 20760.3 21519.8 21794.7 22132.3 989.7 997.5 1013.5 1026.6 1029.2 10526.8 10677.3 10910.5 10956.3 11049.9 532713.0 533369.3 537615.0 547669.0 554321.0 142554.0 143324.0 144905.3 147339.3 147347.0 1042.7 1160.1 1169.6 1176.7 1197.5 19711.0 23201.9 23514.4 23587.3 23609.6 68993.7 69380.0 69531.7 70583.0 71667.6 90587.5 90836.0 90962.5 91199.0 91235.0 15645.1 16141.6 16294.7 16318.0 16343.2 17638.3 18123.4 18231.7 18236.2 18273.9 12319.3 14707.9 14738.6 14825.5 14895.9 12837.1 13011.7 13049.4 13205.7 13299.7 14874.6 14961.7 14978.0 15096.5 15180.6 15495.4 15805.2 15883.0 15981.4 15991.7 14377.4 14609.4 14693.0 14729.9 14730.8 19:08:01 INFO - PID 2807 | 19:08:01 INFO - PID 2807 | [#3] mootools.html Cycles:180 Average:7148.38 Median:3998.94 stddev:4509.39 (112.8%) stddev-sans-first:4501.01 19:08:01 INFO - PID 2807 | Values: 1353.5 1398.5 1413.0 1426.3 1432.6 9256.8 11707.0 12109.3 12131.5 12453.0 11988.5 12193.0 12198.4 12200.4 12225.9 2530.2 2587.5 2589.3 2591.3 2591.5 2480.7 2514.2 2515.1 2519.9 2521.9 2436.1 2461.2 2467.9 2469.2 2470.8 2417.8 2446.5 2456.1 2461.0 2462.6 2407.8 2426.5 2440.4 2453.8 2520.9 11756.0 11851.1 11869.2 11872.3 11889.6 6055.4 6702.0 6704.5 6707.2 6722.1 4020.9 4186.1 4261.7 4264.8 4278.7 1055.6 1238.3 1240.7 1262.0 1265.5 15561.4 15948.8 16576.0 16577.8 16687.7 5433.2 5448.4 5587.4 5662.7 5665.2 11849.4 11861.6 11875.8 11878.6 11905.4 5635.0 6041.8 6438.7 6481.6 6557.0 6068.6 6183.7 6200.3 6214.8 6263.9 3976.9 4184.9 4339.7 4399.5 4472.2 1156.0 1169.0 1255.2 1258.4 1278.8 746.2 776.2 793.4 796.2 797.3 6756.1 6965.0 7072.2 7078.5 7164.3 897.9 934.0 945.7 946.4 947.4 3941.1 4336.0 4415.8 4536.4 4594.6 14972.9 15160.2 15418.7 15446.7 15585.2 12325.9 14679.4 15381.3 15427.5 15569.8 8598.3 8764.1 8784.5 8817.5 8818.8 6237.8 6975.7 7096.3 7272.7 7396.0 12989.6 13043.9 13082.5 13366.7 13370.7 13153.3 13225.5 13338.4 13450.2 13515.3 9440.3 9580.7 9622.8 9628.0 9632.0 10694.1 10715.8 10804.9 10829.8 10874.9 9142.9 9144.4 9268.9 9273.6 9290.0 9031.5 9041.5 9143.5 9186.1 9200.5 8156.1 8275.8 8323.4 8334.3 8350.4 5475.6 5881.9 6017.5 6058.6 6224.9 5412.0 6085.3 6247.3 6267.7 6318.3 19:08:01 INFO - PID 2807 | 19:08:01 INFO - PID 2807 | [#4] prototype.html Cycles:180 Average:5478.73 Median:6298.12 stddev:1791.00 (28.4%) stddev-sans-first:1754.39 19:08:01 INFO - PID 2807 | Values: 363.9 384.2 395.5 405.5 410.2 6332.2 6503.7 6546.2 6547.8 6599.6 5190.9 6242.8 6387.6 6387.9 6389.2 4579.8 4624.3 4634.7 4653.5 4672.1 4575.6 4649.5 4668.0 4670.0 4681.4 4088.7 4615.5 4616.0 4620.7 4622.7 4336.3 4622.9 4631.1 4639.1 4658.7 3628.7 3941.2 3949.2 3960.1 3970.8 5201.8 5407.9 6280.0 6316.3 6322.9 4653.8 5504.1 5515.2 5526.9 5541.6 5501.4 6083.1 6806.3 6898.0 6934.2 4096.0 4101.5 4462.4 4464.9 4486.8 6751.0 6933.7 7098.0 7195.1 7338.9 3750.5 3907.8 4209.8 4236.5 4499.4 4577.4 4586.3 4595.2 4602.9 4607.1 4745.8 5280.4 5376.5 5472.5 5476.4 6746.2 6754.0 6829.9 6853.3 6878.8 1351.7 1534.9 1552.5 1560.3 1563.3 993.8 1017.5 1023.2 1024.5 1029.4 5358.4 6368.0 6446.3 6461.8 6474.9 7141.3 7147.5 7155.3 7186.6 7193.4 5443.1 6370.8 6431.7 6444.8 6461.3 3230.0 3248.5 3254.8 3485.8 3713.5 7302.7 7333.9 7342.2 7356.7 7368.0 6005.6 7279.2 7299.6 7309.6 7327.4 6777.5 7247.6 7282.2 7284.4 7348.2 6218.8 6791.7 7152.0 7160.4 7165.9 6648.6 6994.9 7023.5 7031.4 7033.5 6692.1 7008.6 7333.2 7372.7 7373.6 3948.9 4015.0 4472.6 4485.7 4520.9 6815.2 6957.3 7124.4 7179.2 7200.4 6029.7 6786.3 6822.4 6833.1 6879.7 5728.9 6020.8 6772.7 6850.1 6867.6 5546.7 6671.4 6688.2 6695.0 6713.3 5729.6 6837.3 6838.0 6839.8 6849.8 5505.3 6584.3 6658.7 6676.2 6686.3 19:08:01 INFO - PID 2807 | 19:08:01 INFO - PID 2807 | [#5] yui.html Cycles:180 Average:26086.82 Median:17853.33 stddev:40120.34 (224.7%) stddev-sans-first:40187.95 19:08:01 INFO - PID 2807 | Values: 795.1 813.7 825.4 840.2 842.4 13507.9 14060.6 14719.0 15263.3 16969.6 15995.2 17870.0 17895.3 17928.4 17992.3 973.5 983.9 998.2 999.0 1002.5 1007.2 1007.8 1008.2 1009.6 1012.2 948.8 1034.7 1036.9 1037.5 1055.5 1030.7 1031.4 1038.7 1052.9 1053.4 743.3 744.1 745.4 747.6 766.4 17178.5 17451.7 17512.3 17581.7 18104.9 17753.6 17787.7 17900.6 18237.8 18253.7 12753.6 13949.0 14890.9 14961.6 14966.2 3470.8 3587.3 3588.5 3614.5 3618.3 144653.3 148393.0 148558.5 149156.0 149242.5 13521.6 13621.9 13697.9 13705.5 13832.3 26423.6 26640.3 26921.0 26940.5 27090.9 6559.4 6778.5 7396.0 7701.3 7796.9 3674.8 3752.8 3757.6 3759.8 3759.9 4148.0 4207.5 4214.9 4246.4 4246.5 821.7 898.9 923.4 931.7 935.1 638.8 653.8 655.3 661.6 674.9 9724.8 9939.7 11664.7 12196.2 12210.9 622.7 633.7 640.0 641.2 664.1 5603.9 5682.4 5736.9 5746.6 5752.5 119846.6 123941.0 143223.0 143423.0 145950.0 124461.0 126922.0 127796.5 129976.0 130454.0 107495.0 107692.0 107809.0 107937.0 109405.0 10873.9 11087.9 11125.6 11154.2 11318.4 39478.8 45987.0 46337.8 46434.0 47387.1 80092.6 82860.0 85396.0 85811.6 89164.6 17026.7 17190.9 17803.5 17836.6 18053.0 17233.5 19183.8 19195.0 19327.2 19509.0 16563.8 16569.3 17530.8 17591.7 17616.5 17401.0 17407.3 18121.7 18143.9 18456.9 16585.0 16688.7 16726.5 16800.7 16876.0 9875.8 10583.8 10845.9 11762.1 12321.9 12605.9 13185.7 13341.5 13602.9 14016.9 19:08:01 INFO - PID 2807 | -------- Summary: end -------- 19:08:01 INFO - PID 2807 | 19:08:02 INFO - TEST-INFO | 2807: exit 0 19:08:02 INFO - TEST-OK | dromaeo_css | took 1143910ms 19:08:02 INFO - TEST-START | kraken 19:08:02 INFO - operating with platform_type : mac_ 19:08:02 INFO - Initialising browser for kraken test... 19:08:02 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:58417/getInfo.html -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmpA8Z_Np/profile 19:08:02 INFO - TEST-INFO | started process 2814 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:58417/getInfo.html -foreground) 19:08:36 INFO - TEST-INFO | 2814: killed by SIGTERM 19:08:36 INFO - Browser initialized. 19:08:36 INFO - Running cycle 1/1 for kraken test... 19:08:36 INFO - TEST-INFO | started process 2818 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmpA8Z_Np/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:08:38 INFO - PID 2818 | 2017-05-30 19:08:38.443 plugin-container[2819:41961] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 19:08:38 INFO - PID 2818 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:08:38 INFO - PID 2818 | 2017-05-30 19:08:38.444 plugin-container[2819:41961] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x491f, name = 'com.apple.CFPasteboardClient' 19:08:38 INFO - PID 2818 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:08:38 INFO - PID 2818 | 2017-05-30 19:08:38.444 plugin-container[2819:41961] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:08:38 INFO - PID 2818 | 2017-05-30 19:08:38.846 plugin-container[2820:42005] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 19:08:38 INFO - PID 2818 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:08:38 INFO - PID 2818 | 2017-05-30 19:08:38.847 plugin-container[2820:42005] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 19:08:38 INFO - PID 2818 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:08:38 INFO - PID 2818 | 2017-05-30 19:08:38.847 plugin-container[2820:42005] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:08:48 INFO - PID 2818 | RSS: Main: 268988416 19:08:48 INFO - PID 2818 | 19:08:50 INFO - PID 2818 | Cycle 1(1): loaded http://localhost:58417/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58417/tests/kraken/driver.html?testName=audio-beat-detection) 19:08:51 INFO - PID 2818 | RSS: Main: 276770816 19:08:51 INFO - PID 2818 | 19:08:54 INFO - PID 2818 | Cycle 1(1): loaded http://localhost:58417/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58417/tests/kraken/driver.html?testName=audio-dft) 19:08:55 INFO - PID 2818 | RSS: Main: 284545024 19:08:55 INFO - PID 2818 | 19:08:58 INFO - PID 2818 | Cycle 1(1): loaded http://localhost:58417/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58417/tests/kraken/driver.html?testName=audio-fft) 19:08:59 INFO - PID 2818 | RSS: Main: 282431488 19:08:59 INFO - PID 2818 | 19:09:01 INFO - PID 2818 | Cycle 1(1): loaded http://localhost:58417/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58417/tests/kraken/driver.html?testName=audio-oscillator) 19:09:01 INFO - PID 2818 | RSS: Main: 283815936 19:09:01 INFO - PID 2818 | 19:09:03 INFO - PID 2818 | Cycle 1(1): loaded http://localhost:58417/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58417/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:09:04 INFO - PID 2818 | RSS: Main: 283840512 19:09:04 INFO - PID 2818 | 19:09:07 INFO - PID 2818 | Cycle 1(1): loaded http://localhost:58417/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58417/tests/kraken/driver.html?testName=imaging-darkroom) 19:09:07 INFO - PID 2818 | RSS: Main: 288079872 19:09:07 INFO - PID 2818 | 19:09:10 INFO - PID 2818 | Cycle 1(1): loaded http://localhost:58417/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58417/tests/kraken/driver.html?testName=imaging-desaturate) 19:09:11 INFO - PID 2818 | RSS: Main: 288231424 19:09:11 INFO - PID 2818 | 19:09:14 INFO - PID 2818 | Cycle 1(1): loaded http://localhost:58417/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58417/tests/kraken/driver.html?testName=json-parse-financial) 19:09:15 INFO - PID 2818 | RSS: Main: 289665024 19:09:15 INFO - PID 2818 | 19:09:16 INFO - PID 2818 | Cycle 1(1): loaded http://localhost:58417/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58417/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:09:17 INFO - PID 2818 | RSS: Main: 289247232 19:09:17 INFO - PID 2818 | 19:09:19 INFO - PID 2818 | Cycle 1(1): loaded http://localhost:58417/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58417/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:09:19 INFO - PID 2818 | RSS: Main: 289247232 19:09:19 INFO - PID 2818 | 19:09:22 INFO - PID 2818 | Cycle 1(1): loaded http://localhost:58417/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58417/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:09:22 INFO - PID 2818 | RSS: Main: 289230848 19:09:22 INFO - PID 2818 | 19:09:25 INFO - PID 2818 | Cycle 1(1): loaded http://localhost:58417/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58417/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:09:26 INFO - PID 2818 | RSS: Main: 289230848 19:09:26 INFO - PID 2818 | 19:09:29 INFO - PID 2818 | Cycle 1(1): loaded http://localhost:58417/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58417/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:09:30 INFO - PID 2818 | RSS: Main: 289415168 19:09:30 INFO - PID 2818 | 19:09:31 INFO - PID 2818 | Cycle 1(1): loaded http://localhost:58417/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58417/tests/kraken/driver.html?testName=ai-astar) 19:09:32 INFO - PID 2818 | RSS: Main: 291336192 19:09:32 INFO - PID 2818 | 19:09:32 INFO - PID 2818 | __start_tp_report 19:09:32 INFO - PID 2818 | _x_x_mozilla_page_load 19:09:32 INFO - PID 2818 | _x_x_mozilla_page_load_details 19:09:32 INFO - PID 2818 | |i|pagename|runs| 19:09:32 INFO - PID 2818 | |0;ai-astar;102;114;113;113;127;170;162;114;113;110 19:09:32 INFO - PID 2818 | |1;audio-beat-detection;137;113;109;142;108;113;110;115;137;112 19:09:32 INFO - PID 2818 | |2;audio-dft;203;200;199;228;244;241;201;187;266;281 19:09:32 INFO - PID 2818 | |3;audio-fft;82;59;59;87;61;59;59;62;82;65 19:09:32 INFO - PID 2818 | |4;audio-oscillator;78;70;83;72;79;87;87;72;71;89 19:09:32 INFO - PID 2818 | |5;imaging-gaussian-blur;91;90;88;88;89;88;89;97;88;88 19:09:32 INFO - PID 2818 | |6;imaging-darkroom;156;118;97;97;125;108;98;98;98;98 19:09:32 INFO - PID 2818 | |7;imaging-desaturate;90;92;91;92;95;96;88;93;92;89 19:09:32 INFO - PID 2818 | |8;json-parse-financial;52;49;51;52;50;50;51;52;50;52 19:09:32 INFO - PID 2818 | |9;json-stringify-tinderbox;60;62;58;74;88;92;84;49;82;82 19:09:32 INFO - PID 2818 | |10;stanford-crypto-aes;88;89;88;87;85;89;90;92;127;95 19:09:32 INFO - PID 2818 | |11;stanford-crypto-ccm;141;158;127;137;157;148;151;163;160;145 19:09:32 INFO - PID 2818 | |12;stanford-crypto-pbkdf2;185;178;186;186;186;181;249;231;187;219 19:09:32 INFO - PID 2818 | |13;stanford-crypto-sha256-iterative;66;70;65;67;68;66;69;65;70;69 19:09:32 INFO - PID 2818 | __end_tp_report 19:09:32 INFO - PID 2818 | __start_cc_report 19:09:32 INFO - PID 2818 | _x_x_mozilla_cycle_collect,1186 19:09:32 INFO - PID 2818 | __end_cc_report 19:09:32 INFO - PID 2818 | __startTimestamp1496196572156__endTimestamp 19:09:32 INFO - PID 2818 | 19:09:32 INFO - PID 2818 | ------- Summary: start ------- 19:09:32 INFO - PID 2818 | Number of tests: 14 19:09:32 INFO - PID 2818 | 19:09:32 INFO - PID 2818 | [#0] ai-astar Cycles:10 Average:123.80 Median:114.00 stddev:23.12 (20.3%) stddev-sans-first:23.14 19:09:32 INFO - PID 2818 | Values: 102.0 114.0 113.0 113.0 127.0 170.0 162.0 114.0 113.0 110.0 19:09:32 INFO - PID 2818 | 19:09:32 INFO - PID 2818 | [#1] audio-beat-detection Cycles:10 Average:119.60 Median:114.00 stddev:13.38 (11.7%) stddev-sans-first:12.63 19:09:32 INFO - PID 2818 | Values: 137.0 113.0 109.0 142.0 108.0 113.0 110.0 115.0 137.0 112.0 19:09:32 INFO - PID 2818 | 19:09:32 INFO - PID 2818 | [#2] audio-dft Cycles:10 Average:225.00 Median:234.50 stddev:32.06 (13.7%) stddev-sans-first:33.00 19:09:32 INFO - PID 2818 | Values: 203.0 200.0 199.0 228.0 244.0 241.0 201.0 187.0 266.0 281.0 19:09:32 INFO - PID 2818 | 19:09:32 INFO - PID 2818 | [#3] audio-fft Cycles:10 Average:67.50 Median:63.50 stddev:11.39 (17.9%) stddev-sans-first:10.81 19:09:32 INFO - PID 2818 | Values: 82.0 59.0 59.0 87.0 61.0 59.0 59.0 62.0 82.0 65.0 19:09:32 INFO - PID 2818 | 19:09:32 INFO - PID 2818 | [#4] audio-oscillator Cycles:10 Average:78.80 Median:81.00 stddev:7.36 (9.1%) stddev-sans-first:7.80 19:09:32 INFO - PID 2818 | Values: 78.0 70.0 83.0 72.0 79.0 87.0 87.0 72.0 71.0 89.0 19:09:32 INFO - PID 2818 | 19:09:32 INFO - PID 2818 | [#5] imaging-gaussian-blur Cycles:10 Average:89.60 Median:89.00 stddev:2.80 (3.1%) stddev-sans-first:2.92 19:09:32 INFO - PID 2818 | Values: 91.0 90.0 88.0 88.0 89.0 88.0 89.0 97.0 88.0 88.0 19:09:32 INFO - PID 2818 | 19:09:32 INFO - PID 2818 | [#6] imaging-darkroom Cycles:10 Average:109.30 Median:97.50 stddev:19.20 (19.7%) stddev-sans-first:10.58 19:09:32 INFO - PID 2818 | Values: 156.0 118.0 97.0 97.0 125.0 108.0 98.0 98.0 98.0 98.0 19:09:32 INFO - PID 2818 | 19:09:32 INFO - PID 2818 | [#7] imaging-desaturate Cycles:10 Average:91.80 Median:92.00 stddev:2.49 (2.7%) stddev-sans-first:2.55 19:09:32 INFO - PID 2818 | Values: 90.0 92.0 91.0 92.0 95.0 96.0 88.0 93.0 92.0 89.0 19:09:32 INFO - PID 2818 | 19:09:32 INFO - PID 2818 | [#8] json-parse-financial Cycles:10 Average:50.90 Median:51.50 stddev:1.10 (2.1%) stddev-sans-first:1.09 19:09:32 INFO - PID 2818 | Values: 52.0 49.0 51.0 52.0 50.0 50.0 51.0 52.0 50.0 52.0 19:09:32 INFO - PID 2818 | 19:09:32 INFO - PID 2818 | [#9] json-stringify-tinderbox Cycles:10 Average:73.10 Median:82.00 stddev:14.76 (18.0%) stddev-sans-first:14.88 19:09:32 INFO - PID 2818 | Values: 60.0 62.0 58.0 74.0 88.0 92.0 84.0 49.0 82.0 82.0 19:09:32 INFO - PID 2818 | 19:09:32 INFO - PID 2818 | [#10] stanford-crypto-aes Cycles:10 Average:93.00 Median:89.00 stddev:12.26 (13.8%) stddev-sans-first:12.87 19:09:32 INFO - PID 2818 | Values: 88.0 89.0 88.0 87.0 85.0 89.0 90.0 92.0 127.0 95.0 19:09:32 INFO - PID 2818 | 19:09:32 INFO - PID 2818 | [#11] stanford-crypto-ccm Cycles:10 Average:148.70 Median:154.00 stddev:11.42 (7.4%) stddev-sans-first:11.77 19:09:32 INFO - PID 2818 | Values: 141.0 158.0 127.0 137.0 157.0 148.0 151.0 163.0 160.0 145.0 19:09:32 INFO - PID 2818 | 19:09:32 INFO - PID 2818 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:198.80 Median:186.50 stddev:24.80 (13.3%) stddev-sans-first:25.80 19:09:32 INFO - PID 2818 | Values: 185.0 178.0 186.0 186.0 186.0 181.0 249.0 231.0 187.0 219.0 19:09:32 INFO - PID 2818 | 19:09:32 INFO - PID 2818 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:68.50 stddev:1.96 (2.9%) stddev-sans-first:2.00 19:09:32 INFO - PID 2818 | Values: 66.0 70.0 65.0 67.0 68.0 66.0 69.0 65.0 70.0 69.0 19:09:32 INFO - PID 2818 | -------- Summary: end -------- 19:09:32 INFO - PID 2818 | 19:09:32 INFO - TEST-INFO | 2818: exit 0 19:09:32 INFO - TEST-OK | kraken | took 90827ms 19:09:32 INFO - SUITE-END | took 1234s 19:09:33 INFO - Completed test suite (00:20:35) 19:09:33 INFO - javascript benchmark 19:09:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8215.648707018358, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3544.2557063016766, 3556.608773034966, 3600.0099972507574, 3608.9097772555683, 3610.6750392464673, 5059.2916991621205, 6156.846078848031, 6170.47550958168, 6212.3166451690395, 6236.314005459401, 6183.196184496021, 6187.412195841388, 6268.153799237101, 6303.936960630393, 6319.210098737658, 5084.79660813567, 5319.867003324909, 5490.396056433789, 5499.230107784903, 5507.256520369759, 5553.6390134641115, 5634.295713035871, 5643.322801263834, 5647.548196144319, 5696.031674615326, 5441.29263195786, 5489.6980666063355, 5520.806771762969, 5529.087700529408, 5547.001539722889, 5547.77808887648, 5560.0, 5560.916586251209, 5562.9443705563235, 5609.5792815539, 5434.565234781208, 5466.316710411199, 5516.255305533742, 5535.833924982217, 5540.556755459554, 6164.815055548341, 6168.599041062362, 6221.160143380631, 6235.625862448268, 6275.560710750249, 6686.498512611525, 6732.091167692348, 6734.259231484532, 6755.3582409671, 6770.221424536143, 6855.314468553105, 6860.451163906925, 6867.759628412981, 6868.210155832094, 6869.587824730532, 6277.309495955441, 6294.0558916162945, 6326.557141000178, 6363.96818015907, 6368.203974503233, 5784.855378615568, 6175.752969881244, 6966.582005079711, 6975.267596902354, 6977.40692041172, 5137.948620513747, 6290.685465726787, 6461.386168314003, 6517.804465866032, 6607.108040414579, 6761.188657361148, 6826.419754320839, 6828.0440738296675, 6832.282610325944, 6841.486888483384, 6475.384838440341, 6537.8365540860905, 6599.373059559431, 6723.260441351447, 6741.393274605309, 6613.702383392759, 6731.0, 6731.461483081342, 6739.629320387327, 6746.763863264762, 5294.814681486129, 5298.125809241509, 5330.253764472977, 5360.3299587551555, 5360.812371566976, 4690.929636055462, 4734.857954261378, 4755.976220118877, 4765.666403351732, 4819.566239038503, 5250.317458730417, 6266.185395898504, 6334.588251763621, 6357.713902874381, 6421.775237866652, 6769.898451523231, 6772.796816095525, 6774.627395493295, 6782.186137663413, 6818.522703410714, 6305.0, 6352.428281454691, 6353.809385718436, 6368.267649220399, 6371.171747672773, 5881.852953676107, 6002.219711437572, 6022.2171117754415, 6081.087836824512, 6087.1173679816175, 6930.826729331706, 6982.406495447944, 6987.336203060701, 6998.335158160068, 6999.47503937217, 6568.343165683394, 6804.11546498952, 6816.863662726619, 6834.350736680008, 6853.417459515997, 6125.540584456095, 6852.314768523108, 6898.965505172442, 6940.236573976757, 7001.614911179934, 6629.46964242869, 6733.494987876027, 6733.629650369081, 6743.797686069426, 6747.730090796314, 6642.767503137367, 6692.966535167293, 6845.212800527804, 6863.450923926173, 6869.381755642016, 6470.061841033118, 6555.311692272243, 6558.901616475852, 6576.243731970693, 6582.539222254396, 6533.477321814338, 6554.147960765261, 6572.474202063728, 6591.670416479061, 6596.0, 5426.67439953604, 6356.968215158895, 6441.1626488556185, 6444.097826304415, 6470.547061705635, 6353.0, 6450.41946224842, 6492.902606460812, 6549.0176473529355, 6554.410103090745, 6259.624422534663, 6339.683015849281, 6346.143270658402, 6349.111124442674, 6360.141380913517], "value": 6133.63944784705, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [775.5811861594739, 826.7933016745814, 849.3460035772455, 878.9165029322212, 1020.0564477857985, 38404.84785456434, 39022.8048859756, 39071.21857562847, 39142.60857391425, 39182.0, 32374.67625323746, 39015.41476877842, 39284.21431571374, 39455.0, 39544.40683389751, 8147.837043259131, 8383.706570270042, 8388.496690198608, 8388.538630375328, 8393.24460798528, 8260.967379077616, 8405.495670259805, 8407.285380742644, 8408.033076196254, 8424.115467875878, 6553.311902250267, 7720.034995625547, 8364.539950302731, 8411.663533458684, 8412.284955778736, 8325.83348333036, 8353.540555269457, 8368.037675667316, 8390.202930721633, 8396.118407567239, 8167.795805104861, 8168.836623267561, 8229.423940324179, 8230.09468958414, 8250.504969701837, 29487.0, 36607.90176294716, 36860.63139368599, 36923.63076369229, 37050.814745926094, 3558.6085530591613, 3594.370985077627, 3595.8921232363073, 3598.082488965297, 3601.549806274216, 30220.546691799642, 30623.846880765675, 30647.233819154477, 30658.84670576655, 30681.386372272653, 2580.909668161605, 3090.103869877733, 3101.6433110192393, 3103.906882793497, 3126.5935428394164, 237006.0, 239570.0, 273597.0, 294964.0, 295849.0, 37923.81038094792, 46655.76672116685, 46885.76557117193, 46925.061498769835, 46950.0, 49240.0, 49309.753451232515, 49493.752531237114, 54718.726406367714, 55122.0, 14565.344559494848, 14759.557213283619, 14761.852381476048, 14766.630834229016, 14779.704405911822, 11760.176787624785, 11864.22882512634, 11867.228630139014, 11879.58421455245, 11880.465379057963, 14037.947153963289, 14167.433302667983, 14198.716025679636, 14199.36102875373, 14308.356123974447, 6007.48936340416, 6046.727897244635, 6059.666718330534, 6063.72713227897, 6105.145279660851, 924.8993697500088, 968.6874285343399, 981.8183636027358, 1031.9277650564388, 1040.308195050283, 23484.765152348256, 24116.51766964686, 24131.396715082265, 24207.0, 24314.756852431605, 1078.3475997021847, 1080.9405482698528, 1113.1206346985791, 1116.776644671069, 1116.8771435142128, 9563.665271715456, 12239.69400764988, 12341.950934170696, 12342.506299747909, 12426.813597796041, 320501.0, 320621.0, 321955.0, 322173.0, 322676.0, 53125.73437132867, 53430.732846336294, 53493.732531337875, 53526.0, 53580.73209634005, 1413.3993052952453, 1447.8061455483103, 1474.609228554434, 1487.1671863756114, 1511.9924400377927, 23499.17752878641, 23605.881970590723, 23717.525649486568, 23719.40701482408, 23841.88079059663, 35897.28205435824, 36212.818935905154, 36359.63640363669, 36364.454533181495, 36388.45417318795, 273628.0, 273823.0, 273963.0, 274237.0, 274829.0, 34479.96560103201, 35492.0, 35496.7576134834, 35585.64414355823, 35673.82163089168, 44558.66324010285, 45252.77373613111, 45382.546174537834, 45420.545794541635, 45589.86025349393, 26519.0, 29382.0, 29627.703722962495, 31102.0, 31406.0, 31022.689773102884, 31204.21989450191, 31310.686893130776, 31411.685883140875, 31448.528272076393, 30760.69239307668, 37454.62545374512, 37752.24495510129, 37763.86708398753, 37794.24411511809, 29735.85132074326, 29934.95227667021, 31480.27860776057, 31745.04764857058, 31836.04491865248, 24565.017399303826, 24583.50832983366, 24893.189430591738, 24894.12870549522, 25038.0], "value": 17338.718206541886, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.9268638958599, 416.6375253529428, 429.8351467523012, 438.8793081902476, 440.2427824142475, 21295.0, 22249.332520024403, 22363.552728945433, 23154.768452315515, 24353.75646243537, 24084.916178771997, 24541.0, 24608.50782984339, 24625.138120165797, 24631.876840615816, 5236.450172731866, 6347.238331400238, 6407.743690252408, 6409.134766806492, 6410.775622853201, 5867.442592953663, 6351.745930162811, 6396.328385519523, 6405.807825765234, 6415.807525774234, 6056.666883321415, 6128.877422451571, 6130.632162070267, 6132.938670613304, 6134.662593557353, 6077.848053798646, 6092.147099406086, 6128.3258841527395, 6143.477804386633, 6148.631082135042, 5475.206095116226, 5477.370102438231, 5483.424240454735, 5488.121900495941, 5493.615446918718, 24398.634020489706, 28764.568531472043, 29107.56338654922, 29269.56095658567, 29595.66819493128, 2146.3024517032027, 2168.414528077426, 2181.2583721534597, 2183.8908054597205, 2187.9671804922937, 18424.723629145574, 19040.61918761631, 19047.047647617565, 19069.427917162506, 19089.04554772256, 2122.1299267300487, 2124.883131427771, 2165.8483906126576, 2191.9780802191935, 2202.1411649456722, 131499.0, 136424.31787841098, 165983.17008415, 199895.0, 203646.0, 24968.12611558623, 25084.498310033698, 25096.870640821206, 25445.363865903502, 25483.74516254851, 42090.789546052074, 42459.0, 42490.93772655646, 42509.0, 42594.361084583754, 11508.712282193012, 11645.941770291094, 11714.414279286002, 11733.590522448587, 11815.291082534903, 8805.911940880638, 9216.493092879957, 9251.352405331561, 9350.766230844283, 9354.485503297248, 10502.0, 12040.39798010096, 12075.818862717066, 12093.213941093802, 12153.0, 4653.557911998355, 4738.505961715594, 4757.619390448755, 4779.351123862262, 4784.378030855966, 930.9534523273944, 967.0909345215475, 967.4582233949011, 968.8664262812526, 978.6036655154674, 17743.43706904702, 20760.273390431263, 21519.78480215209, 21794.673079903812, 22132.336029918806, 989.6734077754323, 997.4563862694848, 1013.4628646817199, 1026.6047571684956, 1029.2177944762034, 10526.842097368546, 10677.252592318464, 10910.5090270938, 10956.28784129029, 11049.944750276198, 532713.0, 533369.3331533396, 537615.0, 547669.0, 554321.0, 142554.0, 143324.0, 144905.27547362196, 147339.2633036828, 147347.0, 1042.7341028037954, 1160.1356989043218, 1169.637412402141, 1176.7117056321256, 1197.503036239968, 19711.0, 23201.88399057994, 23514.412139696644, 23587.29238122859, 23609.64585531184, 68993.65503172453, 69380.0, 69531.65234173796, 70583.0, 71667.64166179136, 90587.54706226691, 90836.0, 90962.54518727364, 91199.0, 91235.0, 15645.139517326656, 16141.59646008859, 16294.674106517567, 16318.0, 16343.182840857671, 17638.294468221127, 18123.36568220106, 18231.726524102414, 18236.17937192803, 18273.90863045676, 12319.322437266037, 14707.92646036763, 14738.55784326472, 14825.481108161162, 14895.85104148988, 12837.10140290208, 13011.739765204833, 13049.412776425273, 13205.669858253235, 13299.734005320033, 14874.553763387115, 14961.700765984837, 14977.951543392293, 15096.471623493126, 15180.620484487976, 15495.380184792482, 15805.209739513208, 15882.967607105502, 15981.44064957721, 15991.68016639684, 14377.353019114375, 14609.415623374945, 14692.971491995459, 14729.852701473277, 14730.77903831422], "value": 12461.053806418653, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1353.4721458631132, 1398.524501669432, 1413.0391333892962, 1426.2512181104912, 1432.6203556057644, 9256.814863702723, 11707.0, 12109.333986630732, 12131.514739410424, 12453.003759699224, 11988.520459181644, 12193.0, 12198.390080495961, 12200.389980500962, 12225.877741222585, 2530.2029860593852, 2587.4954383895147, 2589.2879458148973, 2591.261490475215, 2591.520568694788, 2480.6899137607797, 2514.182890560566, 2515.0819950717946, 2519.937001574966, 2521.9117330893423, 2436.122995721548, 2461.237016524883, 2467.8889449974704, 2469.2098528470897, 2470.8146888983397, 2417.8186636002283, 2446.54738873309, 2456.1280745335507, 2460.987695061513, 2462.5936720441196, 2407.807375409963, 2426.466177440978, 2440.3771491958005, 2453.7914277286272, 2520.8991640334366, 11756.0, 11851.111166662535, 11869.169158158933, 11872.287662740264, 11889.583864564698, 6055.364186760415, 6702.0, 6704.463642908556, 6707.228668703113, 6722.058911752359, 4020.9396859047138, 4186.1418409226035, 4261.74429534229, 4264.808083636244, 4278.679099067551, 1055.5513906589745, 1238.2570457725383, 1240.665020444475, 1261.9558315459008, 1265.5254279645133, 15561.377544898303, 15948.760768588596, 16576.0, 16577.751333730004, 16687.666246675, 5433.157860531624, 5448.42791506894, 5587.38538760736, 5662.66024038559, 5665.206871038058, 11849.407529623484, 11861.5848445304, 11875.821862672066, 11878.584249551224, 11905.404729763477, 5634.971825140848, 6041.848953776191, 6438.678066096676, 6481.578697384655, 6556.967215163893, 6068.6358818471035, 6183.659898705524, 6200.348963358872, 6214.844628884314, 6263.937360626426, 3976.940345894814, 4184.8744537663915, 4339.717918335298, 4399.49405818335, 4472.217361961643, 1155.9711007224719, 1168.9596259329203, 1255.2468518888777, 1258.389681004712, 1278.8465384153776, 746.1568427676691, 776.1656219563991, 793.4208028139491, 796.2037962037962, 797.2545669798757, 6756.121704178524, 6964.955256711533, 7072.165463492639, 7078.539894906815, 7164.283571642898, 897.9102089790969, 933.9906600934039, 945.6595625574788, 946.4274114160971, 947.4078700811992, 3941.1329507508303, 4335.978320108442, 4415.757133357632, 4536.432945881765, 4594.5864872161665, 14972.850271497146, 15160.241987900781, 15418.691626167414, 15446.691066178613, 15585.220738963006, 12325.938370308091, 14679.412823487155, 15381.307841147176, 15427.5371738848, 15569.844301557067, 8598.312135029182, 8764.079771623889, 8784.472931623995, 8817.515036673045, 8818.839384350875, 6237.844053898689, 6975.686094125878, 7096.290370962863, 7272.672729727069, 7395.963020184865, 12989.610311690665, 13043.869561304646, 13082.542111026069, 13366.665833354244, 13370.732585348043, 13153.342332883509, 13225.47098116103, 13338.399772010476, 13450.192988420335, 13515.256660883746, 9440.33917625787, 9580.66467673628, 9622.759431014001, 9627.951860240653, 9632.0, 10694.091002264895, 10715.821259661276, 10804.945975270073, 10829.783404332027, 10874.94562527214, 9142.862857057282, 9144.405613635092, 9268.907310926805, 9273.582688778886, 9289.953550232205, 9031.50326732036, 9041.508151154985, 9143.542822858963, 9186.127317904788, 9200.539973001458, 8156.143604921398, 8275.83448331042, 8323.41736078493, 8334.291585215324, 8350.373721970755, 5475.589330800126, 5881.882362352643, 6017.458428741435, 6058.5758996871555, 6224.875502490016, 5411.972940135274, 6085.269767627913, 6247.31279559239, 6267.717952692231, 6318.27339855904], "value": 5396.80338174246, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.91993761372487, 384.2142817937318, 395.4720448201651, 405.50123348281596, 410.19396885120733, 6332.176817013793, 6503.739850405984, 6546.247181574115, 6547.773756922639, 6599.6370199639005, 5190.948090519094, 6242.812715618538, 6387.584806987543, 6387.90418143727, 6389.233292004954, 4579.816807327719, 4624.260118381059, 4634.72191668501, 4653.464851542065, 4672.135654903836, 4575.611073058794, 4649.51180126087, 4668.008852431173, 4669.953300466986, 4681.438227412716, 4088.693347998897, 4615.538446155391, 4616.0, 4620.676552641316, 4622.699524530912, 4336.3278691802825, 4622.907541849144, 4631.096936097462, 4639.095376401603, 4658.673892827504, 3628.727845411605, 3941.192055628589, 3949.2299001694682, 3960.108975480494, 3970.7617542947514, 5201.765920533585, 5407.945920540823, 6279.968600157015, 6316.263027667383, 6322.936770632281, 4653.767311634439, 5504.091824848895, 5515.179990603091, 5526.889462210734, 5541.612087153901, 5501.449855014466, 6083.148359229712, 6806.285340039274, 6897.965510172417, 6934.237233904267, 4096.0, 4101.52832424272, 4462.397576327219, 4464.8883777905485, 4486.791943760194, 6750.966245168841, 6933.653317334113, 7098.0, 7195.071376107383, 7338.889916651201, 3750.549934007882, 3907.765534067965, 4209.810898332911, 4236.533981262059, 4499.392582001454, 4577.382053422745, 4586.312053191981, 4595.195840727859, 4602.861914142581, 4607.101615185074, 4745.83389581363, 5280.445553216857, 5376.462353764592, 5472.480114389126, 5476.424975377608, 6746.156730408699, 6753.966230168916, 6829.897551536731, 6853.339728984339, 6878.759243426556, 1351.7364113997676, 1534.9386024558892, 1552.472159465787, 1560.2510794818324, 1563.2730780187267, 993.8409854423257, 1017.4658304390107, 1023.2223510132353, 1024.5184763161303, 1029.4389557691077, 5358.410574836765, 6367.968160159169, 6446.290908000116, 6461.7738379157445, 6474.902876456857, 7141.250168732312, 7147.499675022803, 7155.320244576861, 7186.6047367395295, 7193.424526037905, 5443.129099344086, 6370.77702280418, 6431.678416079178, 6444.80665580024, 6461.289258181596, 3229.951550726741, 3248.456982933088, 3254.772165948361, 3485.7908525488047, 3713.535808023997, 7302.744403945836, 7333.863251196073, 7342.229065947999, 7356.705731770669, 7367.963160184165, 6005.6396616203165, 7279.216591934904, 7299.649564830524, 7309.597972111585, 7327.377172940253, 6777.49168812341, 7247.637618119073, 7282.198958114604, 7284.380827629605, 7348.228436014356, 6218.782342617987, 6791.694373753292, 7152.033190872552, 7160.355567998905, 7165.892511612435, 6648.634325112165, 6994.930050699428, 7023.508354415142, 7031.437485001085, 7033.472489563201, 6692.063111164538, 7008.649567521502, 7333.193348731742, 7372.668229929559, 7373.5575865448245, 3948.874570747323, 4015.036391266017, 4472.619827314715, 4485.730856148642, 4520.88697782558, 6815.182178138655, 6957.339052789976, 7124.430045596444, 7179.210286868545, 7200.351968322876, 6029.698515074317, 6786.321367863175, 6822.420094291842, 6833.077534532775, 6879.6560171992205, 5728.885422291614, 6020.819375418744, 6772.695228714818, 6850.143732033496, 6867.622280774605, 5546.694931778791, 6671.366220209073, 6688.1909597968, 6695.0, 6713.261541230363, 5729.627574207663, 6837.3162683731225, 6838.008488769001, 6839.794806155824, 6849.801284775244, 5505.284313039279, 6584.308647592125, 6658.7336506539195, 6676.165479315086, 6686.297938716365], "value": 4909.588839195786, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [795.1293333799495, 813.6745301879243, 825.4139560911752, 840.1556435782038, 842.3513894301389, 13507.864921350783, 14060.64848378791, 14719.0, 15263.313150908209, 16969.575760605992, 15995.200239988013, 17870.01714905683, 17895.28418863244, 17928.37250696226, 17992.280308787635, 973.5035132082636, 983.9311248212592, 998.1765043838826, 998.9860291803825, 1002.5087707023579, 1007.2143727892255, 1007.7984403119369, 1008.2186305613135, 1009.6112996496329, 1012.2458768217688, 948.7628092976756, 1034.7413146713322, 1036.896310368961, 1037.5486663301488, 1055.519738518972, 1030.654730665228, 1031.4378663628265, 1038.7247379444452, 1052.884182739902, 1053.394298278489, 743.3124359967063, 744.1107876088076, 745.3813334932048, 747.5589402252642, 766.4481573267238, 17178.484645460656, 17451.73822392665, 17512.299508019663, 17581.7362739559, 18104.90947545254, 17753.644927101384, 17787.73318400225, 17900.55248618782, 18237.817621823746, 18253.726194107097, 12753.61739147827, 13949.0, 14890.925545372313, 14961.551153465414, 14966.17686027279, 3470.8091054991964, 3587.264610754789, 3588.4796704477703, 3614.493970844084, 3618.2763447310645, 144653.27673361672, 148393.0, 148558.51441485554, 149156.0, 149242.50757492395, 13521.59435216945, 13621.931890340684, 13697.863021369658, 13705.45178192881, 13832.30838458073, 26423.603645945324, 26640.333991650365, 26921.0, 26940.461190776074, 27090.86454567754, 6559.44244739193, 6778.491613129035, 7395.963020184865, 7701.3068823806125, 7796.883046754303, 3674.761140525857, 3752.75607085544, 3757.5678796938155, 3759.793211373401, 3759.9436008459893, 4147.958520414757, 4207.474065741782, 4214.936775948363, 4246.447961765012, 4246.4691913510815, 821.7263241974915, 898.8606765951288, 923.4320892650971, 931.6878845586668, 935.0742764662934, 638.8498702804837, 653.8430776613578, 655.2791928878196, 661.6460193796296, 674.9291324410964, 9724.756881077888, 9939.65211217604, 11664.6500604982, 12196.207246528948, 12210.938945305394, 622.6793201501231, 633.6894921488438, 639.9840003999844, 641.1952998986243, 664.0802488553388, 5603.8666580386935, 5682.431756824284, 5736.856578585569, 5746.597738158289, 5752.482276595043, 119846.60306793815, 123941.0, 143223.0, 143423.0, 145950.0, 124461.0, 126922.0, 127796.45126220556, 129976.0, 130454.0, 107495.0, 107692.0, 107809.0, 107937.0, 109405.0, 10873.891261087289, 11087.889121108685, 11125.554977800797, 11154.219204655596, 11318.377489238172, 39478.802605986784, 45987.0, 46337.76831115823, 46434.0, 47387.052258955315, 80092.59953700428, 82860.0, 85396.0, 85811.57094214739, 89164.5541772313, 17026.74459883078, 17190.914045429694, 17803.46589602314, 17836.643267134325, 18053.0, 17233.482995510156, 19183.8081619182, 19195.0, 19327.226910923968, 19509.02454877222, 16563.83436165623, 16569.25438355301, 17530.824691752918, 17591.736123957893, 17616.471505854846, 17400.955942643483, 17407.303707851544, 18121.728174077136, 18143.911365318123, 18456.907715461337, 16585.004899706055, 16688.749668755223, 16726.498205053867, 16800.663986719952, 16876.0, 9875.753106172117, 10583.788324233627, 10845.945770271099, 11762.117841162119, 12321.876781232431, 12605.93697031509, 13185.73628527405, 13341.466341346237, 13602.86397136016, 14016.929915350358], "value": 8756.839542274147, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1537.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 114.0, 113.0, 113.0, 127.0, 170.0, 162.0, 114.0, 113.0, 110.0], "value": 123.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 113.0, 109.0, 142.0, 108.0, 113.0, 110.0, 115.0, 137.0, 112.0], "value": 119.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [203.0, 200.0, 199.0, 228.0, 244.0, 241.0, 201.0, 187.0, 266.0, 281.0], "value": 225.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 59.0, 87.0, 61.0, 59.0, 59.0, 62.0, 82.0, 65.0], "value": 67.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 70.0, 83.0, 72.0, 79.0, 87.0, 87.0, 72.0, 71.0, 89.0], "value": 78.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 88.0, 88.0, 89.0, 88.0, 89.0, 97.0, 88.0, 88.0], "value": 89.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 118.0, 97.0, 97.0, 125.0, 108.0, 98.0, 98.0, 98.0, 98.0], "value": 109.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 92.0, 91.0, 92.0, 95.0, 96.0, 88.0, 93.0, 92.0, 89.0], "value": 91.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 49.0, 51.0, 52.0, 50.0, 50.0, 51.0, 52.0, 50.0, 52.0], "value": 50.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 58.0, 74.0, 88.0, 92.0, 84.0, 49.0, 82.0, 82.0], "value": 73.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 88.0, 87.0, 85.0, 89.0, 90.0, 92.0, 127.0, 95.0], "value": 93.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 158.0, 127.0, 137.0, 157.0, 148.0, 151.0, 163.0, 160.0, 145.0], "value": 148.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 178.0, 186.0, 186.0, 186.0, 181.0, 249.0, 231.0, 187.0, 219.0], "value": 198.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 65.0, 67.0, 68.0, 66.0, 69.0, 65.0, 70.0, 69.0], "value": 67.5, "unit": "score"}]}]} 19:09:33 INFO - Return code: 0 19:09:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:09:33 INFO - # TBPL SUCCESS # 19:09:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:09:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:09:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:09:33 INFO - 2017-05-30 19:09:33.475 screenresolution[2826:42399] starting screenresolution argv=screenresolution get 19:09:33 INFO - 2017-05-30 19:09:33.478 screenresolution[2826:42399] Display 0: 1600x1200x32@60 19:09:33 INFO - 2017-05-30 19:09:33.484 screenresolution[2827:42402] starting screenresolution argv=screenresolution list 19:09:33 INFO - Available Modes on Display 0 19:09:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:09:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:09:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:09:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:09:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:09:33 INFO - Intel Iris: 19:09:33 INFO - Chipset Model: Intel Iris 19:09:33 INFO - Type: GPU 19:09:33 INFO - Bus: Built-In 19:09:33 INFO - VRAM (Dynamic, Max): 1536 MB 19:09:33 INFO - Vendor: Intel (0x8086) 19:09:33 INFO - Device ID: 0x0a2e 19:09:33 INFO - Revision ID: 0x0009 19:09:33 INFO - Displays: 19:09:33 INFO - Display: 19:09:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:09:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:09:33 INFO - Display Serial Number: 1600x1200 60 19:09:33 INFO - Main Display: Yes 19:09:33 INFO - Mirror: Off 19:09:33 INFO - Online: Yes 19:09:33 INFO - Rotation: Supported 19:09:33 INFO - Return code: 0 19:09:33 INFO - Running post-action listener: _resource_record_post_action 19:09:33 INFO - [mozharness: 2017-05-31 02:09:33.721549Z] Finished run-tests step (success) 19:09:33 INFO - Running post-run listener: _resource_record_post_run 19:09:33 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 528896; Write bytes: 568224768; Read time: 4; Write time: 1833 19:09:33 INFO - TinderboxPrint: CPU usage
53.3% 19:09:33 INFO - TinderboxPrint: I/O read bytes / time
528,896 / 4 19:09:33 INFO - TinderboxPrint: I/O write bytes / time
568,224,768 / 1,833 19:09:33 INFO - TinderboxPrint: CPU idle
2,349.8 (46.7%) 19:09:33 INFO - TinderboxPrint: CPU system
1,019.9 (20.3%) 19:09:33 INFO - TinderboxPrint: CPU user
1,660.0 (33.0%) 19:09:33 INFO - TinderboxPrint: Swap in / out
245,747,712 / 0 19:09:33 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 229628416; Write bytes: 228487168; Read time: 16686; Write time: 426 19:09:33 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 19:09:33 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 528384; Write bytes: 319603712; Read time: 4; Write time: 1325 19:09:34 INFO - Running post-run listener: _upload_blobber_files 19:09:34 INFO - Blob upload gear active. 19:09:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:09:34 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 19:09:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:09:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:09:34 INFO - (blobuploader) - INFO - Open directory for files ... 19:09:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:09:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:09:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:09:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:09:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:09:35 INFO - (blobuploader) - INFO - Done attempting. 19:09:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:09:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:09:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:09:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:09:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:09:36 INFO - (blobuploader) - INFO - Done attempting. 19:09:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:09:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:09:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:09:37 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:09:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:09:37 INFO - (blobuploader) - INFO - Done attempting. 19:09:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:09:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:09:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:09:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:09:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:09:40 INFO - (blobuploader) - INFO - Done attempting. 19:09:40 INFO - (blobuploader) - INFO - Iteration through files over. 19:09:40 INFO - Return code: 0 19:09:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:09:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:09:40 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d15c82e77d510ecddd7aba5c0a637f1ebc2077c17220e63225da11cea82927224c5de6c5e397cdc84ee8df783ad56d98bb7d9c824a8e123ac94d4800a38bf033", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/05807bca45638ed81df32a8135506d670cd0ba2a29cdcf4868a6d84685b53436d67fc5686fc02ee102102f0d2d1a18914441e3f670d62a82da41d3a9cef2a249", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db27621368d4428521ee88c39326712da249b2ad447870e4958730258cdc5aece4240af521a9efdf9839dfa56c923f5d499f47afd8c85a7fda9bcbcaec6f80b6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/07fbe99a3ddad2aebfb4a6556f80cd86178f39099d45b5e04b364cdf9340335c40288e0312bd5cf6557a45b957db3cf694dd01cc2f9ab9e4f793b4f2ceda6dfd"} 19:09:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:09:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:09:40 INFO - Contents: 19:09:40 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d15c82e77d510ecddd7aba5c0a637f1ebc2077c17220e63225da11cea82927224c5de6c5e397cdc84ee8df783ad56d98bb7d9c824a8e123ac94d4800a38bf033", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/05807bca45638ed81df32a8135506d670cd0ba2a29cdcf4868a6d84685b53436d67fc5686fc02ee102102f0d2d1a18914441e3f670d62a82da41d3a9cef2a249", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db27621368d4428521ee88c39326712da249b2ad447870e4958730258cdc5aece4240af521a9efdf9839dfa56c923f5d499f47afd8c85a7fda9bcbcaec6f80b6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/07fbe99a3ddad2aebfb4a6556f80cd86178f39099d45b5e04b364cdf9340335c40288e0312bd5cf6557a45b957db3cf694dd01cc2f9ab9e4f793b4f2ceda6dfd"} 19:09:40 INFO - Running post-run listener: copy_logs_to_upload_dir 19:09:40 INFO - Copying logs to upload dir... 19:09:40 INFO - mkdir: /builds/slave/test/build/upload/logs 19:09:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1339.641410 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-05-30 19:09:40.242578) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-30 19:09:40.246434) ========= 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.fn1suntHu1/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.GODvfpuU6O/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d15c82e77d510ecddd7aba5c0a637f1ebc2077c17220e63225da11cea82927224c5de6c5e397cdc84ee8df783ad56d98bb7d9c824a8e123ac94d4800a38bf033", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/05807bca45638ed81df32a8135506d670cd0ba2a29cdcf4868a6d84685b53436d67fc5686fc02ee102102f0d2d1a18914441e3f670d62a82da41d3a9cef2a249", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db27621368d4428521ee88c39326712da249b2ad447870e4958730258cdc5aece4240af521a9efdf9839dfa56c923f5d499f47afd8c85a7fda9bcbcaec6f80b6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/07fbe99a3ddad2aebfb4a6556f80cd86178f39099d45b5e04b364cdf9340335c40288e0312bd5cf6557a45b957db3cf694dd01cc2f9ab9e4f793b4f2ceda6dfd"} build_url:https://queue.taskcluster.net/v1/task/EAsbH3b1T7KMQ5aG8m2ioQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011128 build_url: 'https://queue.taskcluster.net/v1/task/EAsbH3b1T7KMQ5aG8m2ioQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d15c82e77d510ecddd7aba5c0a637f1ebc2077c17220e63225da11cea82927224c5de6c5e397cdc84ee8df783ad56d98bb7d9c824a8e123ac94d4800a38bf033", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/05807bca45638ed81df32a8135506d670cd0ba2a29cdcf4868a6d84685b53436d67fc5686fc02ee102102f0d2d1a18914441e3f670d62a82da41d3a9cef2a249", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db27621368d4428521ee88c39326712da249b2ad447870e4958730258cdc5aece4240af521a9efdf9839dfa56c923f5d499f47afd8c85a7fda9bcbcaec6f80b6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/07fbe99a3ddad2aebfb4a6556f80cd86178f39099d45b5e04b364cdf9340335c40288e0312bd5cf6557a45b957db3cf694dd01cc2f9ab9e4f793b4f2ceda6dfd"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-30 19:09:40.279327) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 19:09:40.279639) ========= 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.fn1suntHu1/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.GODvfpuU6O/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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.005300 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 19:09:40.327684) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-30 19:09:40.328000) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-30 19:09:40.328315) ========= ========= Total master_lag: 2.06 =========