builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0226 starttime: 1496603843.78 results: success (0) revision: 8a3aa1701537ea6b8334f432cd030d260d492fa3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-04 12:17:23.778807) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-04 12:17:23.779332) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-04 12:17:23.779644) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-04 12:17:23.832093) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-04 12:17:23.832510) ========= 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.qsywQHYM7A/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.BNOzEIWKIG/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-04 12:17:23-- 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% 18.6M=0.001s 2017-06-04 12:17:24 (18.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.165672 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-04 12:17:24.025911) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-04 12:17:24.026294) ========= 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.qsywQHYM7A/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.BNOzEIWKIG/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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.034157 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-04 12:17:24.082485) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-04 12:17:24.082834) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8a3aa1701537ea6b8334f432cd030d260d492fa3 --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 8a3aa1701537ea6b8334f432cd030d260d492fa3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qsywQHYM7A/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.BNOzEIWKIG/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-04 12:17:24,180 truncating revision to first 12 chars 2017-06-04 12:17:24,180 Setting DEBUG logging. 2017-06-04 12:17:24,180 attempt 1/10 2017-06-04 12:17:24,180 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8a3aa1701537?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-04 12:17:24,874 unpacking tar archive at: mozilla-central-8a3aa1701537/testing/mozharness/ program finished with exit code 0 elapsedTime=0.969258 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-04 12:17:25.074334) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-04 12:17:25.074649) ========= script_repo_revision: 8a3aa1701537ea6b8334f432cd030d260d492fa3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-04 12:17:25.075032) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-04 12:17:25.075329) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-04 12:17:25.113792) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 20 secs) (at 2017-06-04 12:17:25.114218) ========= /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.qsywQHYM7A/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.BNOzEIWKIG/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 12:17:25 INFO - MultiFileLogger online at 20170604 12:17:25 in /builds/slave/test 12:17:25 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 12:17:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:17:25 INFO - {'append_to_log': False, 12:17:25 INFO - 'base_work_dir': '/builds/slave/test', 12:17:25 INFO - 'blob_upload_branch': 'Firefox', 12:17:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:17:25 INFO - 'branch': 'Firefox', 12:17:25 INFO - 'buildbot_json_path': 'buildprops.json', 12:17:25 INFO - 'config_files': ('talos/mac_config.py',), 12:17:25 INFO - 'default_actions': ('clobber', 12:17:25 INFO - 'read-buildbot-config', 12:17:25 INFO - 'download-and-extract', 12:17:25 INFO - 'populate-webroot', 12:17:25 INFO - 'create-virtualenv', 12:17:25 INFO - 'install', 12:17:25 INFO - 'setup-mitmproxy', 12:17:25 INFO - 'run-tests'), 12:17:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:17:25 INFO - 'download_minidump_stackwalk': True, 12:17:25 INFO - 'download_symbols': 'ondemand', 12:17:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:17:25 INFO - 'tooltool.py': '/tools/tooltool.py', 12:17:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:17:25 INFO - '/tools/misc-python/virtualenv.py')}, 12:17:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:17:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:17:25 INFO - 'gecko_profile': False, 12:17:25 INFO - 'gecko_profile_interval': 0, 12:17:25 INFO - 'installer_path': 'installer.exe', 12:17:25 INFO - 'log_level': 'info', 12:17:25 INFO - 'log_name': 'talos', 12:17:25 INFO - 'log_to_console': True, 12:17:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:17:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:17:25 INFO - 'opt_config_files': (), 12:17:25 INFO - 'pip_index': False, 12:17:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:17:25 INFO - 'cmd': ('bash', 12:17:25 INFO - '-c', 12:17:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:17:25 INFO - 'enabled': True, 12:17:25 INFO - 'halt_on_failure': False, 12:17:25 INFO - 'name': 'check_screen_resolution'},), 12:17:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:17:25 INFO - 'cmd': ('bash', 12:17:25 INFO - '-c', 12:17:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:17:25 INFO - 'enabled': True, 12:17:25 INFO - 'halt_on_failure': False, 12:17:25 INFO - 'name': 'check_screen_resolution'},), 12:17:25 INFO - 'run_cmd_checks_enabled': True, 12:17:25 INFO - 'suite': 'dromaeojs-e10s', 12:17:25 INFO - 'system_bits': '32', 12:17:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:17:25 INFO - 'title': 't-yosemite-r7-0226', 12:17:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:17:25 INFO - 'use_talos_json': True, 12:17:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:17:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:17:25 INFO - 'work_dir': 'build'} 12:17:25 INFO - [mozharness: 2017-06-04 19:17:25.320106Z] Running clobber step. 12:17:25 INFO - Running pre-action listener: _resource_record_pre_action 12:17:25 INFO - Running main action method: clobber 12:17:25 INFO - rmtree: /builds/slave/test/build 12:17:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:17:27 INFO - Running post-action listener: _resource_record_post_action 12:17:27 INFO - [mozharness: 2017-06-04 19:17:27.891536Z] Finished clobber step (success) 12:17:27 INFO - [mozharness: 2017-06-04 19:17:27.891664Z] Running read-buildbot-config step. 12:17:27 INFO - Running pre-action listener: _resource_record_pre_action 12:17:27 INFO - Running main action method: read_buildbot_config 12:17:27 INFO - Using buildbot properties: 12:17:27 INFO - { 12:17:27 INFO - "project": "", 12:17:27 INFO - "product": "firefox", 12:17:27 INFO - "who": "archaeopteryx@coole-files.de", 12:17:27 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 12:17:27 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 12:17:27 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 12:17:27 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 12:17:27 INFO - "stage_platform": "macosx64", 12:17:27 INFO - "basedir": "/builds/slave/test", 12:17:27 INFO - "buildnumber": 75, 12:17:27 INFO - "platform": "yosemite_r7", 12:17:27 INFO - "parent_task_id": "GQ0HQlC-RgGQg_O_S0g1GA", 12:17:27 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 12:17:27 INFO - "slavebuilddir": "test", 12:17:27 INFO - "taskId": "OM8p1DaCTfuVXihUEHjcmA", 12:17:27 INFO - "branch": "mozilla-central", 12:17:27 INFO - "script_repo_revision": "production", 12:17:27 INFO - "revision": "8a3aa1701537ea6b8334f432cd030d260d492fa3", 12:17:27 INFO - "slavename": "t-yosemite-r7-0226", 12:17:27 INFO - "repo_path": "mozilla-central" 12:17:27 INFO - } 12:17:27 INFO - Finding installer, test and symbols from parent task. 12:17:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OM8p1DaCTfuVXihUEHjcmA'}, attempt #1 12:17:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OM8p1DaCTfuVXihUEHjcmA'}, attempt #1 12:17:29 INFO - Task dependencies: eXh_NgiwS_icV2XJkDW7Kw GQ0HQlC-RgGQg_O_S0g1GA 12:17:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GQ0HQlC-RgGQg_O_S0g1GA'}, attempt #1 12:17:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/GQ0HQlC-RgGQg_O_S0g1GA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:17:29 INFO - Set test_url: https://queue.taskcluster.net/v1/task/GQ0HQlC-RgGQg_O_S0g1GA/artifacts/public/build/test_packages.json 12:17:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/GQ0HQlC-RgGQg_O_S0g1GA/artifacts/public/build/target.crashreporter-symbols.zip 12:17:29 INFO - Running post-action listener: _resource_record_post_action 12:17:29 INFO - [mozharness: 2017-06-04 19:17:29.737049Z] Finished read-buildbot-config step (success) 12:17:29 INFO - [mozharness: 2017-06-04 19:17:29.737165Z] Running download-and-extract step. 12:17:29 INFO - Running pre-action listener: _resource_record_pre_action 12:17:29 INFO - Running main action method: download_and_extract 12:17:29 INFO - mkdir: /builds/slave/test/build/tests 12:17: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')]} 12:17:29 INFO - trying https://queue.taskcluster.net/v1/task/GQ0HQlC-RgGQg_O_S0g1GA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 12:17:29 INFO - Downloading https://queue.taskcluster.net/v1/task/GQ0HQlC-RgGQg_O_S0g1GA/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 12:17:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GQ0HQlC-RgGQg_O_S0g1GA/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 12:17:30 INFO - Downloaded 1328 bytes. 12:17:30 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 12:17:30 INFO - Using the following test package requirements: 12:17:30 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:17:30 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 12:17:30 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 12:17:30 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:17:30 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 12:17:30 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:17:30 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 12:17:30 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:17:30 INFO - u'jsshell-mac.zip'], 12:17:30 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:17:30 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 12:17:30 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 12:17:30 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:17:30 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 12:17:30 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:17:30 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 12:17:30 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:17:30 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 12:17:30 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:17:30 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 12:17:30 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:17:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GQ0HQlC-RgGQg_O_S0g1GA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 12:17:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GQ0HQlC-RgGQg_O_S0g1GA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:17:30 INFO - Fetch https://queue.taskcluster.net/v1/task/GQ0HQlC-RgGQg_O_S0g1GA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 12:17:32 INFO - Content-Length response header: 57631567 12:17:32 INFO - Bytes received: 57631567 12:17:50 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 12:17:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GQ0HQlC-RgGQg_O_S0g1GA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 12:17:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GQ0HQlC-RgGQg_O_S0g1GA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:17:50 INFO - Fetch https://queue.taskcluster.net/v1/task/GQ0HQlC-RgGQg_O_S0g1GA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 12:17:52 INFO - Content-Length response header: 57631567 12:17:52 INFO - Bytes received: 57631567 12:18:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GQ0HQlC-RgGQg_O_S0g1GA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 12:18:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GQ0HQlC-RgGQg_O_S0g1GA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:18:10 INFO - Fetch https://queue.taskcluster.net/v1/task/GQ0HQlC-RgGQg_O_S0g1GA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 12:18:11 INFO - Content-Length response header: 14447425 12:18:11 INFO - Bytes received: 14447425 12:18:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:11 INFO - trying https://queue.taskcluster.net/v1/task/GQ0HQlC-RgGQg_O_S0g1GA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:18:11 INFO - Downloading https://queue.taskcluster.net/v1/task/GQ0HQlC-RgGQg_O_S0g1GA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 12:18:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GQ0HQlC-RgGQg_O_S0g1GA/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 12:18:13 INFO - Downloaded 67785234 bytes. 12:18:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GQ0HQlC-RgGQg_O_S0g1GA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:18:13 INFO - mkdir: /builds/slave/test/properties 12:18:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:18:13 INFO - Writing to file /builds/slave/test/properties/build_url 12:18:13 INFO - Contents: 12:18:13 INFO - build_url:https://queue.taskcluster.net/v1/task/GQ0HQlC-RgGQg_O_S0g1GA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:18:13 INFO - Running post-action listener: _resource_record_post_action 12:18:13 INFO - Running post-action listener: set_extra_try_arguments 12:18:13 INFO - [mozharness: 2017-06-04 19:18:13.838118Z] Finished download-and-extract step (success) 12:18:13 INFO - [mozharness: 2017-06-04 19:18:13.838217Z] Running populate-webroot step. 12:18:13 INFO - Running pre-action listener: _resource_record_pre_action 12:18:13 INFO - Running main action method: populate_webroot 12:18:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:18:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:18:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:18:13 INFO - '--tppagecycles', 12:18:13 INFO - '7'], 12:18:13 INFO - 'tests': ['tsvgm']}}, 12:18:13 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:18:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:18:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:18:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:18:13 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:18:13 INFO - 'talos_options': ['--disable-e10s'], 12:18:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:18:13 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:18:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:18:13 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:18:13 INFO - 'talos_options': ['--disable-e10s'], 12:18:13 INFO - 'tests': ['damp', 'tps']}, 12:18:13 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:18:13 INFO - 'tests': ['damp', 'tps']}, 12:18:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:18:13 INFO - 'tests': ['dromaeo_dom']}, 12:18:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:18:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:18:13 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 12:18:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:18:13 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 12:18:13 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 12:18:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:18:13 INFO - 'tests': ['a11yr', 12:18:13 INFO - 'ts_paint', 12:18:13 INFO - 'tpaint', 12:18:13 INFO - 'sessionrestore', 12:18:13 INFO - 'sessionrestore_no_auto_restore', 12:18:13 INFO - 'tabpaint']}, 12:18:13 INFO - 'other-e10s': {'tests': ['a11yr', 12:18:13 INFO - 'ts_paint', 12:18:13 INFO - 'tpaint', 12:18:13 INFO - 'sessionrestore', 12:18:13 INFO - 'sessionrestore_no_auto_restore', 12:18:13 INFO - 'tabpaint']}, 12:18:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:18:13 INFO - 'ts_paint', 12:18:13 INFO - 'tpaint', 12:18:13 INFO - 'sessionrestore', 12:18:13 INFO - 'sessionrestore_no_auto_restore', 12:18:13 INFO - 'tabpaint']}, 12:18:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:18:13 INFO - 'ts_paint', 12:18:13 INFO - 'tpaint', 12:18:13 INFO - 'sessionrestore', 12:18:13 INFO - 'sessionrestore_no_auto_restore', 12:18:13 INFO - 'tabpaint']}, 12:18:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:18:13 INFO - 'tests': ['a11yr', 12:18:13 INFO - 'ts_paint', 12:18:13 INFO - 'tpaint', 12:18:13 INFO - 'sessionrestore', 12:18:13 INFO - 'sessionrestore_no_auto_restore', 12:18:13 INFO - 'tabpaint']}, 12:18:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:18:13 INFO - 'tests': ['a11yr', 12:18:13 INFO - 'ts_paint', 12:18:13 INFO - 'tpaint', 12:18:13 INFO - 'sessionrestore', 12:18:13 INFO - 'sessionrestore_no_auto_restore', 12:18:13 INFO - 'tabpaint']}, 12:18:13 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 12:18:13 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 12:18:13 INFO - 'bloom_basic_ref']}, 12:18:13 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 12:18:13 INFO - 'talos_options': ['--mitmproxy', 12:18:13 INFO - 'mitmproxy-recording-1.mp', 12:18:13 INFO - '--firstNonBlankPaint'], 12:18:13 INFO - 'tests': ['Quantum_1']}, 12:18:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:18:13 INFO - 'tests': ['tsvgx', 12:18:13 INFO - 'tsvgr_opacity', 12:18:13 INFO - 'tart', 12:18:13 INFO - 'tscrollx', 12:18:13 INFO - 'cart', 12:18:13 INFO - 'tsvg_static']}, 12:18:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:18:13 INFO - 'tsvgr_opacity', 12:18:13 INFO - 'tart', 12:18:13 INFO - 'tscrollx', 12:18:13 INFO - 'cart', 12:18:13 INFO - 'tsvg_static']}, 12:18:13 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:18:13 INFO - 'talos_options': ['--disable-e10s'], 12:18:13 INFO - 'tests': ['tp5o']}, 12:18:13 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:18:13 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:18:13 INFO - 'talos_options': ['--disable-e10s', 12:18:13 INFO - '--xperf_path', 12:18:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:18:13 INFO - 'tests': ['tp5n']}, 12:18:13 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:18:13 INFO - 'talos_options': ['--xperf_path', 12:18:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:18:13 INFO - 'tests': ['tp5n']}}, 12:18:13 INFO - 'talos.zip': {'path': '', 12:18:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:18:13 INFO - Running post-action listener: _resource_record_post_action 12:18:13 INFO - [mozharness: 2017-06-04 19:18:13.859410Z] Finished populate-webroot step (success) 12:18:13 INFO - [mozharness: 2017-06-04 19:18:13.859529Z] Running create-virtualenv step. 12:18:13 INFO - Running pre-action listener: _resource_record_pre_action 12:18:13 INFO - Running main action method: create_virtualenv 12:18:13 INFO - Creating virtualenv /builds/slave/test/build/venv 12:18:13 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 12:18:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:18:13 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:18:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:18:13 INFO - Using real prefix '/tools/python27' 12:18:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:18:14 INFO - Installing distribute.............................................................................................................................................................................................done. 12:18:17 INFO - Installing pip.................done. 12:18:17 INFO - Return code: 0 12:18:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:18:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:18:17 INFO - Reading from file tmpfile_stdout 12:18:17 INFO - Output received: 12:18:17 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) 12:18:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:18:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:17 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 0x10565ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105662768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8041c77e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055e7c60>, '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 0x10537ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053a5be8>, '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.qsywQHYM7A/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.BNOzEIWKIG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 12:18:17 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 12:18:17 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 12:18:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qsywQHYM7A/Render', 12:18:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:18:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:18:17 INFO - 'HOME': '/Users/cltbld', 12:18:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:18:17 INFO - 'LOGNAME': 'cltbld', 12:18:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:17 INFO - 'MOZ_NO_REMOTE': '1', 12:18:17 INFO - 'NO_EM_RESTART': '1', 12:18:17 INFO - 'PAGER': '/bin/cat', 12:18:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:18:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:17 INFO - 'PWD': '/builds/slave/test', 12:18:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:18:17 INFO - 'SHELL': '/bin/bash', 12:18:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BNOzEIWKIG/Listeners', 12:18:17 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 12:18:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:18:17 INFO - 'USER': 'cltbld', 12:18:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:18:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:18:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:17 INFO - 'XPC_FLAGS': '0x0', 12:18:17 INFO - 'XPC_SERVICE_NAME': '0', 12:18:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:18:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:18 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 12:18:18 INFO - Cleaning up... 12:18:18 INFO - Return code: 0 12:18:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:18: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')]} 12:18:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18: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')]} 12:18:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18: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', '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 0x10565ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105662768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8041c77e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055e7c60>, '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 0x10537ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053a5be8>, '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.qsywQHYM7A/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.BNOzEIWKIG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 12:18: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', 'psutil>=3.1.1'] in /builds/slave/test/build 12:18: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 psutil>=3.1.1 12:18:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qsywQHYM7A/Render', 12:18:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:18:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:18:18 INFO - 'HOME': '/Users/cltbld', 12:18:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:18:18 INFO - 'LOGNAME': 'cltbld', 12:18:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:18 INFO - 'MOZ_NO_REMOTE': '1', 12:18:18 INFO - 'NO_EM_RESTART': '1', 12:18:18 INFO - 'PAGER': '/bin/cat', 12:18:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:18:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:18 INFO - 'PWD': '/builds/slave/test', 12:18:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:18:18 INFO - 'SHELL': '/bin/bash', 12:18:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BNOzEIWKIG/Listeners', 12:18:18 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 12:18:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:18:18 INFO - 'USER': 'cltbld', 12:18:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:18:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:18:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:18 INFO - 'XPC_FLAGS': '0x0', 12:18:18 INFO - 'XPC_SERVICE_NAME': '0', 12:18:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:18:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:18 INFO - Downloading/unpacking psutil>=3.1.1 12:18: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 12:18: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 12:18: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 12:18: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 12:18:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:18:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:18:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:18:20 INFO - Installing collected packages: psutil 12:18:20 INFO - Running setup.py install for psutil 12:18:20 INFO - building 'psutil._psutil_osx' extension 12:18:20 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 12:18:21 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 12:18:21 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 12:18:21 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 12:18:21 INFO - building 'psutil._psutil_posix' extension 12:18:21 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 12:18:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:18:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:18:21 INFO - ^ 12:18:21 INFO - 1 warning generated. 12:18:21 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 12:18:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:18:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:18:21 INFO - Successfully installed psutil 12:18:21 INFO - Cleaning up... 12:18:21 INFO - Return code: 0 12:18:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:18: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')]} 12:18:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18: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')]} 12:18:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10565ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105662768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8041c77e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055e7c60>, '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 0x10537ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053a5be8>, '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.qsywQHYM7A/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.BNOzEIWKIG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 12:18: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:18: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 mozsystemmonitor==0.3 12:18:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qsywQHYM7A/Render', 12:18:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:18:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:18:21 INFO - 'HOME': '/Users/cltbld', 12:18:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:18:21 INFO - 'LOGNAME': 'cltbld', 12:18:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:21 INFO - 'MOZ_NO_REMOTE': '1', 12:18:21 INFO - 'NO_EM_RESTART': '1', 12:18:21 INFO - 'PAGER': '/bin/cat', 12:18:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:18:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:21 INFO - 'PWD': '/builds/slave/test', 12:18:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:18:21 INFO - 'SHELL': '/bin/bash', 12:18:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BNOzEIWKIG/Listeners', 12:18:21 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 12:18:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:18:21 INFO - 'USER': 'cltbld', 12:18:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:18:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:18:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:21 INFO - 'XPC_FLAGS': '0x0', 12:18:21 INFO - 'XPC_SERVICE_NAME': '0', 12:18:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:18:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:18: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 12:18: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 12:18: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 12:18: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 12:18:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:18:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:18:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:18:23 INFO - Installing collected packages: mozsystemmonitor 12:18:23 INFO - Running setup.py install for mozsystemmonitor 12:18:23 INFO - Successfully installed mozsystemmonitor 12:18:23 INFO - Cleaning up... 12:18:23 INFO - Return code: 0 12:18:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:18:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10565ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105662768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8041c77e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055e7c60>, '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 0x10537ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053a5be8>, '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.qsywQHYM7A/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.BNOzEIWKIG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 12:18:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:18:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:18:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qsywQHYM7A/Render', 12:18:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:18:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:18:23 INFO - 'HOME': '/Users/cltbld', 12:18:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:18:23 INFO - 'LOGNAME': 'cltbld', 12:18:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:23 INFO - 'MOZ_NO_REMOTE': '1', 12:18:23 INFO - 'NO_EM_RESTART': '1', 12:18:23 INFO - 'PAGER': '/bin/cat', 12:18:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:18:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:23 INFO - 'PWD': '/builds/slave/test', 12:18:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:18:23 INFO - 'SHELL': '/bin/bash', 12:18:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BNOzEIWKIG/Listeners', 12:18:23 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 12:18:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:18:23 INFO - 'USER': 'cltbld', 12:18:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:18:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:18:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:23 INFO - 'XPC_FLAGS': '0x0', 12:18:23 INFO - 'XPC_SERVICE_NAME': '0', 12:18:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:18:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:24 INFO - Downloading/unpacking jsonschema==2.5.1 12:18:24 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 12:18:24 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 12:18:24 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 12:18:24 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 12:18:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:18:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:18:25 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 12:18:25 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 12:18:25 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 12:18:25 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 12:18:25 INFO - Downloading functools32-3.2.3-2.tar.gz 12:18:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:18:25 INFO - warning: no files found matching '*.txt' 12:18:25 INFO - no previously-included directories found matching 'build' 12:18:25 INFO - no previously-included directories found matching 'dist' 12:18:25 INFO - no previously-included directories found matching '.git*' 12:18:25 INFO - Installing collected packages: jsonschema, functools32 12:18:25 INFO - Running setup.py install for functools32 12:18:26 INFO - warning: no files found matching '*.txt' 12:18:26 INFO - no previously-included directories found matching 'build' 12:18:26 INFO - no previously-included directories found matching 'dist' 12:18:26 INFO - no previously-included directories found matching '.git*' 12:18:26 INFO - Successfully installed jsonschema functools32 12:18:26 INFO - Cleaning up... 12:18:26 INFO - Return code: 0 12:18:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:18:26 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')]} 12:18:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:26 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')]} 12:18:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:26 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 0x10565ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105662768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8041c77e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055e7c60>, '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 0x10537ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053a5be8>, '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.qsywQHYM7A/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.BNOzEIWKIG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 12:18:26 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 12:18:26 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 12:18:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qsywQHYM7A/Render', 12:18:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:18:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:18:26 INFO - 'HOME': '/Users/cltbld', 12:18:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:18:26 INFO - 'LOGNAME': 'cltbld', 12:18:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:26 INFO - 'MOZ_NO_REMOTE': '1', 12:18:26 INFO - 'NO_EM_RESTART': '1', 12:18:26 INFO - 'PAGER': '/bin/cat', 12:18:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:18:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:26 INFO - 'PWD': '/builds/slave/test', 12:18:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:18:26 INFO - 'SHELL': '/bin/bash', 12:18:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BNOzEIWKIG/Listeners', 12:18:26 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 12:18:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:18:26 INFO - 'USER': 'cltbld', 12:18:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:18:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:18:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:26 INFO - 'XPC_FLAGS': '0x0', 12:18:26 INFO - 'XPC_SERVICE_NAME': '0', 12:18:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:18:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:18:26 INFO - Cleaning up... 12:18:26 INFO - Return code: 0 12:18:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:18:26 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')]} 12:18:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:26 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')]} 12:18:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:26 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 0x10565ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105662768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8041c77e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055e7c60>, '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 0x10537ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053a5be8>, '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.qsywQHYM7A/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.BNOzEIWKIG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 12:18:26 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 12:18:26 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 12:18:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qsywQHYM7A/Render', 12:18:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:18:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:18:26 INFO - 'HOME': '/Users/cltbld', 12:18:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:18:26 INFO - 'LOGNAME': 'cltbld', 12:18:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:26 INFO - 'MOZ_NO_REMOTE': '1', 12:18:26 INFO - 'NO_EM_RESTART': '1', 12:18:26 INFO - 'PAGER': '/bin/cat', 12:18:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:18:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:26 INFO - 'PWD': '/builds/slave/test', 12:18:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:18:26 INFO - 'SHELL': '/bin/bash', 12:18:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BNOzEIWKIG/Listeners', 12:18:26 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 12:18:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:18:26 INFO - 'USER': 'cltbld', 12:18:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:18:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:18:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:26 INFO - 'XPC_FLAGS': '0x0', 12:18:26 INFO - 'XPC_SERVICE_NAME': '0', 12:18:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:18:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:26 INFO - Downloading/unpacking blobuploader==1.2.4 12:18:26 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 12:18:26 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 12:18:26 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 12:18:26 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 12:18:28 INFO - Downloading blobuploader-1.2.4.tar.gz 12:18:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:18:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:18:28 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 12:18:28 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 12:18:28 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 12:18:28 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 12:18:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:18:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:18:28 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 12:18:28 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 12:18:28 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 12:18:28 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 12:18:28 INFO - Downloading docopt-0.6.1.tar.gz 12:18:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:18:28 INFO - Installing collected packages: blobuploader, requests, docopt 12:18:28 INFO - Running setup.py install for blobuploader 12:18:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:18:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:18:29 INFO - Running setup.py install for requests 12:18:29 INFO - Running setup.py install for docopt 12:18:29 INFO - Successfully installed blobuploader requests docopt 12:18:29 INFO - Cleaning up... 12:18:29 INFO - Return code: 0 12:18:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:18: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')]} 12:18:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18: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')]} 12:18:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:29 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 0x10565ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105662768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8041c77e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055e7c60>, '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 0x10537ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053a5be8>, '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.qsywQHYM7A/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.BNOzEIWKIG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 12:18:29 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 12:18:29 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 12:18:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qsywQHYM7A/Render', 12:18:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:18:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:18:29 INFO - 'HOME': '/Users/cltbld', 12:18:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:18:29 INFO - 'LOGNAME': 'cltbld', 12:18:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:29 INFO - 'MOZ_NO_REMOTE': '1', 12:18:29 INFO - 'NO_EM_RESTART': '1', 12:18:29 INFO - 'PAGER': '/bin/cat', 12:18:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:18:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:29 INFO - 'PWD': '/builds/slave/test', 12:18:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:18:29 INFO - 'SHELL': '/bin/bash', 12:18:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BNOzEIWKIG/Listeners', 12:18:29 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 12:18:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:18:29 INFO - 'USER': 'cltbld', 12:18:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:18:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:18:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:29 INFO - 'XPC_FLAGS': '0x0', 12:18:29 INFO - 'XPC_SERVICE_NAME': '0', 12:18:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:18:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:18:30 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-d4g31D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:18:30 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-fdBlrP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:18:30 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-duAjDu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:18:30 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-uEOQyo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:18:30 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-CIrrxE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:18:30 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-JnEELT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:18:30 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-caCLTf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:18:30 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-ycNXOE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:18:30 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-dboQnE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:18:31 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-NXQAXc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:18:31 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-E5kh_1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:18:31 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-F2ogX4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:18:31 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-gUc4rC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:18:31 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-DGLirm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:18:31 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-jlzJt7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:18:31 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-xDXkFM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:18:31 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-zjMc_l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:18:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:18:31 INFO - Running setup.py install for manifestparser 12:18:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:18:31 INFO - Running setup.py install for mozcrash 12:18:32 INFO - Running setup.py install for mozdebug 12:18:32 INFO - Running setup.py install for mozdevice 12:18:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:18:32 INFO - Running setup.py install for mozfile 12:18:32 INFO - Running setup.py install for mozhttpd 12:18:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:18:32 INFO - Running setup.py install for mozinfo 12:18:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:18:32 INFO - Running setup.py install for mozInstall 12:18:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:18:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:18:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:18:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:18:32 INFO - Running setup.py install for mozleak 12:18:33 INFO - Running setup.py install for mozlog 12:18:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:18:33 INFO - Running setup.py install for moznetwork 12:18:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:18:33 INFO - Running setup.py install for mozprocess 12:18:33 INFO - Running setup.py install for mozprofile 12:18:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:18:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:18:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:18:33 INFO - Running setup.py install for mozrunner 12:18:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:18:33 INFO - Running setup.py install for mozscreenshot 12:18:34 INFO - Running setup.py install for moztest 12:18:34 INFO - Running setup.py install for mozversion 12:18:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:18:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:18:34 INFO - Cleaning up... 12:18:34 INFO - Return code: 0 12:18:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:18:34 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')]} 12:18:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:34 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')]} 12:18:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:34 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 0x10565ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105662768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8041c77e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055e7c60>, '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 0x10537ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053a5be8>, '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.qsywQHYM7A/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.BNOzEIWKIG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 12:18:34 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 12:18:34 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 12:18:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qsywQHYM7A/Render', 12:18:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:18:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:18:34 INFO - 'HOME': '/Users/cltbld', 12:18:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:18:34 INFO - 'LOGNAME': 'cltbld', 12:18:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:34 INFO - 'MOZ_NO_REMOTE': '1', 12:18:34 INFO - 'NO_EM_RESTART': '1', 12:18:34 INFO - 'PAGER': '/bin/cat', 12:18:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:18:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:34 INFO - 'PWD': '/builds/slave/test', 12:18:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:18:34 INFO - 'SHELL': '/bin/bash', 12:18:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BNOzEIWKIG/Listeners', 12:18:34 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 12:18:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:18:34 INFO - 'USER': 'cltbld', 12:18:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:18:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:18:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:34 INFO - 'XPC_FLAGS': '0x0', 12:18:34 INFO - 'XPC_SERVICE_NAME': '0', 12:18:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:18:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:18:34 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-ED5Cxb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:18:34 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)) 12:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:18:34 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-VPoQh0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:18:34 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)) 12:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:18:34 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-1JoXVg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:18:34 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)) 12:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:18:34 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-0z1TS4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:18:35 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)) 12:18:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:18:35 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-o36c08-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:18:35 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)) 12:18:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:18:35 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-bJVMFi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:18:35 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)) 12:18:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:18:35 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-v51qRr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:18:35 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)) 12:18:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:18:35 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-p3tO8k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:18:35 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)) 12:18:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:18:35 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-carisy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:18:35 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)) 12:18:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:18:35 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-s2Vnnc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:18:35 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)) 12:18:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:18:35 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-hBow0Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:18:35 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)) 12:18:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:18:35 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-dU_wIH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:18:35 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)) 12:18:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:18:35 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-Y2sF74-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:18:36 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)) 12:18:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:18:36 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-MrO8pm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:18:36 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)) 12:18:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:18:36 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-Y1qhSO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:18:36 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)) 12:18:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:18:36 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-uGbhQg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:18:36 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)) 12:18:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:18:36 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-6iwMn6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:18:36 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)) 12:18:36 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)) 12:18:36 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)) 12:18:36 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)) 12:18:36 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)) 12:18:36 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)) 12:18:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:18:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:18:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:18:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:18:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:18:37 INFO - Downloading blessings-1.6.tar.gz 12:18:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:18:38 INFO - Installing collected packages: blessings 12:18:38 INFO - Running setup.py install for blessings 12:18:38 INFO - Successfully installed blessings 12:18:38 INFO - Cleaning up... 12:18:38 INFO - Return code: 0 12:18:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:18:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:18:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:18:38 INFO - Reading from file tmpfile_stdout 12:18:38 INFO - Current package versions: 12:18:38 INFO - blessings == 1.6 12:18:38 INFO - blobuploader == 1.2.4 12:18:38 INFO - docopt == 0.6.1 12:18:38 INFO - functools32 == 3.2.3-2 12:18:38 INFO - jsonschema == 2.5.1 12:18:38 INFO - manifestparser == 1.1 12:18:38 INFO - mozInstall == 1.12 12:18:38 INFO - mozcrash == 1.0 12:18:38 INFO - mozdebug == 0.1 12:18:38 INFO - mozdevice == 0.51 12:18:38 INFO - mozfile == 1.2 12:18:38 INFO - mozhttpd == 0.7 12:18:38 INFO - mozinfo == 0.9 12:18:38 INFO - mozleak == 0.1 12:18:38 INFO - mozlog == 3.4 12:18:38 INFO - moznetwork == 0.27 12:18:38 INFO - mozprocess == 0.25 12:18:38 INFO - mozprofile == 0.28 12:18:38 INFO - mozrunner == 6.13 12:18:38 INFO - mozscreenshot == 0.1 12:18:38 INFO - mozsystemmonitor == 0.3 12:18:38 INFO - moztest == 0.8 12:18:38 INFO - mozversion == 1.4 12:18:38 INFO - psutil == 3.1.1 12:18:38 INFO - requests == 1.2.3 12:18:38 INFO - wsgiref == 0.1.2 12:18:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:18:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:38 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 0x10565ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105662768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8041c77e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055e7c60>, '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 0x10537ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053a5be8>, '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.qsywQHYM7A/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.BNOzEIWKIG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 12:18:38 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 12:18:38 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 12:18:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qsywQHYM7A/Render', 12:18:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:18:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:18:38 INFO - 'HOME': '/Users/cltbld', 12:18:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:18:38 INFO - 'LOGNAME': 'cltbld', 12:18:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:38 INFO - 'MOZ_NO_REMOTE': '1', 12:18:38 INFO - 'NO_EM_RESTART': '1', 12:18:38 INFO - 'PAGER': '/bin/cat', 12:18:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:18:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:38 INFO - 'PWD': '/builds/slave/test', 12:18:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:18:38 INFO - 'SHELL': '/bin/bash', 12:18:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BNOzEIWKIG/Listeners', 12:18:38 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 12:18:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:18:38 INFO - 'USER': 'cltbld', 12:18:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:18:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:18:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:38 INFO - 'XPC_FLAGS': '0x0', 12:18:38 INFO - 'XPC_SERVICE_NAME': '0', 12:18:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:18:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:38 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)) 12:18:38 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)) 12:18:38 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)) 12:18:38 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)) 12:18:38 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)) 12:18:38 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)) 12:18:38 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)) 12:18:38 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)) 12:18:38 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)) 12:18:38 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:18:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:18:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:18:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:18:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:18:40 INFO - Downloading marionette_driver-1.4.0.tar.gz 12:18:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 12:18:40 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)) 12:18:40 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)) 12:18:40 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)) 12:18:40 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)) 12:18:40 INFO - Installing collected packages: marionette-driver 12:18:40 INFO - Running setup.py install for marionette-driver 12:18:40 INFO - Successfully installed marionette-driver 12:18:40 INFO - Cleaning up... 12:18:40 INFO - Return code: 0 12:18:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:18:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10565ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105662768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8041c77e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055e7c60>, '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 0x10537ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053a5be8>, '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.qsywQHYM7A/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.BNOzEIWKIG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 12:18:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:18:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:18:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qsywQHYM7A/Render', 12:18:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:18:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:18:40 INFO - 'HOME': '/Users/cltbld', 12:18:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:18:40 INFO - 'LOGNAME': 'cltbld', 12:18:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:40 INFO - 'MOZ_NO_REMOTE': '1', 12:18:40 INFO - 'NO_EM_RESTART': '1', 12:18:40 INFO - 'PAGER': '/bin/cat', 12:18:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:18:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:40 INFO - 'PWD': '/builds/slave/test', 12:18:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:18:40 INFO - 'SHELL': '/bin/bash', 12:18:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BNOzEIWKIG/Listeners', 12:18:40 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 12:18:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:18:40 INFO - 'USER': 'cltbld', 12:18:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:18:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:18:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:40 INFO - 'XPC_FLAGS': '0x0', 12:18:40 INFO - 'XPC_SERVICE_NAME': '0', 12:18:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:18:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:18:40 INFO - Cleaning up... 12:18:40 INFO - Return code: 0 12:18:40 INFO - Running post-action listener: _resource_record_post_action 12:18:40 INFO - Running post-action listener: _start_resource_monitoring 12:18:40 INFO - Starting resource monitoring. 12:18:40 INFO - [mozharness: 2017-06-04 19:18:40.858670Z] Finished create-virtualenv step (success) 12:18:40 INFO - [mozharness: 2017-06-04 19:18:40.859858Z] Running install step. 12:18:40 INFO - Running pre-action listener: _resource_record_pre_action 12:18:40 INFO - Running main action method: install 12:18:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:18:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:18:41 INFO - Reading from file tmpfile_stdout 12:18:41 INFO - Detecting whether we're running mozinstall >=1.0... 12:18:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:18:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:18:41 INFO - Reading from file tmpfile_stdout 12:18:41 INFO - Output received: 12:18:41 INFO - Usage: mozinstall [options] installer 12:18:41 INFO - Options: 12:18:41 INFO - -h, --help show this help message and exit 12:18:41 INFO - -d DEST, --destination=DEST 12:18:41 INFO - Directory to install application into. [default: 12:18:41 INFO - "/builds/slave/test"] 12:18:41 INFO - --app=APP Application being installed. [default: firefox] 12:18:41 INFO - mkdir: /builds/slave/test/build/application 12:18:41 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'] 12:18:41 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 12:19:02 INFO - Reading from file tmpfile_stdout 12:19:02 INFO - Output received: 12:19:02 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:19:02 INFO - Running post-action listener: _resource_record_post_action 12:19:02 INFO - [mozharness: 2017-06-04 19:19:02.897408Z] Finished install step (success) 12:19:02 INFO - [mozharness: 2017-06-04 19:19:02.897513Z] Running setup-mitmproxy step. 12:19:02 INFO - Running pre-action listener: _resource_record_pre_action 12:19:02 INFO - Running main action method: setup_mitmproxy 12:19:02 INFO - Skipping: mitmproxy is not required 12:19:02 INFO - Running post-action listener: _resource_record_post_action 12:19:02 INFO - [mozharness: 2017-06-04 19:19:02.898245Z] Finished setup-mitmproxy step (success) 12:19:02 INFO - [mozharness: 2017-06-04 19:19:02.898392Z] Running run-tests step. 12:19:02 INFO - Running pre-action listener: _resource_record_pre_action 12:19:02 INFO - Running main action method: run_tests 12:19:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:19:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:19:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:19:02 INFO - 2017-06-04 12:19:02.912 screenresolution[1635:8238] starting screenresolution argv=screenresolution get 12:19:02 INFO - 2017-06-04 12:19:02.915 screenresolution[1635:8238] Display 0: 1600x1200x32@60 12:19:02 INFO - 2017-06-04 12:19:02.921 screenresolution[1636:8241] starting screenresolution argv=screenresolution list 12:19:02 INFO - Available Modes on Display 0 12:19:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:19:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:19:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:19:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:19:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:19:03 INFO - Intel Iris: 12:19:03 INFO - Chipset Model: Intel Iris 12:19:03 INFO - Type: GPU 12:19:03 INFO - Bus: Built-In 12:19:03 INFO - VRAM (Dynamic, Max): 1536 MB 12:19:03 INFO - Vendor: Intel (0x8086) 12:19:03 INFO - Device ID: 0x0a2e 12:19:03 INFO - Revision ID: 0x0009 12:19:03 INFO - Displays: 12:19:03 INFO - Display: 12:19:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:19:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:19:03 INFO - Display Serial Number: 1600x1200 60 12:19:03 INFO - Main Display: Yes 12:19:03 INFO - Mirror: Off 12:19:03 INFO - Online: Yes 12:19:03 INFO - Rotation: Supported 12:19:03 INFO - Return code: 0 12:19:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:19:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:19:03 INFO - Python 2.7.3 12:19:03 INFO - Return code: 0 12:19:03 INFO - grabbing minidump binary from tooltool 12:19:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:19:03 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 0x1055e7c60>, '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 0x10537ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053a5be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:19:03 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 12:19:03 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 12:19:03 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 12:19:03 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:19:03 INFO - Return code: 0 12:19:03 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:19:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:19:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:19:03 INFO - ENV: RUST_BACKTRACE is now 1 12:19:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:19:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:19:03 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/GQ0HQlC-RgGQg_O_S0g1GA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0226', '--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 12:19:03 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/GQ0HQlC-RgGQg_O_S0g1GA/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0226 --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 12:19:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qsywQHYM7A/Render', 12:19:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:03 INFO - 'HOME': '/Users/cltbld', 12:19:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:03 INFO - 'LOGNAME': 'cltbld', 12:19:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:19:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:19:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:03 INFO - 'MOZ_NO_REMOTE': '1', 12:19:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:19:03 INFO - 'NO_EM_RESTART': '1', 12:19:03 INFO - 'PAGER': '/bin/cat', 12:19:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:03 INFO - 'PWD': '/builds/slave/test', 12:19:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:19:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:03 INFO - 'RUST_BACKTRACE': '1', 12:19:03 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:19:03 INFO - 'SHELL': '/bin/bash', 12:19:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BNOzEIWKIG/Listeners', 12:19:03 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 12:19:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:03 INFO - 'USER': 'cltbld', 12:19:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:03 INFO - 'XPC_FLAGS': '0x0', 12:19:03 INFO - 'XPC_SERVICE_NAME': '0', 12:19:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:03 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/GQ0HQlC-RgGQg_O_S0g1GA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0226', '--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 12:19:03 INFO - mozversion application_buildid: 20170604111234 12:19:03 INFO - mozversion application_changeset: 8a3aa1701537ea6b8334f432cd030d260d492fa3 12:19:03 INFO - mozversion application_display_name: Nightly 12:19:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:19:03 INFO - mozversion application_name: Firefox 12:19:03 INFO - mozversion application_remotingname: firefox 12:19:03 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:19:03 INFO - mozversion application_vendor: Mozilla 12:19:03 INFO - mozversion application_version: 55.0a1 12:19:03 INFO - mozversion platform_buildid: 20170604111234 12:19:03 INFO - mozversion platform_changeset: 8a3aa1701537ea6b8334f432cd030d260d492fa3 12:19:03 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:19:03 INFO - mozversion platform_version: 55.0a1 12:19:03 INFO - using testdate: 1496603943 12:19:03 INFO - actual date: 1496603943 12:19:03 INFO - starting webserver on 'localhost:49228' 12:19:03 INFO - SUITE-START | Running 2 tests 12:19:03 INFO - TEST-START | dromaeo_css 12:19:03 INFO - operating with platform_type : mac_ 12:19:03 INFO - Initialising browser for dromaeo_css test... 12:19:03 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpPzYrFY/profile 12:19:03 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 12:19:37 INFO - TEST-INFO | 1646: killed by SIGTERM 12:19:37 INFO - Browser initialized. 12:19:37 INFO - Running cycle 1/1 for dromaeo_css test... 12:19:37 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpPzYrFY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:19:39 INFO - PID 1654 | 2017-06-04 12:19:39.844 plugin-container[1655:8738] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9937, name = 'com.apple.tsm.portname' 12:19:39 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:19:39 INFO - PID 1654 | 2017-06-04 12:19:39.844 plugin-container[1655:8738] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x993b, name = 'com.apple.CFPasteboardClient' 12:19:39 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:19:39 INFO - PID 1654 | 2017-06-04 12:19:39.844 plugin-container[1655:8738] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:19:40 INFO - PID 1654 | 2017-06-04 12:19:40.312 plugin-container[1656:8784] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4743, name = 'com.apple.tsm.portname' 12:19:40 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:19:40 INFO - PID 1654 | 2017-06-04 12:19:40.312 plugin-container[1656:8784] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e03, name = 'com.apple.CFPasteboardClient' 12:19:40 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:19:40 INFO - PID 1654 | 2017-06-04 12:19:40.312 plugin-container[1656:8784] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:19:49 INFO - PID 1654 | RSS: Main: 269365248 12:19:49 INFO - PID 1654 | 12:22:51 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49228/tests/dromaeo/cssquery-ext.html) 12:22:53 INFO - PID 1654 | RSS: Main: 272072704 12:22:53 INFO - PID 1654 | 12:25:55 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-ext.html (next: http://localhost:49228/tests/dromaeo/cssquery-jquery.html) 12:25:56 INFO - PID 1654 | RSS: Main: 283066368 12:25:56 INFO - PID 1654 | 12:28:58 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49228/tests/dromaeo/cssquery-mootools.html) 12:28:58 INFO - PID 1654 | RSS: Main: 283320320 12:28:58 INFO - PID 1654 | 12:32:00 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49228/tests/dromaeo/cssquery-prototype.html) 12:32:01 INFO - PID 1654 | RSS: Main: 286998528 12:32:01 INFO - PID 1654 | 12:35:03 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49228/tests/dromaeo/cssquery-yui.html) 12:35:04 INFO - PID 1654 | RSS: Main: 287014912 12:35:04 INFO - PID 1654 | 12:38:06 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-yui.html (next: http://localhost:49228/tests/dromaeo/cssquery-dojo.html) 12:38:06 INFO - PID 1654 | RSS: Main: 286965760 12:38:06 INFO - PID 1654 | 12:38:06 INFO - PID 1654 | __start_tp_report 12:38:06 INFO - PID 1654 | _x_x_mozilla_page_load 12:38:06 INFO - PID 1654 | _x_x_mozilla_page_load_details 12:38:06 INFO - PID 1654 | |i|pagename|runs| 12:38:06 INFO - PID 1654 | |0;dojo.html;3471.965280347197;3522.771019883709;3538;3622.9637703622966;3628.872989445368;4906.852794416164;5539.14143307788;5790.015697331453;6117.082437634346;6174.660393678346;6121.270068921552;6135.508280839769;6143.174139895654;6180.3510631383615;6193.194884664998;5358.624896257264;5384.5692344612335;5408.972955135238;5413.106837371829;5416.620836541444;5078.923816142761;5457.590680698943;5510.6969116698565;5544.0575102232515;5563.109902415615;5324.334458192726;5390.757415916293;5431.918521222185;5447.727613619303;5474.644148130379;5415.891682166374;5422.43064478228;5454.963556924172;5465.672059676433;5504.146857237135;4511.939694171867;4641.164590373732;4920.843601753585;5374.91937620936;5456.7544460498975;4393.978030109829;6167.84580385494;6186;6188.443040126365;6203.100550420164;6473.546851720382;6486.286508484063;6496.090547323379;6602.40578347951;6630.73477060917;6761.357671021246;6767.255601883789;6770.695318710621;6770.763023294161;6810.829729256759;6261.029540421242;6265.248170219512;6271.62370257786;6275.843103922392;6294.685265736694;6632.369924857131;6708.731650733916;6765.695543700545;6816.352446517573;6869.656517174121;6311.526635502356;6333.271673757485;6469.482441404676;6516.674166291667;6528.575642583217;6537.803865884031;6618.8345291368105;6690.698918548677;6731.663416829139;6755.7973260802255;6613.173353330833;6623.6688165592495;6628.436582890508;6631.237407698177;6650.86698266032;6390.265119511223;6483.481321494269;6536.705848236841;6604.603723776493;6645.800625981228;5272.815451459181;5325.281087053276;5325.786968521215;5347.973260133675;5369.731513424391;4726.645501587326;4746.335513028179;4753.263244197155;4756;4781.091592597395;5122.872967947045;6136.519078024463;6173.475254603409;6269.028300613412;6271.216097987752;6589.9341006590275;6722.798316050526;6728.293529179464;6760.391564759097;6777;6224;6230.813075607739;6260.467860231931;6266.404691554386;6307.653079080603;5725.31296244445;5799.507041901401;5841.561882858718;5886.735096920649;5899.085641725539;6595.901061484082;6727.327267273331;6832.521723479309;6848.9657551711925;6855.314468553105;6731.091302674076;6752.7298908045095;6759.425448836904;6771.255161932085;6772.085768421397;6763.7632682855865;6812.795616131524;6870.553414028072;6893.0694356261265;6898.034275201376;6384.201974753156;6419.293877673359;6449.419552240321;6534.248561415308;6597.340265973365;6610.4050635443045;6649.501287403367;6660.300668429745;6662.500312476487;6662.6335551545135;6304.211973503312;6348.269948955929;6392.232932048184;6414.454771344488;6423.325550817097;6421.935780642134;6507.089007538853;6534.085228068169;6551.836204094745;6569;6287.591306565058;6378.712957916812;6381.744730210739;6393.041043843275;6413.166288382667;6494.058361537645;6506.446952008943;6513.250976137805;6553.344665533408;6568.408843204134;6197;6251.093591429309;6265.749370025148;6301.558890877594;6326.367363263821 12:38:06 INFO - PID 1654 | |1;ext.html;795.3954994204403;825.653225645229;851.0298259983617;1248.9625311240666;1314.2114731161298;31947.680523194762;34467.965961021175;39331.80334098329;40394.59605403949;40492.59507404925;39987.60012399875;40393.19213615726;40452;40475;40589.173487193075;6634.933650663504;8052.51684898905;8307.293880020206;8489.575521223946;8550.657973681045;8382.958085209595;8433.873491897626;8437.409381343308;8475.364347673918;8489.872651910226;7387.707151248537;8397.076321604618;8449.366297527678;8483.533405662685;8486.151384861527;7792.532448053078;8188.853560501535;8415.200555947175;8445.704400345958;8446.239838414573;8314.210150035737;8347.123552027071;8350.62422191003;8363.58182090899;8397.664093436255;33174.6682533174;34588.616455341755;37445;37532.624673753184;37773.86678399653;3566.197605538759;3608.2061946371755;3608.6932610728118;3609.7112231021456;3613;30512.389752204832;30602.693973060206;30616.2345941351;30677.84661076703;30788.2302942426;3059.311654877657;3096.086654436924;3102.829344386058;3108.0520441265403;3122.406742718899;238883;243100;265996.67001665063;297984;298134;47773.76113119412;48039.519604804205;48060;48118.5188148121;48198.5180148201;49492.50507494951;49592.75203624031;49923;49943.25085123726;50241.24638130431;13811.930940345435;13824.963127765457;13913.30433478322;13920.58238252854;13927.512537061155;10965.945170274097;11006.944965275123;11021.228514003942;11063.391513466837;11114.388708621103;13866.197394338873;13966.067125903674;13968.790468142986;13969.580912572435;14094.083884547677;6192.225971753531;6220.657863817533;6243.625382477066;6252.656103914328;6276.843078922972;967.7000129959735;1007.6221416968637;1015.1523477895892;1056.3872953686844;1062.0919114157439;23135.421614459436;23946.76053239445;24335.26994190177;24788.5042299153;25167.62248566273;1084.2139448899643;1094.7482079121846;1161.3264306702092;1162.203890335123;1169.2283093158474;10589.735256618646;11961.34212618297;12714.55499057547;12976.480940762453;13000;332945;337196;337714;339741;340702;54294.45705542973;54641;54713.452865471634;54791;54846.17730734042;1472.4699108320997;1492.276246020701;1522.9238538073052;1528.396283468046;1530.6249968757725;23100.884495577415;23168.6524702133;23881;24215.878920605282;24441.38896527601;38498.80750596229;38498.845034649006;38515.42226866655;38584.61415385923;38606.6139338603;230523;268963.65518172283;269408;269438;270189;34259;35688.75089371859;35702.64297357097;35728.82135589409;35765.64234357623;46545.53454465412;47531.28703069526;49294;49308;49538;25035.123770668666;33284.50073248952;33809.3238135231;33916.83041584776;34158.97523074312;33316.500252495745;33603.49594756032;34241.486377703855;34507;34567.481487778205;32346;39895.60104398919;42268.577314227696;42309;42441.787791060844;32231.677683223807;33344.333113338085;34307.4853877197;34540.65459345475;34810.563260571515;24294.27117186487;26325.07862224813;26475.205743827715;26588.335291617863;26626.866865665546 12:38:06 INFO - PID 1654 | |2;jquery.html;429.9123218257807;435.5992486912041;436.94319738434007;443.6783332084239;449.56588482740057;21702.023408946596;22490.999999999978;22518;23561.882190589065;24491.26526204212;24137.637935430983;24683.629745553786;24773.75226247737;24840.50318993619;24852.50294994099;6210.596311239767;6341.017142342921;6358.078078678612;6358.809235722936;6384.138141350904;4899.8040078396825;6243.937560624404;6312.305646378894;6323.968380158093;6324.335944725806;5248.291480650101;5320.680759154463;6124.265088189423;6174.259088909314;6205.906911396332;6166.599171053867;6214.3474935131835;6223.844403889939;6230.657313847737;6234.096056071867;5553.139263414178;5554.250176226198;5564.415736347706;5569.693666848321;5572.219889215513;22535.21126760572;27816.30459238515;28164.436711265866;28347.716522834715;28660.570091448644;2206.5035367042447;2218.589560931233;2239.3729755668437;2249.910003599854;2253.008676182491;18649.53376165593;19100.33148839798;19134.904325478423;19144.712829307573;19382.806171938242;2115.2279418012467;2132.893355332227;2156.9352919412445;2163.935081947544;2173.282816670495;146922;152432;184525;192910.03544982325;196529;12474.505098980058;13113.934430327787;13415.396307166005;13492.460301587827;13711.846275356724;16793.580160496087;16847.32610695583;16850.662986740197;16854.494365169063;16880.324787008627;8685.392022558486;8947.239484643878;9022.14289642483;9102.271818254523;9106.635734570675;8580.742577722678;8607.827843443096;8613.310935125175;8648.308135349156;8651.567421628893;6966.79099627012;7542.208068152875;7614.048243969504;8280.337572994145;8362.080171181165;1924.5862139639764;1927.4291452466196;2297.1270917051415;2299.091858715831;2455.9807679813034;945.8581212818133;951.871497347863;955.2774203610516;997.9002099789963;999.1157825324494;18392.26430942774;18858;19738.901305493382;20384.388468345973;21152.78847211539;786.7128498098153;964.7202311329776;964.8070385922844;1002.2883752535781;1006.5907729179062;7807.6486558105025;9555.151612001207;9860.506974651238;10115.747106322253;10452.947735261276;529345;533241;549417;555645;564212;110588;127855;127954.80112997249;137312;138892;892.1346294094718;932.6222879733718;1109.755853712182;1155.0586272188307;1181.8463599732152;18755.437336879917;19124.52188695238;20872.851993140524;21631.567368652224;23667.52664946657;59903.70048149731;60113.39886601078;60481;65325.346746531926;65596.34403655902;58829.70585147047;66752.33247667461;76105;80072.3985520298;95852;8137.79655508617;8800.38397312182;10471.842922356323;10488.423136727553;10507.947460262956;17792.644147117244;18294.725579116057;18308.26766929308;18309.084545772923;18471.53821154482;15013.924930375279;15022.924885375503;15035.624109397353;15099.698006040038;15256.703180229804;10714.946425268135;13311.201327920357;13522.86477135216;13626;13786.65533361674;15164.497857853772;15202.619934501283;15488.838337124986;15512.37950481968;15538.766918496458;10285.74285642835;10323.122534584645;10361.927614476985;10371.740706482398;10373.17014638812;14887.10677359362;14939.327730251947;15052.54842354731;15130.470433534774;15207.315670795062 12:38:06 INFO - PID 1654 | |3;mootools.html;1394.0868730981206;1452.1287227663397;1461.568837193028;1468.9412423503068;1481.5629389330147;11625.651230463089;11951.701207469818;12037.458314375852;12111.333876636792;12347.320897350643;11836.644900652973;11947.58183463579;12036.277823330607;12043.458044388002;12057.095717821177;2541.9491610167784;2572.8970841166424;2586.741325867417;2587.40489687372;2589.7669209771116;2124.925627603034;2205.305328821424;2474.2948259745976;2515.534626094169;2573.7040240372316;2553.131935142051;2571.7042540107846;2577.8453292802396;2582.883770230344;2584.8061395395325;2490.340059884132;2510.3222130024883;2513.1329691256487;2518.40817407909;2521.8360806547516;2496.812739044579;2501.5497210502103;2502.1117503286255;2512.849229046263;2513.195777351248;11934.522619095225;11960;12040.939795300967;12041.458134383975;12095.032397408186;5697.829065128053;6630.502712296597;6649.401553860127;6711.765088221938;6728.058071869942;3570.535830342039;4095.89760255996;4209.894752631178;4234.259004674201;4256.59562341577;1254.5107408110844;1256.6355756830508;1261.186534685132;1270.339423499775;1285.6400207941697;13270.73458530824;16501;16542.42101526453;16624.750628740698;16634.75047874283;5108.390856880071;5293.788248470018;5471.2887324647545;5541.556675465953;5648.209250704905;12273.140880138482;12439.37803109841;12442.253464792142;12449.626511204497;12456.501739930483;6378.681065946684;6487.124238227779;6495.317991610813;6506.674666266667;6526.67366631676;6054.212952316171;6058.394160583906;6103.206583144251;6143.047827586731;6150.292716337589;4211.178820130045;4278.379634952915;4285.871423857289;4326.502452218036;4379.102284031829;1081.259337353915;1115.6374178392055;1163.7497937943212;1179.6166245970094;1220.6704189868863;682.8770821252223;758.4804408979867;831.1356189562787;844.3329769482041;852.26705033671;6563.310852360529;7091.2554181810165;7198.435122799351;7228.530145540523;7522.210167932398;877.9473231606124;890.6214858685163;926.2636204217663;971.2958105373548;983.735899369307;4584.679072464895;4688.507706690816;4688.976555117202;4937.950620493749;4949.084419382426;14785.964982451125;15243.923780381248;15290.311935962909;15305.770413443808;15314.693706125816;14866.851331486763;15409.845901541064;15442.691146177014;15458.84541154574;15476.922615386851;8546.401751877433;8654.78363040929;8707.216350528483;8760.56197190138;8783.858098447161;6184.319724830355;6925.515213935178;7202.89195662076;7397.889031664422;7503.212162722835;13651.931740341235;13723.588292351245;13748.175109493463;13841.238731869844;13943.372548235557;14027.29863506841;14090.929545352208;14219.21794301282;14264.215468149352;14378.209198493765;8823.558822058743;8870.689525866565;8968.148025937526;8997.595108220277;9018.909810901807;10681.46592670379;10728.785424291627;10812.945935270272;10833.133349332193;10844.620438284623;9139.177474027369;9199.632014719336;9251.352405331561;9280.350375473652;9385.577649005674;9009;9074;9115.817683646423;9155.633774649206;9250.259979201783;8184.672613095648;8345.123762005145;8360.372972027244;8385.790355241168;8392.538410387488;6026.758929642765;6130.220122295444;6557.508186886098;6569.605823650596;6611.933880661132;5240.737963101906;6274.905876411766;6637.966810165918;6720.798376048726;6756.054152418567 12:38:06 INFO - PID 1654 | |4;prototype.html;401.5703197578591;403.3727553654069;408.2345601996257;408.2447472176474;412.1859327827539;4938.654294199408;5655.915161272585;6437.066625339329;6473.187507497896;6543.34566543346;4982.103221420152;6297.2128483939505;6317.305096439388;6348.936510634892;6386.457151142147;3588.5155504007016;4269.3595960606;4523.2762757958735;4609.907801843961;4652.627789776827;3375.7468189885735;4023.637872591466;4646.465656449518;4670.90658186836;4677.625789936814;3947.50656167979;4247.08687632159;4584.005957408538;4605.838795642153;4617.676762626601;3527.6472352764517;3782.092297848524;3868.651821336079;4163.875083747493;4606.838760643362;3473.2532505511435;3951.723379363446;3998.6001399860074;4013.8795836124964;4033.81847816846;4963.726995015273;6160.229971253593;6173.506119510428;6208.255009398856;6212.471939885115;5134.229865520165;5375.172441369914;5484.040292948758;5511.559075274008;5532.917006244909;5457.918131227995;5489.615726899118;6157.78447754331;6836.794896153124;6850.177978642595;4523.773811309421;4529.1621050105505;4530.304468841067;4545.886352841172;4553.408056952575;5750.913736293917;6018.893387314449;7157.785266442015;7211.531250468755;7270.709171633128;4036.8183431745642;4539.16025535277;4572.359869618256;4577.1074640444795;4580.290055041474;4003.9589706676475;4060.4315395844014;4089.9591004090175;4133.7106402551535;4343.587359200871;4711.882202944886;4764.356811830358;5399.894041323887;5460.781568737286;5462.863428414322;4988.5260900214425;6597.80206593803;6776.322367763283;6880.48862165306;6917.13535808024;1586.4056623093718;1598.8720902327786;1600.0559669794877;1600.5438450041681;1603.5029140966105;1024.4212020208706;1046.1630695443766;1058.8358804385332;1060.5068643081004;1067.5249513966367;5410.675359478444;5757.942420575824;6406.67966601668;6408.903866441914;6415.67921603918;5564.4713752193475;6207.6585787782105;6886.622675464927;7069.8939515907305;7110.92889071113;6250.781222657185;6315.652639104894;6340.904886426707;6346.746130154835;6384.509321983091;3065.340951695396;3196.7122958933805;3559.483874838134;3639;3677.8414799338125;6345.873082538324;6436.871262574722;7216.097987751531;7238.348548630648;7252.854942901113;7035.417031168048;7066.3993560546905;7107.218205997337;7171.928280717126;7200.675969581382;6581.605103693698;7133.179684336368;7208.477033038394;7225.2774722528375;7281.344678978918;7021.5787052777;7038.507304488637;7055.894161587683;7082.822929426806;7114.608696521741;6317.231175270931;6791.252962174256;6875.278095799869;7016.298370163147;7153.320434558708;5716.399778023248;6227.377262273918;6765.167181146979;7118.722008957291;7212.783616491513;4493.258612328929;4522.819087236474;4547.431571053618;4556.202664533627;4562.270036794097;5768.567357448292;6068.575199736153;7047.330503602149;7056.929430705627;7124.430045596237;5419.539339156216;6434.324395938359;6518.826611210027;6561.311062338385;6746.773564723728;6748.898766518408;6777.559458635173;6779.661016949231;6785.728570857111;6790.45676345901;6113.449789518787;6293.811185664437;6308.716107775253;6666.2333831609985;6729.327067293231;6128.387161283836;6765.932340676531;6794.898076528955;6827.590344579341;6839.726410943706;5433.212184233343;6373.7131829066875;6560.376764207584;6588.604683718992;6673.766418175341 12:38:06 INFO - PID 1654 | |5;yui.html;719.6186021408652;833.2001278772378;837.7361131243662;868.3096937934341;877.1141147441083;16742.91628541857;17081.060541670202;17200.483985480423;17221.7416738749;17236.741448878278;15148.545543633682;15151.84848151521;15326.846731532709;15394.846051539482;15657.843421565782;948.7865230323191;968.7626531499757;977.3891317926297;978.1685567267801;980.96076156954;907.863820426938;985.9014098590119;998.1066945084145;998.4358837256963;999.4203362049993;969.8399764038925;987.9753006174867;995.6863587969766;997.8503224516344;1009.9293049486539;966.4732720667218;978.0366339155961;988.4859872866141;989.5299732627017;994.0854413939194;724.543537571332;743.8177646476594;744.8547533231022;745.5303159009843;746.6453434618567;17086.145692715316;17092.2308496118;17185.312587496486;17189.7421538677;17579.824201757947;17261.395851145277;17312.913435432867;17436.65126697459;17812.643747125116;17835.10824458785;14597.270136493133;14784.186869722162;14853.405863765436;14860.331285092198;14915.850841491554;3552.6802587767224;3569.946865674632;3584.623614520464;3585.354636165489;3598.712103031751;132259.33870330587;132556;132626.02121936358;133590.33204834012;135133.97299040522;13702.588922332347;13896.513622023384;13938.094023888414;13987.160770353812;14096.154230745982;23263.302100937;25647.582989520823;27005.32486687809;27167.184984450498;27240.774165162617;8024.919750802534;8057.073436554754;8063.354931605575;8096.068952070587;8104.230098140667;3896.883093507199;3935.3900145477496;3963.6829053676215;4010.1378203685763;4016.7991600419864;4424.6239069678795;4514.029483661028;4515.480719717275;4517.819287228474;4529.932051019237;919.3151102428733;946.8769060022153;963.7301555564453;971.2763990826738;972.5331840716495;571.9771209151587;646.253577118429;663.8605892762574;683.3235097253684;698.5494356140262;10435.582576697;10464.581416743245;10880.668472448444;10937.507812148473;11568.730972482046;551.8647931256787;626.2672672972633;642.1587720086701;677.695037233247;680.8740383029156;4778.4982576829625;5399.190121481731;5409.890972350659;5497.862553436116;5848.210491583582;126889.70900799916;128655;130036.3498182522;132398.67601324056;133681.3211537611;69448;74259;101775.49112254391;108290;108346.45826770816;88056.559717201;89852.5507372472;93919;98541;98852.50573747086;11948.103892207946;12450.81323780126;12632.873671263169;12691.492340306653;12983.935080324538;33929.66070339364;40346.79826600848;40387.596124039555;40412.3938140934;40910.79544602258;53506;53648.73175634097;53773.19340209978;54065.72967135139;54341.913161735756;16125.67748645044;16371.42700005494;16704.248308826376;16770.58073548171;16850.410235641695;17853;18085.909570452062;18518.537036574195;19277.036148192816;19337.8066219336;16995.980241185567;18098.638027239645;18206.18072186781;18251;18402.171902264163;16658.583535411228;17149.485515434557;17792.46622601324;17845.37541186052;17871.106444677975;17133.82866171322;17604.911975440555;17642;17856.910715446338;18080.90959545194;11211.88788112141;11436.599719009795;11902.642920712393;12134.575289864812;12275.93862030684;11633.979111855191;11985.101117416054;12787.296698681661;13347.399367028313;14322.856771432153 12:38:06 INFO - PID 1654 | __end_tp_report 12:38:06 INFO - PID 1654 | __start_cc_report 12:38:06 INFO - PID 1654 | _x_x_mozilla_cycle_collect,452 12:38:06 INFO - PID 1654 | __end_cc_report 12:38:06 INFO - PID 1654 | __startTimestamp1496605086696__endTimestamp 12:38:06 INFO - PID 1654 | 12:38:06 INFO - PID 1654 | ------- Summary: start ------- 12:38:06 INFO - PID 1654 | Number of tests: 6 12:38:06 INFO - PID 1654 | 12:38:06 INFO - PID 1654 | [#0] dojo.html Cycles:165 Average:6089.96 Median:6326.37 stddev:746.74 (11.8%) stddev-sans-first:720.23 12:38:06 INFO - PID 1654 | Values: 3472.0 3522.8 3538.0 3623.0 3628.9 4906.9 5539.1 5790.0 6117.1 6174.7 6121.3 6135.5 6143.2 6180.4 6193.2 5358.6 5384.6 5409.0 5413.1 5416.6 5078.9 5457.6 5510.7 5544.1 5563.1 5324.3 5390.8 5431.9 5447.7 5474.6 5415.9 5422.4 5455.0 5465.7 5504.1 4511.9 4641.2 4920.8 5374.9 5456.8 4394.0 6167.8 6186.0 6188.4 6203.1 6473.5 6486.3 6496.1 6602.4 6630.7 6761.4 6767.3 6770.7 6770.8 6810.8 6261.0 6265.2 6271.6 6275.8 6294.7 6632.4 6708.7 6765.7 6816.4 6869.7 6311.5 6333.3 6469.5 6516.7 6528.6 6537.8 6618.8 6690.7 6731.7 6755.8 6613.2 6623.7 6628.4 6631.2 6650.9 6390.3 6483.5 6536.7 6604.6 6645.8 5272.8 5325.3 5325.8 5348.0 5369.7 4726.6 4746.3 4753.3 4756.0 4781.1 5122.9 6136.5 6173.5 6269.0 6271.2 6589.9 6722.8 6728.3 6760.4 6777.0 6224.0 6230.8 6260.5 6266.4 6307.7 5725.3 5799.5 5841.6 5886.7 5899.1 6595.9 6727.3 6832.5 6849.0 6855.3 6731.1 6752.7 6759.4 6771.3 6772.1 6763.8 6812.8 6870.6 6893.1 6898.0 6384.2 6419.3 6449.4 6534.2 6597.3 6610.4 6649.5 6660.3 6662.5 6662.6 6304.2 6348.3 6392.2 6414.5 6423.3 6421.9 6507.1 6534.1 6551.8 6569.0 6287.6 6378.7 6381.7 6393.0 6413.2 6494.1 6506.4 6513.3 6553.3 6568.4 6197.0 6251.1 6265.7 6301.6 6326.4 12:38:06 INFO - PID 1654 | 12:38:06 INFO - PID 1654 | [#1] ext.html Cycles:180 Average:44764.24 Median:34387.73 stddev:76504.81 (222.5%) stddev-sans-first:76648.20 12:38:06 INFO - PID 1654 | Values: 795.4 825.7 851.0 1249.0 1314.2 31947.7 34468.0 39331.8 40394.6 40492.6 39987.6 40393.2 40452.0 40475.0 40589.2 6634.9 8052.5 8307.3 8489.6 8550.7 8383.0 8433.9 8437.4 8475.4 8489.9 7387.7 8397.1 8449.4 8483.5 8486.2 7792.5 8188.9 8415.2 8445.7 8446.2 8314.2 8347.1 8350.6 8363.6 8397.7 33174.7 34588.6 37445.0 37532.6 37773.9 3566.2 3608.2 3608.7 3609.7 3613.0 30512.4 30602.7 30616.2 30677.8 30788.2 3059.3 3096.1 3102.8 3108.1 3122.4 238883.0 243100.0 265996.7 297984.0 298134.0 47773.8 48039.5 48060.0 48118.5 48198.5 49492.5 49592.8 49923.0 49943.3 50241.2 13811.9 13825.0 13913.3 13920.6 13927.5 10965.9 11006.9 11021.2 11063.4 11114.4 13866.2 13966.1 13968.8 13969.6 14094.1 6192.2 6220.7 6243.6 6252.7 6276.8 967.7 1007.6 1015.2 1056.4 1062.1 23135.4 23946.8 24335.3 24788.5 25167.6 1084.2 1094.7 1161.3 1162.2 1169.2 10589.7 11961.3 12714.6 12976.5 13000.0 332945.0 337196.0 337714.0 339741.0 340702.0 54294.5 54641.0 54713.5 54791.0 54846.2 1472.5 1492.3 1522.9 1528.4 1530.6 23100.9 23168.7 23881.0 24215.9 24441.4 38498.8 38498.8 38515.4 38584.6 38606.6 230523.0 268963.7 269408.0 269438.0 270189.0 34259.0 35688.8 35702.6 35728.8 35765.6 46545.5 47531.3 49294.0 49308.0 49538.0 25035.1 33284.5 33809.3 33916.8 34159.0 33316.5 33603.5 34241.5 34507.0 34567.5 32346.0 39895.6 42268.6 42309.0 42441.8 32231.7 33344.3 34307.5 34540.7 34810.6 24294.3 26325.1 26475.2 26588.3 26626.9 12:38:06 INFO - PID 1654 | 12:38:06 INFO - PID 1654 | [#2] jquery.html Cycles:180 Average:36759.54 Median:2298.11 stddev:93472.04 (4067.3%) stddev-sans-first:93694.45 12:38:06 INFO - PID 1654 | Values: 429.9 435.6 436.9 443.7 449.6 21702.0 22491.0 22518.0 23561.9 24491.3 24137.6 24683.6 24773.8 24840.5 24852.5 6210.6 6341.0 6358.1 6358.8 6384.1 4899.8 6243.9 6312.3 6324.0 6324.3 5248.3 5320.7 6124.3 6174.3 6205.9 6166.6 6214.3 6223.8 6230.7 6234.1 5553.1 5554.3 5564.4 5569.7 5572.2 22535.2 27816.3 28164.4 28347.7 28660.6 2206.5 2218.6 2239.4 2249.9 2253.0 18649.5 19100.3 19134.9 19144.7 19382.8 2115.2 2132.9 2156.9 2163.9 2173.3 146922.0 152432.0 184525.0 192910.0 196529.0 12474.5 13113.9 13415.4 13492.5 13711.8 16793.6 16847.3 16850.7 16854.5 16880.3 8685.4 8947.2 9022.1 9102.3 9106.6 8580.7 8607.8 8613.3 8648.3 8651.6 6966.8 7542.2 7614.0 8280.3 8362.1 1924.6 1927.4 2297.1 2299.1 2456.0 945.9 951.9 955.3 997.9 999.1 18392.3 18858.0 19738.9 20384.4 21152.8 786.7 964.7 964.8 1002.3 1006.6 7807.6 9555.2 9860.5 10115.7 10452.9 529345.0 533241.0 549417.0 555645.0 564212.0 110588.0 127855.0 127954.8 137312.0 138892.0 892.1 932.6 1109.8 1155.1 1181.8 18755.4 19124.5 20872.9 21631.6 23667.5 59903.7 60113.4 60481.0 65325.3 65596.3 58829.7 66752.3 76105.0 80072.4 95852.0 8137.8 8800.4 10471.8 10488.4 10507.9 17792.6 18294.7 18308.3 18309.1 18471.5 15013.9 15022.9 15035.6 15099.7 15256.7 10714.9 13311.2 13522.9 13626.0 13786.7 15164.5 15202.6 15488.8 15512.4 15538.8 10285.7 10323.1 10361.9 10371.7 10373.2 14887.1 14939.3 15052.5 15130.5 15207.3 12:38:06 INFO - PID 1654 | 12:38:06 INFO - PID 1654 | [#3] mootools.html Cycles:180 Average:7238.13 Median:3833.22 stddev:4580.15 (119.5%) stddev-sans-first:4571.94 12:38:06 INFO - PID 1654 | Values: 1394.1 1452.1 1461.6 1468.9 1481.6 11625.7 11951.7 12037.5 12111.3 12347.3 11836.6 11947.6 12036.3 12043.5 12057.1 2541.9 2572.9 2586.7 2587.4 2589.8 2124.9 2205.3 2474.3 2515.5 2573.7 2553.1 2571.7 2577.8 2582.9 2584.8 2490.3 2510.3 2513.1 2518.4 2521.8 2496.8 2501.5 2502.1 2512.8 2513.2 11934.5 11960.0 12040.9 12041.5 12095.0 5697.8 6630.5 6649.4 6711.8 6728.1 3570.5 4095.9 4209.9 4234.3 4256.6 1254.5 1256.6 1261.2 1270.3 1285.6 13270.7 16501.0 16542.4 16624.8 16634.8 5108.4 5293.8 5471.3 5541.6 5648.2 12273.1 12439.4 12442.3 12449.6 12456.5 6378.7 6487.1 6495.3 6506.7 6526.7 6054.2 6058.4 6103.2 6143.0 6150.3 4211.2 4278.4 4285.9 4326.5 4379.1 1081.3 1115.6 1163.7 1179.6 1220.7 682.9 758.5 831.1 844.3 852.3 6563.3 7091.3 7198.4 7228.5 7522.2 877.9 890.6 926.3 971.3 983.7 4584.7 4688.5 4689.0 4938.0 4949.1 14786.0 15243.9 15290.3 15305.8 15314.7 14866.9 15409.8 15442.7 15458.8 15476.9 8546.4 8654.8 8707.2 8760.6 8783.9 6184.3 6925.5 7202.9 7397.9 7503.2 13651.9 13723.6 13748.2 13841.2 13943.4 14027.3 14090.9 14219.2 14264.2 14378.2 8823.6 8870.7 8968.1 8997.6 9018.9 10681.5 10728.8 10812.9 10833.1 10844.6 9139.2 9199.6 9251.4 9280.4 9385.6 9009.0 9074.0 9115.8 9155.6 9250.3 8184.7 8345.1 8360.4 8385.8 8392.5 6026.8 6130.2 6557.5 6569.6 6611.9 5240.7 6274.9 6638.0 6720.8 6756.1 12:38:06 INFO - PID 1654 | 12:38:06 INFO - PID 1654 | [#4] prototype.html Cycles:180 Average:5350.52 Median:6043.73 stddev:1738.73 (28.8%) stddev-sans-first:1703.46 12:38:06 INFO - PID 1654 | Values: 401.6 403.4 408.2 408.2 412.2 4938.7 5655.9 6437.1 6473.2 6543.3 4982.1 6297.2 6317.3 6348.9 6386.5 3588.5 4269.4 4523.3 4609.9 4652.6 3375.7 4023.6 4646.5 4670.9 4677.6 3947.5 4247.1 4584.0 4605.8 4617.7 3527.6 3782.1 3868.7 4163.9 4606.8 3473.3 3951.7 3998.6 4013.9 4033.8 4963.7 6160.2 6173.5 6208.3 6212.5 5134.2 5375.2 5484.0 5511.6 5532.9 5457.9 5489.6 6157.8 6836.8 6850.2 4523.8 4529.2 4530.3 4545.9 4553.4 5750.9 6018.9 7157.8 7211.5 7270.7 4036.8 4539.2 4572.4 4577.1 4580.3 4004.0 4060.4 4090.0 4133.7 4343.6 4711.9 4764.4 5399.9 5460.8 5462.9 4988.5 6597.8 6776.3 6880.5 6917.1 1586.4 1598.9 1600.1 1600.5 1603.5 1024.4 1046.2 1058.8 1060.5 1067.5 5410.7 5757.9 6406.7 6408.9 6415.7 5564.5 6207.7 6886.6 7069.9 7110.9 6250.8 6315.7 6340.9 6346.7 6384.5 3065.3 3196.7 3559.5 3639.0 3677.8 6345.9 6436.9 7216.1 7238.3 7252.9 7035.4 7066.4 7107.2 7171.9 7200.7 6581.6 7133.2 7208.5 7225.3 7281.3 7021.6 7038.5 7055.9 7082.8 7114.6 6317.2 6791.3 6875.3 7016.3 7153.3 5716.4 6227.4 6765.2 7118.7 7212.8 4493.3 4522.8 4547.4 4556.2 4562.3 5768.6 6068.6 7047.3 7056.9 7124.4 5419.5 6434.3 6518.8 6561.3 6746.8 6748.9 6777.6 6779.7 6785.7 6790.5 6113.4 6293.8 6308.7 6666.2 6729.3 6128.4 6765.9 6794.9 6827.6 6839.7 5433.2 6373.7 6560.4 6588.6 6673.8 12:38:06 INFO - PID 1654 | 12:38:06 INFO - PID 1654 | [#5] yui.html Cycles:180 Average:23178.29 Median:26326.45 stddev:34262.60 (130.1%) stddev-sans-first:34317.22 12:38:06 INFO - PID 1654 | Values: 719.6 833.2 837.7 868.3 877.1 16742.9 17081.1 17200.5 17221.7 17236.7 15148.5 15151.8 15326.8 15394.8 15657.8 948.8 968.8 977.4 978.2 981.0 907.9 985.9 998.1 998.4 999.4 969.8 988.0 995.7 997.9 1009.9 966.5 978.0 988.5 989.5 994.1 724.5 743.8 744.9 745.5 746.6 17086.1 17092.2 17185.3 17189.7 17579.8 17261.4 17312.9 17436.7 17812.6 17835.1 14597.3 14784.2 14853.4 14860.3 14915.9 3552.7 3569.9 3584.6 3585.4 3598.7 132259.3 132556.0 132626.0 133590.3 135134.0 13702.6 13896.5 13938.1 13987.2 14096.2 23263.3 25647.6 27005.3 27167.2 27240.8 8024.9 8057.1 8063.4 8096.1 8104.2 3896.9 3935.4 3963.7 4010.1 4016.8 4424.6 4514.0 4515.5 4517.8 4529.9 919.3 946.9 963.7 971.3 972.5 572.0 646.3 663.9 683.3 698.5 10435.6 10464.6 10880.7 10937.5 11568.7 551.9 626.3 642.2 677.7 680.9 4778.5 5399.2 5409.9 5497.9 5848.2 126889.7 128655.0 130036.3 132398.7 133681.3 69448.0 74259.0 101775.5 108290.0 108346.5 88056.6 89852.6 93919.0 98541.0 98852.5 11948.1 12450.8 12632.9 12691.5 12983.9 33929.7 40346.8 40387.6 40412.4 40910.8 53506.0 53648.7 53773.2 54065.7 54341.9 16125.7 16371.4 16704.2 16770.6 16850.4 17853.0 18085.9 18518.5 19277.0 19337.8 16996.0 18098.6 18206.2 18251.0 18402.2 16658.6 17149.5 17792.5 17845.4 17871.1 17133.8 17604.9 17642.0 17856.9 18080.9 11211.9 11436.6 11902.6 12134.6 12275.9 11634.0 11985.1 12787.3 13347.4 14322.9 12:38:06 INFO - PID 1654 | -------- Summary: end -------- 12:38:06 INFO - PID 1654 | 12:38:07 INFO - TEST-INFO | 1654: exit 0 12:38:07 INFO - TEST-OK | dromaeo_css | took 1143762ms 12:38:07 INFO - TEST-START | kraken 12:38:07 INFO - operating with platform_type : mac_ 12:38:07 INFO - Initialising browser for kraken test... 12:38:07 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpROpkaw/profile 12:38:07 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 12:38:41 INFO - TEST-INFO | 1664: killed by SIGTERM 12:38:41 INFO - Browser initialized. 12:38:41 INFO - Running cycle 1/1 for kraken test... 12:38:41 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpROpkaw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:38:43 INFO - PID 1669 | 2017-06-04 12:38:43.043 plugin-container[1670:11766] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x983f, name = 'com.apple.tsm.portname' 12:38:43 INFO - PID 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:38:43 INFO - PID 1669 | 2017-06-04 12:38:43.046 plugin-container[1670:11766] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 12:38:43 INFO - PID 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:38:43 INFO - PID 1669 | 2017-06-04 12:38:43.046 plugin-container[1670:11766] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:38:43 INFO - PID 1669 | 2017-06-04 12:38:43.813 plugin-container[1671:11812] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 12:38:43 INFO - PID 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:38:43 INFO - PID 1669 | 2017-06-04 12:38:43.813 plugin-container[1671:11812] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a13, name = 'com.apple.CFPasteboardClient' 12:38:43 INFO - PID 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:38:43 INFO - PID 1669 | 2017-06-04 12:38:43.813 plugin-container[1671:11812] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:38:53 INFO - PID 1669 | RSS: Main: 260112384 12:38:53 INFO - PID 1669 | 12:38:55 INFO - PID 1669 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection) 12:38:56 INFO - PID 1669 | RSS: Main: 271167488 12:38:56 INFO - PID 1669 | 12:38:59 INFO - PID 1669 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-dft) 12:39:00 INFO - PID 1669 | RSS: Main: 280219648 12:39:00 INFO - PID 1669 | 12:39:03 INFO - PID 1669 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-fft) 12:39:04 INFO - PID 1669 | RSS: Main: 282468352 12:39:04 INFO - PID 1669 | 12:39:06 INFO - PID 1669 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator) 12:39:06 INFO - PID 1669 | RSS: Main: 283217920 12:39:06 INFO - PID 1669 | 12:39:08 INFO - PID 1669 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:39:09 INFO - PID 1669 | RSS: Main: 285114368 12:39:09 INFO - PID 1669 | 12:39:12 INFO - PID 1669 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom) 12:39:13 INFO - PID 1669 | RSS: Main: 295182336 12:39:13 INFO - PID 1669 | 12:39:16 INFO - PID 1669 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate) 12:39:16 INFO - PID 1669 | RSS: Main: 293801984 12:39:16 INFO - PID 1669 | 12:39:19 INFO - PID 1669 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial) 12:39:20 INFO - PID 1669 | RSS: Main: 292171776 12:39:20 INFO - PID 1669 | 12:39:22 INFO - PID 1669 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:39:22 INFO - PID 1669 | RSS: Main: 292265984 12:39:22 INFO - PID 1669 | 12:39:24 INFO - PID 1669 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:39:25 INFO - PID 1669 | RSS: Main: 292122624 12:39:25 INFO - PID 1669 | 12:39:27 INFO - PID 1669 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:39:28 INFO - PID 1669 | RSS: Main: 291569664 12:39:28 INFO - PID 1669 | 12:39:30 INFO - PID 1669 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:39:31 INFO - PID 1669 | RSS: Main: 291999744 12:39:31 INFO - PID 1669 | 12:39:34 INFO - PID 1669 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:39:35 INFO - PID 1669 | RSS: Main: 292311040 12:39:35 INFO - PID 1669 | 12:39:37 INFO - PID 1669 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49228/tests/kraken/driver.html?testName=ai-astar) 12:39:37 INFO - PID 1669 | RSS: Main: 294641664 12:39:37 INFO - PID 1669 | 12:39:37 INFO - PID 1669 | __start_tp_report 12:39:37 INFO - PID 1669 | _x_x_mozilla_page_load 12:39:37 INFO - PID 1669 | _x_x_mozilla_page_load_details 12:39:37 INFO - PID 1669 | |i|pagename|runs| 12:39:37 INFO - PID 1669 | |0;ai-astar;104;115;114;113;115;113;114;115;114;125 12:39:37 INFO - PID 1669 | |1;audio-beat-detection;140;102;124;185;146;99;142;149;134;127 12:39:37 INFO - PID 1669 | |2;audio-dft;214;241;195;211;212;213;253;278;246;196 12:39:37 INFO - PID 1669 | |3;audio-fft;79;57;60;89;51;66;84;90;91;57 12:39:37 INFO - PID 1669 | |4;audio-oscillator;86;69;81;72;69;74;87;68;85;118 12:39:37 INFO - PID 1669 | |5;imaging-gaussian-blur;90;90;93;90;103;138;114;137;90;90 12:39:37 INFO - PID 1669 | |6;imaging-darkroom;97;98;97;97;97;97;97;97;96;97 12:39:37 INFO - PID 1669 | |7;imaging-desaturate;93;96;143;150;91;91;94;93;124;130 12:39:37 INFO - PID 1669 | |8;json-parse-financial;50;49;51;51;50;51;53;59;65;60 12:39:37 INFO - PID 1669 | |9;json-stringify-tinderbox;57;62;57;55;69;51;49;55;83;83 12:39:37 INFO - PID 1669 | |10;stanford-crypto-aes;92;111;114;89;87;91;93;90;110;93 12:39:37 INFO - PID 1669 | |11;stanford-crypto-ccm;138;138;134;138;138;135;149;137;138;136 12:39:37 INFO - PID 1669 | |12;stanford-crypto-pbkdf2;183;186;225;239;229;185;185;183;185;181 12:39:37 INFO - PID 1669 | |13;stanford-crypto-sha256-iterative;69;65;68;83;95;95;68;68;63;69 12:39:37 INFO - PID 1669 | __end_tp_report 12:39:37 INFO - PID 1669 | __start_cc_report 12:39:37 INFO - PID 1669 | _x_x_mozilla_cycle_collect,1189 12:39:37 INFO - PID 1669 | __end_cc_report 12:39:37 INFO - PID 1669 | __startTimestamp1496605177498__endTimestamp 12:39:37 INFO - PID 1669 | 12:39:37 INFO - PID 1669 | ------- Summary: start ------- 12:39:37 INFO - PID 1669 | Number of tests: 14 12:39:37 INFO - PID 1669 | 12:39:37 INFO - PID 1669 | [#0] ai-astar Cycles:10 Average:114.20 Median:114.50 stddev:5.01 (4.4%) stddev-sans-first:3.71 12:39:37 INFO - PID 1669 | Values: 104.0 115.0 114.0 113.0 115.0 113.0 114.0 115.0 114.0 125.0 12:39:37 INFO - PID 1669 | 12:39:37 INFO - PID 1669 | [#1] audio-beat-detection Cycles:10 Average:134.80 Median:144.00 stddev:24.63 (17.1%) stddev-sans-first:26.06 12:39:37 INFO - PID 1669 | Values: 140.0 102.0 124.0 185.0 146.0 99.0 142.0 149.0 134.0 127.0 12:39:37 INFO - PID 1669 | 12:39:37 INFO - PID 1669 | [#2] audio-dft Cycles:10 Average:225.90 Median:227.50 stddev:27.19 (12.0%) stddev-sans-first:28.49 12:39:37 INFO - PID 1669 | Values: 214.0 241.0 195.0 211.0 212.0 213.0 253.0 278.0 246.0 196.0 12:39:37 INFO - PID 1669 | 12:39:37 INFO - PID 1669 | [#3] audio-fft Cycles:10 Average:72.40 Median:81.50 stddev:15.76 (19.3%) stddev-sans-first:16.54 12:39:37 INFO - PID 1669 | Values: 79.0 57.0 60.0 89.0 51.0 66.0 84.0 90.0 91.0 57.0 12:39:37 INFO - PID 1669 | 12:39:37 INFO - PID 1669 | [#4] audio-oscillator Cycles:10 Average:80.90 Median:77.50 stddev:15.03 (19.4%) stddev-sans-first:15.83 12:39:37 INFO - PID 1669 | Values: 86.0 69.0 81.0 72.0 69.0 74.0 87.0 68.0 85.0 118.0 12:39:37 INFO - PID 1669 | 12:39:37 INFO - PID 1669 | [#5] imaging-gaussian-blur Cycles:10 Average:103.50 Median:90.00 stddev:19.56 (21.7%) stddev-sans-first:20.13 12:39:37 INFO - PID 1669 | Values: 90.0 90.0 93.0 90.0 103.0 138.0 114.0 137.0 90.0 90.0 12:39:37 INFO - PID 1669 | 12:39:37 INFO - PID 1669 | [#6] imaging-darkroom Cycles:10 Average:97.00 Median:97.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 12:39:37 INFO - PID 1669 | Values: 97.0 98.0 97.0 97.0 97.0 97.0 97.0 97.0 96.0 97.0 12:39:37 INFO - PID 1669 | 12:39:37 INFO - PID 1669 | [#7] imaging-desaturate Cycles:10 Average:110.50 Median:92.00 stddev:23.65 (25.7%) stddev-sans-first:24.22 12:39:37 INFO - PID 1669 | Values: 93.0 96.0 143.0 150.0 91.0 91.0 94.0 93.0 124.0 130.0 12:39:37 INFO - PID 1669 | 12:39:37 INFO - PID 1669 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:52.00 stddev:5.45 (10.5%) stddev-sans-first:5.59 12:39:37 INFO - PID 1669 | Values: 50.0 49.0 51.0 51.0 50.0 51.0 53.0 59.0 65.0 60.0 12:39:37 INFO - PID 1669 | 12:39:37 INFO - PID 1669 | [#9] json-stringify-tinderbox Cycles:10 Average:62.10 Median:59.50 stddev:12.33 (20.7%) stddev-sans-first:12.94 12:39:37 INFO - PID 1669 | Values: 57.0 62.0 57.0 55.0 69.0 51.0 49.0 55.0 83.0 83.0 12:39:37 INFO - PID 1669 | 12:39:37 INFO - PID 1669 | [#10] stanford-crypto-aes Cycles:10 Average:97.00 Median:90.50 stddev:10.33 (11.4%) stddev-sans-first:10.79 12:39:37 INFO - PID 1669 | Values: 92.0 111.0 114.0 89.0 87.0 91.0 93.0 90.0 110.0 93.0 12:39:37 INFO - PID 1669 | 12:39:37 INFO - PID 1669 | [#11] stanford-crypto-ccm Cycles:10 Average:138.10 Median:138.00 stddev:4.09 (3.0%) stddev-sans-first:4.34 12:39:37 INFO - PID 1669 | Values: 138.0 138.0 134.0 138.0 138.0 135.0 149.0 137.0 138.0 136.0 12:39:37 INFO - PID 1669 | 12:39:37 INFO - PID 1669 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:198.10 Median:185.50 stddev:23.00 (12.4%) stddev-sans-first:23.74 12:39:37 INFO - PID 1669 | Values: 183.0 186.0 225.0 239.0 229.0 185.0 185.0 183.0 185.0 181.0 12:39:37 INFO - PID 1669 | 12:39:37 INFO - PID 1669 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.30 Median:69.00 stddev:12.12 (17.6%) stddev-sans-first:12.70 12:39:37 INFO - PID 1669 | Values: 69.0 65.0 68.0 83.0 95.0 95.0 68.0 68.0 63.0 69.0 12:39:37 INFO - PID 1669 | -------- Summary: end -------- 12:39:37 INFO - PID 1669 | 12:39:38 INFO - TEST-INFO | 1669: exit 0 12:39:38 INFO - TEST-OK | kraken | took 91216ms 12:39:38 INFO - SUITE-END | took 1234s 12:39:38 INFO - Completed test suite (00:20:35) 12:39:38 INFO - javascript benchmark 12:39:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8001.257770337425, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3471.965280347197, 3522.771019883709, 3538.0, 3622.9637703622966, 3628.872989445368, 4906.852794416164, 5539.14143307788, 5790.015697331453, 6117.082437634346, 6174.660393678346, 6121.270068921552, 6135.508280839769, 6143.174139895654, 6180.3510631383615, 6193.194884664998, 5358.624896257264, 5384.5692344612335, 5408.972955135238, 5413.106837371829, 5416.620836541444, 5078.923816142761, 5457.590680698943, 5510.6969116698565, 5544.0575102232515, 5563.109902415615, 5324.334458192726, 5390.757415916293, 5431.918521222185, 5447.727613619303, 5474.644148130379, 5415.891682166374, 5422.43064478228, 5454.963556924172, 5465.672059676433, 5504.146857237135, 4511.939694171867, 4641.164590373732, 4920.843601753585, 5374.91937620936, 5456.7544460498975, 4393.978030109829, 6167.84580385494, 6186.0, 6188.443040126365, 6203.100550420164, 6473.546851720382, 6486.286508484063, 6496.090547323379, 6602.40578347951, 6630.73477060917, 6761.357671021246, 6767.255601883789, 6770.695318710621, 6770.763023294161, 6810.829729256759, 6261.029540421242, 6265.248170219512, 6271.62370257786, 6275.843103922392, 6294.685265736694, 6632.369924857131, 6708.731650733916, 6765.695543700545, 6816.352446517573, 6869.656517174121, 6311.526635502356, 6333.271673757485, 6469.482441404676, 6516.674166291667, 6528.575642583217, 6537.803865884031, 6618.8345291368105, 6690.698918548677, 6731.663416829139, 6755.7973260802255, 6613.173353330833, 6623.6688165592495, 6628.436582890508, 6631.237407698177, 6650.86698266032, 6390.265119511223, 6483.481321494269, 6536.705848236841, 6604.603723776493, 6645.800625981228, 5272.815451459181, 5325.281087053276, 5325.786968521215, 5347.973260133675, 5369.731513424391, 4726.645501587326, 4746.335513028179, 4753.263244197155, 4756.0, 4781.091592597395, 5122.872967947045, 6136.519078024463, 6173.475254603409, 6269.028300613412, 6271.216097987752, 6589.9341006590275, 6722.798316050526, 6728.293529179464, 6760.391564759097, 6777.0, 6224.0, 6230.813075607739, 6260.467860231931, 6266.404691554386, 6307.653079080603, 5725.31296244445, 5799.507041901401, 5841.561882858718, 5886.735096920649, 5899.085641725539, 6595.901061484082, 6727.327267273331, 6832.521723479309, 6848.9657551711925, 6855.314468553105, 6731.091302674076, 6752.7298908045095, 6759.425448836904, 6771.255161932085, 6772.085768421397, 6763.7632682855865, 6812.795616131524, 6870.553414028072, 6893.0694356261265, 6898.034275201376, 6384.201974753156, 6419.293877673359, 6449.419552240321, 6534.248561415308, 6597.340265973365, 6610.4050635443045, 6649.501287403367, 6660.300668429745, 6662.500312476487, 6662.6335551545135, 6304.211973503312, 6348.269948955929, 6392.232932048184, 6414.454771344488, 6423.325550817097, 6421.935780642134, 6507.089007538853, 6534.085228068169, 6551.836204094745, 6569.0, 6287.591306565058, 6378.712957916812, 6381.744730210739, 6393.041043843275, 6413.166288382667, 6494.058361537645, 6506.446952008943, 6513.250976137805, 6553.344665533408, 6568.408843204134, 6197.0, 6251.093591429309, 6265.749370025148, 6301.558890877594, 6326.367363263821], "value": 6040.109630945133, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [795.3954994204403, 825.653225645229, 851.0298259983617, 1248.9625311240666, 1314.2114731161298, 31947.680523194762, 34467.965961021175, 39331.80334098329, 40394.59605403949, 40492.59507404925, 39987.60012399875, 40393.19213615726, 40452.0, 40475.0, 40589.173487193075, 6634.933650663504, 8052.51684898905, 8307.293880020206, 8489.575521223946, 8550.657973681045, 8382.958085209595, 8433.873491897626, 8437.409381343308, 8475.364347673918, 8489.872651910226, 7387.707151248537, 8397.076321604618, 8449.366297527678, 8483.533405662685, 8486.151384861527, 7792.532448053078, 8188.853560501535, 8415.200555947175, 8445.704400345958, 8446.239838414573, 8314.210150035737, 8347.123552027071, 8350.62422191003, 8363.58182090899, 8397.664093436255, 33174.6682533174, 34588.616455341755, 37445.0, 37532.624673753184, 37773.86678399653, 3566.197605538759, 3608.2061946371755, 3608.6932610728118, 3609.7112231021456, 3613.0, 30512.389752204832, 30602.693973060206, 30616.2345941351, 30677.84661076703, 30788.2302942426, 3059.311654877657, 3096.086654436924, 3102.829344386058, 3108.0520441265403, 3122.406742718899, 238883.0, 243100.0, 265996.67001665063, 297984.0, 298134.0, 47773.76113119412, 48039.519604804205, 48060.0, 48118.5188148121, 48198.5180148201, 49492.50507494951, 49592.75203624031, 49923.0, 49943.25085123726, 50241.24638130431, 13811.930940345435, 13824.963127765457, 13913.30433478322, 13920.58238252854, 13927.512537061155, 10965.945170274097, 11006.944965275123, 11021.228514003942, 11063.391513466837, 11114.388708621103, 13866.197394338873, 13966.067125903674, 13968.790468142986, 13969.580912572435, 14094.083884547677, 6192.225971753531, 6220.657863817533, 6243.625382477066, 6252.656103914328, 6276.843078922972, 967.7000129959735, 1007.6221416968637, 1015.1523477895892, 1056.3872953686844, 1062.0919114157439, 23135.421614459436, 23946.76053239445, 24335.26994190177, 24788.5042299153, 25167.62248566273, 1084.2139448899643, 1094.7482079121846, 1161.3264306702092, 1162.203890335123, 1169.2283093158474, 10589.735256618646, 11961.34212618297, 12714.55499057547, 12976.480940762453, 13000.0, 332945.0, 337196.0, 337714.0, 339741.0, 340702.0, 54294.45705542973, 54641.0, 54713.452865471634, 54791.0, 54846.17730734042, 1472.4699108320997, 1492.276246020701, 1522.9238538073052, 1528.396283468046, 1530.6249968757725, 23100.884495577415, 23168.6524702133, 23881.0, 24215.878920605282, 24441.38896527601, 38498.80750596229, 38498.845034649006, 38515.42226866655, 38584.61415385923, 38606.6139338603, 230523.0, 268963.65518172283, 269408.0, 269438.0, 270189.0, 34259.0, 35688.75089371859, 35702.64297357097, 35728.82135589409, 35765.64234357623, 46545.53454465412, 47531.28703069526, 49294.0, 49308.0, 49538.0, 25035.123770668666, 33284.50073248952, 33809.3238135231, 33916.83041584776, 34158.97523074312, 33316.500252495745, 33603.49594756032, 34241.486377703855, 34507.0, 34567.481487778205, 32346.0, 39895.60104398919, 42268.577314227696, 42309.0, 42441.787791060844, 32231.677683223807, 33344.333113338085, 34307.4853877197, 34540.65459345475, 34810.563260571515, 24294.27117186487, 26325.07862224813, 26475.205743827715, 26588.335291617863, 26626.866865665546], "value": 17752.267523328675, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [429.9123218257807, 435.5992486912041, 436.94319738434007, 443.6783332084239, 449.56588482740057, 21702.023408946596, 22490.999999999978, 22518.0, 23561.882190589065, 24491.26526204212, 24137.637935430983, 24683.629745553786, 24773.75226247737, 24840.50318993619, 24852.50294994099, 6210.596311239767, 6341.017142342921, 6358.078078678612, 6358.809235722936, 6384.138141350904, 4899.8040078396825, 6243.937560624404, 6312.305646378894, 6323.968380158093, 6324.335944725806, 5248.291480650101, 5320.680759154463, 6124.265088189423, 6174.259088909314, 6205.906911396332, 6166.599171053867, 6214.3474935131835, 6223.844403889939, 6230.657313847737, 6234.096056071867, 5553.139263414178, 5554.250176226198, 5564.415736347706, 5569.693666848321, 5572.219889215513, 22535.21126760572, 27816.30459238515, 28164.436711265866, 28347.716522834715, 28660.570091448644, 2206.5035367042447, 2218.589560931233, 2239.3729755668437, 2249.910003599854, 2253.008676182491, 18649.53376165593, 19100.33148839798, 19134.904325478423, 19144.712829307573, 19382.806171938242, 2115.2279418012467, 2132.893355332227, 2156.9352919412445, 2163.935081947544, 2173.282816670495, 146922.0, 152432.0, 184525.0, 192910.03544982325, 196529.0, 12474.505098980058, 13113.934430327787, 13415.396307166005, 13492.460301587827, 13711.846275356724, 16793.580160496087, 16847.32610695583, 16850.662986740197, 16854.494365169063, 16880.324787008627, 8685.392022558486, 8947.239484643878, 9022.14289642483, 9102.271818254523, 9106.635734570675, 8580.742577722678, 8607.827843443096, 8613.310935125175, 8648.308135349156, 8651.567421628893, 6966.79099627012, 7542.208068152875, 7614.048243969504, 8280.337572994145, 8362.080171181165, 1924.5862139639764, 1927.4291452466196, 2297.1270917051415, 2299.091858715831, 2455.9807679813034, 945.8581212818133, 951.871497347863, 955.2774203610516, 997.9002099789963, 999.1157825324494, 18392.26430942774, 18858.0, 19738.901305493382, 20384.388468345973, 21152.78847211539, 786.7128498098153, 964.7202311329776, 964.8070385922844, 1002.2883752535781, 1006.5907729179062, 7807.6486558105025, 9555.151612001207, 9860.506974651238, 10115.747106322253, 10452.947735261276, 529345.0, 533241.0, 549417.0, 555645.0, 564212.0, 110588.0, 127855.0, 127954.80112997249, 137312.0, 138892.0, 892.1346294094718, 932.6222879733718, 1109.755853712182, 1155.0586272188307, 1181.8463599732152, 18755.437336879917, 19124.52188695238, 20872.851993140524, 21631.567368652224, 23667.52664946657, 59903.70048149731, 60113.39886601078, 60481.0, 65325.346746531926, 65596.34403655902, 58829.70585147047, 66752.33247667461, 76105.0, 80072.3985520298, 95852.0, 8137.79655508617, 8800.38397312182, 10471.842922356323, 10488.423136727553, 10507.947460262956, 17792.644147117244, 18294.725579116057, 18308.26766929308, 18309.084545772923, 18471.53821154482, 15013.924930375279, 15022.924885375503, 15035.624109397353, 15099.698006040038, 15256.703180229804, 10714.946425268135, 13311.201327920357, 13522.86477135216, 13626.0, 13786.65533361674, 15164.497857853772, 15202.619934501283, 15488.838337124986, 15512.37950481968, 15538.766918496458, 10285.74285642835, 10323.122534584645, 10361.927614476985, 10371.740706482398, 10373.17014638812, 14887.10677359362, 14939.327730251947, 15052.54842354731, 15130.470433534774, 15207.315670795062], "value": 10936.427786950997, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1394.0868730981206, 1452.1287227663397, 1461.568837193028, 1468.9412423503068, 1481.5629389330147, 11625.651230463089, 11951.701207469818, 12037.458314375852, 12111.333876636792, 12347.320897350643, 11836.644900652973, 11947.58183463579, 12036.277823330607, 12043.458044388002, 12057.095717821177, 2541.9491610167784, 2572.8970841166424, 2586.741325867417, 2587.40489687372, 2589.7669209771116, 2124.925627603034, 2205.305328821424, 2474.2948259745976, 2515.534626094169, 2573.7040240372316, 2553.131935142051, 2571.7042540107846, 2577.8453292802396, 2582.883770230344, 2584.8061395395325, 2490.340059884132, 2510.3222130024883, 2513.1329691256487, 2518.40817407909, 2521.8360806547516, 2496.812739044579, 2501.5497210502103, 2502.1117503286255, 2512.849229046263, 2513.195777351248, 11934.522619095225, 11960.0, 12040.939795300967, 12041.458134383975, 12095.032397408186, 5697.829065128053, 6630.502712296597, 6649.401553860127, 6711.765088221938, 6728.058071869942, 3570.535830342039, 4095.89760255996, 4209.894752631178, 4234.259004674201, 4256.59562341577, 1254.5107408110844, 1256.6355756830508, 1261.186534685132, 1270.339423499775, 1285.6400207941697, 13270.73458530824, 16501.0, 16542.42101526453, 16624.750628740698, 16634.75047874283, 5108.390856880071, 5293.788248470018, 5471.2887324647545, 5541.556675465953, 5648.209250704905, 12273.140880138482, 12439.37803109841, 12442.253464792142, 12449.626511204497, 12456.501739930483, 6378.681065946684, 6487.124238227779, 6495.317991610813, 6506.674666266667, 6526.67366631676, 6054.212952316171, 6058.394160583906, 6103.206583144251, 6143.047827586731, 6150.292716337589, 4211.178820130045, 4278.379634952915, 4285.871423857289, 4326.502452218036, 4379.102284031829, 1081.259337353915, 1115.6374178392055, 1163.7497937943212, 1179.6166245970094, 1220.6704189868863, 682.8770821252223, 758.4804408979867, 831.1356189562787, 844.3329769482041, 852.26705033671, 6563.310852360529, 7091.2554181810165, 7198.435122799351, 7228.530145540523, 7522.210167932398, 877.9473231606124, 890.6214858685163, 926.2636204217663, 971.2958105373548, 983.735899369307, 4584.679072464895, 4688.507706690816, 4688.976555117202, 4937.950620493749, 4949.084419382426, 14785.964982451125, 15243.923780381248, 15290.311935962909, 15305.770413443808, 15314.693706125816, 14866.851331486763, 15409.845901541064, 15442.691146177014, 15458.84541154574, 15476.922615386851, 8546.401751877433, 8654.78363040929, 8707.216350528483, 8760.56197190138, 8783.858098447161, 6184.319724830355, 6925.515213935178, 7202.89195662076, 7397.889031664422, 7503.212162722835, 13651.931740341235, 13723.588292351245, 13748.175109493463, 13841.238731869844, 13943.372548235557, 14027.29863506841, 14090.929545352208, 14219.21794301282, 14264.215468149352, 14378.209198493765, 8823.558822058743, 8870.689525866565, 8968.148025937526, 8997.595108220277, 9018.909810901807, 10681.46592670379, 10728.785424291627, 10812.945935270272, 10833.133349332193, 10844.620438284623, 9139.177474027369, 9199.632014719336, 9251.352405331561, 9280.350375473652, 9385.577649005674, 9009.0, 9074.0, 9115.817683646423, 9155.633774649206, 9250.259979201783, 8184.672613095648, 8345.123762005145, 8360.372972027244, 8385.790355241168, 8392.538410387488, 6026.758929642765, 6130.220122295444, 6557.508186886098, 6569.605823650596, 6611.933880661132, 5240.737963101906, 6274.905876411766, 6637.966810165918, 6720.798376048726, 6756.054152418567], "value": 5454.75020441609, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [401.5703197578591, 403.3727553654069, 408.2345601996257, 408.2447472176474, 412.1859327827539, 4938.654294199408, 5655.915161272585, 6437.066625339329, 6473.187507497896, 6543.34566543346, 4982.103221420152, 6297.2128483939505, 6317.305096439388, 6348.936510634892, 6386.457151142147, 3588.5155504007016, 4269.3595960606, 4523.2762757958735, 4609.907801843961, 4652.627789776827, 3375.7468189885735, 4023.637872591466, 4646.465656449518, 4670.90658186836, 4677.625789936814, 3947.50656167979, 4247.08687632159, 4584.005957408538, 4605.838795642153, 4617.676762626601, 3527.6472352764517, 3782.092297848524, 3868.651821336079, 4163.875083747493, 4606.838760643362, 3473.2532505511435, 3951.723379363446, 3998.6001399860074, 4013.8795836124964, 4033.81847816846, 4963.726995015273, 6160.229971253593, 6173.506119510428, 6208.255009398856, 6212.471939885115, 5134.229865520165, 5375.172441369914, 5484.040292948758, 5511.559075274008, 5532.917006244909, 5457.918131227995, 5489.615726899118, 6157.78447754331, 6836.794896153124, 6850.177978642595, 4523.773811309421, 4529.1621050105505, 4530.304468841067, 4545.886352841172, 4553.408056952575, 5750.913736293917, 6018.893387314449, 7157.785266442015, 7211.531250468755, 7270.709171633128, 4036.8183431745642, 4539.16025535277, 4572.359869618256, 4577.1074640444795, 4580.290055041474, 4003.9589706676475, 4060.4315395844014, 4089.9591004090175, 4133.7106402551535, 4343.587359200871, 4711.882202944886, 4764.356811830358, 5399.894041323887, 5460.781568737286, 5462.863428414322, 4988.5260900214425, 6597.80206593803, 6776.322367763283, 6880.48862165306, 6917.13535808024, 1586.4056623093718, 1598.8720902327786, 1600.0559669794877, 1600.5438450041681, 1603.5029140966105, 1024.4212020208706, 1046.1630695443766, 1058.8358804385332, 1060.5068643081004, 1067.5249513966367, 5410.675359478444, 5757.942420575824, 6406.67966601668, 6408.903866441914, 6415.67921603918, 5564.4713752193475, 6207.6585787782105, 6886.622675464927, 7069.8939515907305, 7110.92889071113, 6250.781222657185, 6315.652639104894, 6340.904886426707, 6346.746130154835, 6384.509321983091, 3065.340951695396, 3196.7122958933805, 3559.483874838134, 3639.0, 3677.8414799338125, 6345.873082538324, 6436.871262574722, 7216.097987751531, 7238.348548630648, 7252.854942901113, 7035.417031168048, 7066.3993560546905, 7107.218205997337, 7171.928280717126, 7200.675969581382, 6581.605103693698, 7133.179684336368, 7208.477033038394, 7225.2774722528375, 7281.344678978918, 7021.5787052777, 7038.507304488637, 7055.894161587683, 7082.822929426806, 7114.608696521741, 6317.231175270931, 6791.252962174256, 6875.278095799869, 7016.298370163147, 7153.320434558708, 5716.399778023248, 6227.377262273918, 6765.167181146979, 7118.722008957291, 7212.783616491513, 4493.258612328929, 4522.819087236474, 4547.431571053618, 4556.202664533627, 4562.270036794097, 5768.567357448292, 6068.575199736153, 7047.330503602149, 7056.929430705627, 7124.430045596237, 5419.539339156216, 6434.324395938359, 6518.826611210027, 6561.311062338385, 6746.773564723728, 6748.898766518408, 6777.559458635173, 6779.661016949231, 6785.728570857111, 6790.45676345901, 6113.449789518787, 6293.811185664437, 6308.716107775253, 6666.2333831609985, 6729.327067293231, 6128.387161283836, 6765.932340676531, 6794.898076528955, 6827.590344579341, 6839.726410943706, 5433.212184233343, 6373.7131829066875, 6560.376764207584, 6588.604683718992, 6673.766418175341], "value": 4820.003358717981, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [719.6186021408652, 833.2001278772378, 837.7361131243662, 868.3096937934341, 877.1141147441083, 16742.91628541857, 17081.060541670202, 17200.483985480423, 17221.7416738749, 17236.741448878278, 15148.545543633682, 15151.84848151521, 15326.846731532709, 15394.846051539482, 15657.843421565782, 948.7865230323191, 968.7626531499757, 977.3891317926297, 978.1685567267801, 980.96076156954, 907.863820426938, 985.9014098590119, 998.1066945084145, 998.4358837256963, 999.4203362049993, 969.8399764038925, 987.9753006174867, 995.6863587969766, 997.8503224516344, 1009.9293049486539, 966.4732720667218, 978.0366339155961, 988.4859872866141, 989.5299732627017, 994.0854413939194, 724.543537571332, 743.8177646476594, 744.8547533231022, 745.5303159009843, 746.6453434618567, 17086.145692715316, 17092.2308496118, 17185.312587496486, 17189.7421538677, 17579.824201757947, 17261.395851145277, 17312.913435432867, 17436.65126697459, 17812.643747125116, 17835.10824458785, 14597.270136493133, 14784.186869722162, 14853.405863765436, 14860.331285092198, 14915.850841491554, 3552.6802587767224, 3569.946865674632, 3584.623614520464, 3585.354636165489, 3598.712103031751, 132259.33870330587, 132556.0, 132626.02121936358, 133590.33204834012, 135133.97299040522, 13702.588922332347, 13896.513622023384, 13938.094023888414, 13987.160770353812, 14096.154230745982, 23263.302100937, 25647.582989520823, 27005.32486687809, 27167.184984450498, 27240.774165162617, 8024.919750802534, 8057.073436554754, 8063.354931605575, 8096.068952070587, 8104.230098140667, 3896.883093507199, 3935.3900145477496, 3963.6829053676215, 4010.1378203685763, 4016.7991600419864, 4424.6239069678795, 4514.029483661028, 4515.480719717275, 4517.819287228474, 4529.932051019237, 919.3151102428733, 946.8769060022153, 963.7301555564453, 971.2763990826738, 972.5331840716495, 571.9771209151587, 646.253577118429, 663.8605892762574, 683.3235097253684, 698.5494356140262, 10435.582576697, 10464.581416743245, 10880.668472448444, 10937.507812148473, 11568.730972482046, 551.8647931256787, 626.2672672972633, 642.1587720086701, 677.695037233247, 680.8740383029156, 4778.4982576829625, 5399.190121481731, 5409.890972350659, 5497.862553436116, 5848.210491583582, 126889.70900799916, 128655.0, 130036.3498182522, 132398.67601324056, 133681.3211537611, 69448.0, 74259.0, 101775.49112254391, 108290.0, 108346.45826770816, 88056.559717201, 89852.5507372472, 93919.0, 98541.0, 98852.50573747086, 11948.103892207946, 12450.81323780126, 12632.873671263169, 12691.492340306653, 12983.935080324538, 33929.66070339364, 40346.79826600848, 40387.596124039555, 40412.3938140934, 40910.79544602258, 53506.0, 53648.73175634097, 53773.19340209978, 54065.72967135139, 54341.913161735756, 16125.67748645044, 16371.42700005494, 16704.248308826376, 16770.58073548171, 16850.410235641695, 17853.0, 18085.909570452062, 18518.537036574195, 19277.036148192816, 19337.8066219336, 16995.980241185567, 18098.638027239645, 18206.18072186781, 18251.0, 18402.171902264163, 16658.583535411228, 17149.485515434557, 17792.46622601324, 17845.37541186052, 17871.106444677975, 17133.82866171322, 17604.911975440555, 17642.0, 17856.910715446338, 18080.90959545194, 11211.88788112141, 11436.599719009795, 11902.642920712393, 12134.575289864812, 12275.93862030684, 11633.979111855191, 11985.101117416054, 12787.296698681661, 13347.399367028313, 14322.856771432153], "value": 8509.853131615793, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1562.6999999999996, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 115.0, 114.0, 113.0, 115.0, 113.0, 114.0, 115.0, 114.0, 125.0], "value": 114.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 102.0, 124.0, 185.0, 146.0, 99.0, 142.0, 149.0, 134.0, 127.0], "value": 134.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [214.0, 241.0, 195.0, 211.0, 212.0, 213.0, 253.0, 278.0, 246.0, 196.0], "value": 225.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 57.0, 60.0, 89.0, 51.0, 66.0, 84.0, 90.0, 91.0, 57.0], "value": 72.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 69.0, 81.0, 72.0, 69.0, 74.0, 87.0, 68.0, 85.0, 118.0], "value": 80.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 93.0, 90.0, 103.0, 138.0, 114.0, 137.0, 90.0, 90.0], "value": 103.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 97.0, 97.0, 97.0, 97.0, 97.0, 97.0, 96.0, 97.0], "value": 97.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 96.0, 143.0, 150.0, 91.0, 91.0, 94.0, 93.0, 124.0, 130.0], "value": 110.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 51.0, 51.0, 50.0, 51.0, 53.0, 59.0, 65.0, 60.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 62.0, 57.0, 55.0, 69.0, 51.0, 49.0, 55.0, 83.0, 83.0], "value": 62.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 111.0, 114.0, 89.0, 87.0, 91.0, 93.0, 90.0, 110.0, 93.0], "value": 97.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 138.0, 134.0, 138.0, 138.0, 135.0, 149.0, 137.0, 138.0, 136.0], "value": 138.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 186.0, 225.0, 239.0, 229.0, 185.0, 185.0, 183.0, 185.0, 181.0], "value": 198.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 65.0, 68.0, 83.0, 95.0, 95.0, 68.0, 68.0, 63.0, 69.0], "value": 74.3, "unit": "score"}]}]} 12:39:38 INFO - Return code: 0 12:39:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:39:38 INFO - # TBPL SUCCESS # 12:39:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:39:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:39:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:39:38 INFO - 2017-06-04 12:39:38.894 screenresolution[1677:12202] starting screenresolution argv=screenresolution get 12:39:38 INFO - 2017-06-04 12:39:38.899 screenresolution[1677:12202] Display 0: 1600x1200x32@60 12:39:38 INFO - 2017-06-04 12:39:38.905 screenresolution[1678:12205] starting screenresolution argv=screenresolution list 12:39:38 INFO - Available Modes on Display 0 12:39:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:39:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:39:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:39:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:39:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:39:39 INFO - Intel Iris: 12:39:39 INFO - Chipset Model: Intel Iris 12:39:39 INFO - Type: GPU 12:39:39 INFO - Bus: Built-In 12:39:39 INFO - VRAM (Dynamic, Max): 1536 MB 12:39:39 INFO - Vendor: Intel (0x8086) 12:39:39 INFO - Device ID: 0x0a2e 12:39:39 INFO - Revision ID: 0x0009 12:39:39 INFO - Displays: 12:39:39 INFO - Display: 12:39:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:39:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:39:39 INFO - Display Serial Number: 1600x1200 60 12:39:39 INFO - Main Display: Yes 12:39:39 INFO - Mirror: Off 12:39:39 INFO - Online: Yes 12:39:39 INFO - Rotation: Supported 12:39:39 INFO - Return code: 0 12:39:39 INFO - Running post-action listener: _resource_record_post_action 12:39:39 INFO - [mozharness: 2017-06-04 19:39:39.145396Z] Finished run-tests step (success) 12:39:39 INFO - Running post-run listener: _resource_record_post_run 12:39:39 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 34907136; Write bytes: 569323520; Read time: 219; Write time: 1760 12:39:39 INFO - TinderboxPrint: CPU usage
53.2% 12:39:39 INFO - TinderboxPrint: I/O read bytes / time
34,907,136 / 219 12:39:39 INFO - TinderboxPrint: I/O write bytes / time
569,323,520 / 1,760 12:39:39 INFO - TinderboxPrint: CPU idle
2,353.2 (46.8%) 12:39:39 INFO - TinderboxPrint: CPU system
1,017.3 (20.2%) 12:39:39 INFO - TinderboxPrint: CPU user
1,659.2 (33.0%) 12:39:39 INFO - TinderboxPrint: Swap in / out
277,778,432 / 0 12:39:39 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 247327232; Write bytes: 249096192; Read time: 18195; Write time: 546 12:39:39 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:39:39 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 30716928; Write bytes: 318588928; Read time: 194; Write time: 1206 12:39:39 INFO - Running post-run listener: _upload_blobber_files 12:39:39 INFO - Blob upload gear active. 12:39:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:39:39 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 12:39:39 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'] 12:39:39 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 12:39:40 INFO - (blobuploader) - INFO - Open directory for files ... 12:39:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:39:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:39:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:39:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:39:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:39:41 INFO - (blobuploader) - INFO - Done attempting. 12:39:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:39:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:39:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:39:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:39:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:39:42 INFO - (blobuploader) - INFO - Done attempting. 12:39:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:39:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:39:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:39:43 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:39:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:39:43 INFO - (blobuploader) - INFO - Done attempting. 12:39:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:39:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:39:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:39:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:39:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:39:45 INFO - (blobuploader) - INFO - Done attempting. 12:39:45 INFO - (blobuploader) - INFO - Iteration through files over. 12:39:45 INFO - Return code: 0 12:39:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:39:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:39:45 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f4da55a53559362aaf4afe4844aeda9a3b4d5a6277e12cf13283b61cc43a9ae4be7b11f2093003c838e3db4ed7efb005d155663f1d9d71f5bef0ef37530dbbde", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/88b70fbcc9c56e0b8093c94f5057096f5cd87cb84b30dbb8276f09010e1bf78ee844c681027f2677dbcb4c9f88ec7d279b343afc9c6034d58a6e2315a0c6948b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/965af9d9b0cd53cde1c70e6595b89ad3ac1fd2491792856951dece38b8c6806465b4acbadb5e42b01479d7cb95cd2365f29eb95a0b6ab97d252521092e908094", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3b610f28fad59e750b9b08d4054063b026ec68880a194309fc2fa27b84cc01d62ba2ee041d86cd44f5b32f167a12bbe36d4f5628e18f045b61fefc215702d2d0"} 12:39:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:39:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:39:45 INFO - Contents: 12:39:45 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f4da55a53559362aaf4afe4844aeda9a3b4d5a6277e12cf13283b61cc43a9ae4be7b11f2093003c838e3db4ed7efb005d155663f1d9d71f5bef0ef37530dbbde", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/88b70fbcc9c56e0b8093c94f5057096f5cd87cb84b30dbb8276f09010e1bf78ee844c681027f2677dbcb4c9f88ec7d279b343afc9c6034d58a6e2315a0c6948b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/965af9d9b0cd53cde1c70e6595b89ad3ac1fd2491792856951dece38b8c6806465b4acbadb5e42b01479d7cb95cd2365f29eb95a0b6ab97d252521092e908094", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3b610f28fad59e750b9b08d4054063b026ec68880a194309fc2fa27b84cc01d62ba2ee041d86cd44f5b32f167a12bbe36d4f5628e18f045b61fefc215702d2d0"} 12:39:45 INFO - Running post-run listener: copy_logs_to_upload_dir 12:39:45 INFO - Copying logs to upload dir... 12:39:45 INFO - mkdir: /builds/slave/test/build/upload/logs 12:39:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1340.117865 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 20 secs) (at 2017-06-04 12:39:45.269449) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-04 12:39:45.272541) ========= 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.qsywQHYM7A/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.BNOzEIWKIG/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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/f4da55a53559362aaf4afe4844aeda9a3b4d5a6277e12cf13283b61cc43a9ae4be7b11f2093003c838e3db4ed7efb005d155663f1d9d71f5bef0ef37530dbbde", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/88b70fbcc9c56e0b8093c94f5057096f5cd87cb84b30dbb8276f09010e1bf78ee844c681027f2677dbcb4c9f88ec7d279b343afc9c6034d58a6e2315a0c6948b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/965af9d9b0cd53cde1c70e6595b89ad3ac1fd2491792856951dece38b8c6806465b4acbadb5e42b01479d7cb95cd2365f29eb95a0b6ab97d252521092e908094", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3b610f28fad59e750b9b08d4054063b026ec68880a194309fc2fa27b84cc01d62ba2ee041d86cd44f5b32f167a12bbe36d4f5628e18f045b61fefc215702d2d0"} build_url:https://queue.taskcluster.net/v1/task/GQ0HQlC-RgGQg_O_S0g1GA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010991 build_url: 'https://queue.taskcluster.net/v1/task/GQ0HQlC-RgGQg_O_S0g1GA/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/f4da55a53559362aaf4afe4844aeda9a3b4d5a6277e12cf13283b61cc43a9ae4be7b11f2093003c838e3db4ed7efb005d155663f1d9d71f5bef0ef37530dbbde", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/88b70fbcc9c56e0b8093c94f5057096f5cd87cb84b30dbb8276f09010e1bf78ee844c681027f2677dbcb4c9f88ec7d279b343afc9c6034d58a6e2315a0c6948b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/965af9d9b0cd53cde1c70e6595b89ad3ac1fd2491792856951dece38b8c6806465b4acbadb5e42b01479d7cb95cd2365f29eb95a0b6ab97d252521092e908094", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3b610f28fad59e750b9b08d4054063b026ec68880a194309fc2fa27b84cc01d62ba2ee041d86cd44f5b32f167a12bbe36d4f5628e18f045b61fefc215702d2d0"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-04 12:39:45.308595) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-04 12:39:45.308950) ========= 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.qsywQHYM7A/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.BNOzEIWKIG/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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.005224 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-04 12:39:45.357898) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-04 12:39:45.358218) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-04 12:39:45.358566) ========= ========= Total master_lag: 0.18 =========