builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0123 starttime: 1496931011.68 results: success (0) revision: f223e1fd2044a026c740434df95f37a7f7accf48 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-08 07:10:11.680630) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-08 07:10:11.684204) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-08 07:10:11.684527) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-08 07:10:11.712455) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 07:10:11.712881) ========= 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.zH8OBTQTWa/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.fiWnHMYMv8/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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-08 07:10:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 159M=0s 2017-06-08 07:10:11 (159 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.128278 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 07:10:11.864500) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 07:10:11.867559) ========= 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.zH8OBTQTWa/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.fiWnHMYMv8/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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.030992 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 07:10:11.919898) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-08 07:10:11.920250) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f223e1fd2044a026c740434df95f37a7f7accf48 --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 f223e1fd2044a026c740434df95f37a7f7accf48 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zH8OBTQTWa/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.fiWnHMYMv8/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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-08 07:10:12,012 truncating revision to first 12 chars 2017-06-08 07:10:12,012 Setting DEBUG logging. 2017-06-08 07:10:12,012 attempt 1/10 2017-06-08 07:10:12,012 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f223e1fd2044?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-08 07:10:13,099 unpacking tar archive at: mozilla-central-f223e1fd2044/testing/mozharness/ program finished with exit code 0 elapsedTime=1.367498 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-08 07:10:13.314081) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-08 07:10:13.314436) ========= script_repo_revision: f223e1fd2044a026c740434df95f37a7f7accf48 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-08 07:10:13.314894) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-08 07:10:13.315259) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-08 07:10:13.334292) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 21 secs) (at 2017-06-08 07:10:13.334691) ========= /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.zH8OBTQTWa/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.fiWnHMYMv8/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 07:10:13 INFO - MultiFileLogger online at 20170608 07:10:13 in /builds/slave/test 07:10:13 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 07:10:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:10:13 INFO - {'append_to_log': False, 07:10:13 INFO - 'base_work_dir': '/builds/slave/test', 07:10:13 INFO - 'blob_upload_branch': 'Firefox', 07:10:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:10:13 INFO - 'branch': 'Firefox', 07:10:13 INFO - 'buildbot_json_path': 'buildprops.json', 07:10:13 INFO - 'config_files': ('talos/mac_config.py',), 07:10:13 INFO - 'default_actions': ('clobber', 07:10:13 INFO - 'read-buildbot-config', 07:10:13 INFO - 'download-and-extract', 07:10:13 INFO - 'populate-webroot', 07:10:13 INFO - 'create-virtualenv', 07:10:13 INFO - 'install', 07:10:13 INFO - 'setup-mitmproxy', 07:10:13 INFO - 'run-tests'), 07:10:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:10:13 INFO - 'download_minidump_stackwalk': True, 07:10:13 INFO - 'download_symbols': 'ondemand', 07:10:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:10:13 INFO - 'tooltool.py': '/tools/tooltool.py', 07:10:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:10:13 INFO - '/tools/misc-python/virtualenv.py')}, 07:10:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:10:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:10:13 INFO - 'gecko_profile': False, 07:10:13 INFO - 'gecko_profile_interval': 0, 07:10:13 INFO - 'installer_path': 'installer.exe', 07:10:13 INFO - 'log_level': 'info', 07:10:13 INFO - 'log_name': 'talos', 07:10:13 INFO - 'log_to_console': True, 07:10:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:10:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:10:13 INFO - 'opt_config_files': (), 07:10:13 INFO - 'pip_index': False, 07:10:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:10:13 INFO - 'cmd': ('bash', 07:10:13 INFO - '-c', 07:10:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:10:13 INFO - 'enabled': True, 07:10:13 INFO - 'halt_on_failure': False, 07:10:13 INFO - 'name': 'check_screen_resolution'},), 07:10:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:10:13 INFO - 'cmd': ('bash', 07:10:13 INFO - '-c', 07:10:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:10:13 INFO - 'enabled': True, 07:10:13 INFO - 'halt_on_failure': False, 07:10:13 INFO - 'name': 'check_screen_resolution'},), 07:10:13 INFO - 'run_cmd_checks_enabled': True, 07:10:13 INFO - 'suite': 'dromaeojs-e10s', 07:10:13 INFO - 'system_bits': '32', 07:10:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:10:13 INFO - 'title': 't-yosemite-r7-0123', 07:10:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:10:13 INFO - 'use_talos_json': True, 07:10:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:10:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:10:13 INFO - 'work_dir': 'build'} 07:10:13 INFO - [mozharness: 2017-06-08 14:10:13.542984Z] Running clobber step. 07:10:13 INFO - Running pre-action listener: _resource_record_pre_action 07:10:13 INFO - Running main action method: clobber 07:10:13 INFO - rmtree: /builds/slave/test/build 07:10:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:10:16 INFO - Running post-action listener: _resource_record_post_action 07:10:16 INFO - [mozharness: 2017-06-08 14:10:16.094242Z] Finished clobber step (success) 07:10:16 INFO - [mozharness: 2017-06-08 14:10:16.094428Z] Running read-buildbot-config step. 07:10:16 INFO - Running pre-action listener: _resource_record_pre_action 07:10:16 INFO - Running main action method: read_buildbot_config 07:10:16 INFO - Using buildbot properties: 07:10:16 INFO - { 07:10:16 INFO - "project": "", 07:10:16 INFO - "product": "firefox", 07:10:16 INFO - "who": "cbook@mozilla.com", 07:10:16 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 07:10:16 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 07:10:16 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 07:10:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 07:10:16 INFO - "stage_platform": "macosx64", 07:10:16 INFO - "basedir": "/builds/slave/test", 07:10:16 INFO - "buildnumber": 179, 07:10:16 INFO - "platform": "yosemite_r7", 07:10:16 INFO - "parent_task_id": "HJEzF5LWQKyemT9rsoGw7w", 07:10:16 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 07:10:16 INFO - "slavebuilddir": "test", 07:10:16 INFO - "taskId": "ZCQTFSS3Sgq7Jk4sGhoc3g", 07:10:16 INFO - "branch": "mozilla-central", 07:10:16 INFO - "script_repo_revision": "production", 07:10:16 INFO - "revision": "f223e1fd2044a026c740434df95f37a7f7accf48", 07:10:16 INFO - "slavename": "t-yosemite-r7-0123", 07:10:16 INFO - "repo_path": "mozilla-central" 07:10:16 INFO - } 07:10:16 INFO - Finding installer, test and symbols from parent task. 07:10:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZCQTFSS3Sgq7Jk4sGhoc3g'}, attempt #1 07:10:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZCQTFSS3Sgq7Jk4sGhoc3g'}, attempt #1 07:10:17 INFO - Task dependencies: GPHc1kMEQ2-fj0BF6w0V-g HJEzF5LWQKyemT9rsoGw7w 07:10:17 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HJEzF5LWQKyemT9rsoGw7w'}, attempt #1 07:10:17 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/HJEzF5LWQKyemT9rsoGw7w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:10:17 INFO - Set test_url: https://queue.taskcluster.net/v1/task/HJEzF5LWQKyemT9rsoGw7w/artifacts/public/build/test_packages.json 07:10:17 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/HJEzF5LWQKyemT9rsoGw7w/artifacts/public/build/target.crashreporter-symbols.zip 07:10:17 INFO - Running post-action listener: _resource_record_post_action 07:10:17 INFO - [mozharness: 2017-06-08 14:10:17.823262Z] Finished read-buildbot-config step (success) 07:10:17 INFO - [mozharness: 2017-06-08 14:10:17.823375Z] Running download-and-extract step. 07:10:17 INFO - Running pre-action listener: _resource_record_pre_action 07:10:17 INFO - Running main action method: download_and_extract 07:10:17 INFO - mkdir: /builds/slave/test/build/tests 07:10: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')]} 07:10:17 INFO - trying https://queue.taskcluster.net/v1/task/HJEzF5LWQKyemT9rsoGw7w/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 07:10:17 INFO - Downloading https://queue.taskcluster.net/v1/task/HJEzF5LWQKyemT9rsoGw7w/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 07:10:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HJEzF5LWQKyemT9rsoGw7w/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 07:10:18 INFO - Downloaded 1328 bytes. 07:10:18 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 07:10:18 INFO - Using the following test package requirements: 07:10:18 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:10:18 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 07:10:18 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 07:10:18 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:10:18 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 07:10:18 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:10:18 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 07:10:18 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:10:18 INFO - u'jsshell-mac.zip'], 07:10:18 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:10:18 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 07:10:18 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 07:10:18 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:10:18 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 07:10:18 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:10:18 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 07:10:18 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:10:18 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 07:10:18 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:10:18 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 07:10:18 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:10:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HJEzF5LWQKyemT9rsoGw7w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 07:10:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HJEzF5LWQKyemT9rsoGw7w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:10:18 INFO - Fetch https://queue.taskcluster.net/v1/task/HJEzF5LWQKyemT9rsoGw7w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 07:10:20 INFO - Content-Length response header: 59540051 07:10:20 INFO - Bytes received: 59540051 07:10:38 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 07:10:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HJEzF5LWQKyemT9rsoGw7w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 07:10:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HJEzF5LWQKyemT9rsoGw7w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:10:38 INFO - Fetch https://queue.taskcluster.net/v1/task/HJEzF5LWQKyemT9rsoGw7w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 07:10:42 INFO - Content-Length response header: 59540051 07:10:42 INFO - Bytes received: 59540051 07:10:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HJEzF5LWQKyemT9rsoGw7w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 07:10:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HJEzF5LWQKyemT9rsoGw7w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:10:59 INFO - Fetch https://queue.taskcluster.net/v1/task/HJEzF5LWQKyemT9rsoGw7w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 07:11:00 INFO - Content-Length response header: 14447568 07:11:00 INFO - Bytes received: 14447568 07:11:00 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')]} 07:11:00 INFO - trying https://queue.taskcluster.net/v1/task/HJEzF5LWQKyemT9rsoGw7w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:11:00 INFO - Downloading https://queue.taskcluster.net/v1/task/HJEzF5LWQKyemT9rsoGw7w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 07:11:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HJEzF5LWQKyemT9rsoGw7w/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 07:11:02 INFO - Downloaded 68129656 bytes. 07:11:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HJEzF5LWQKyemT9rsoGw7w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:11:02 INFO - mkdir: /builds/slave/test/properties 07:11:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:11:02 INFO - Writing to file /builds/slave/test/properties/build_url 07:11:02 INFO - Contents: 07:11:02 INFO - build_url:https://queue.taskcluster.net/v1/task/HJEzF5LWQKyemT9rsoGw7w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:11:02 INFO - Running post-action listener: _resource_record_post_action 07:11:02 INFO - Running post-action listener: set_extra_try_arguments 07:11:02 INFO - [mozharness: 2017-06-08 14:11:02.661804Z] Finished download-and-extract step (success) 07:11:02 INFO - [mozharness: 2017-06-08 14:11:02.661967Z] Running populate-webroot step. 07:11:02 INFO - Running pre-action listener: _resource_record_pre_action 07:11:02 INFO - Running main action method: populate_webroot 07:11:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:11:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:11:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:11:02 INFO - '--tppagecycles', 07:11:02 INFO - '7'], 07:11:02 INFO - 'tests': ['tsvgm']}}, 07:11:02 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:11:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:11:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:11:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:11:02 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:11:02 INFO - 'talos_options': ['--disable-e10s'], 07:11:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:11:02 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:11:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:11:02 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:11:02 INFO - 'talos_options': ['--disable-e10s'], 07:11:02 INFO - 'tests': ['damp', 'tps']}, 07:11:02 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:11:02 INFO - 'tests': ['damp', 'tps']}, 07:11:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:11:02 INFO - 'tests': ['dromaeo_dom']}, 07:11:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:11:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:11:02 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 07:11:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 07:11:02 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 07:11:02 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 07:11:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:11:02 INFO - 'tests': ['a11yr', 07:11:02 INFO - 'ts_paint', 07:11:02 INFO - 'tpaint', 07:11:02 INFO - 'sessionrestore', 07:11:02 INFO - 'sessionrestore_no_auto_restore', 07:11:02 INFO - 'tabpaint']}, 07:11:02 INFO - 'other-e10s': {'tests': ['a11yr', 07:11:02 INFO - 'ts_paint', 07:11:02 INFO - 'tpaint', 07:11:02 INFO - 'sessionrestore', 07:11:02 INFO - 'sessionrestore_no_auto_restore', 07:11:02 INFO - 'tabpaint']}, 07:11:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:11:02 INFO - 'ts_paint', 07:11:02 INFO - 'tpaint', 07:11:02 INFO - 'sessionrestore', 07:11:02 INFO - 'sessionrestore_no_auto_restore', 07:11:02 INFO - 'tabpaint']}, 07:11:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:11:02 INFO - 'ts_paint', 07:11:02 INFO - 'tpaint', 07:11:02 INFO - 'sessionrestore', 07:11:02 INFO - 'sessionrestore_no_auto_restore', 07:11:02 INFO - 'tabpaint']}, 07:11:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:11:02 INFO - 'tests': ['a11yr', 07:11:02 INFO - 'ts_paint', 07:11:02 INFO - 'tpaint', 07:11:02 INFO - 'sessionrestore', 07:11:02 INFO - 'sessionrestore_no_auto_restore', 07:11:02 INFO - 'tabpaint']}, 07:11:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:11:02 INFO - 'tests': ['a11yr', 07:11:02 INFO - 'ts_paint', 07:11:02 INFO - 'tpaint', 07:11:02 INFO - 'sessionrestore', 07:11:02 INFO - 'sessionrestore_no_auto_restore', 07:11:02 INFO - 'tabpaint']}, 07:11:02 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 07:11:02 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 07:11:02 INFO - 'bloom_basic_ref']}, 07:11:02 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 07:11:02 INFO - 'talos_options': ['--mitmproxy', 07:11:02 INFO - 'mitmproxy-recording-1.mp', 07:11:02 INFO - '--firstNonBlankPaint'], 07:11:02 INFO - 'tests': ['Quantum_1']}, 07:11:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:11:02 INFO - 'tests': ['tsvgx', 07:11:02 INFO - 'tsvgr_opacity', 07:11:02 INFO - 'tart', 07:11:02 INFO - 'tscrollx', 07:11:02 INFO - 'cart', 07:11:02 INFO - 'tsvg_static']}, 07:11:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:11:02 INFO - 'tsvgr_opacity', 07:11:02 INFO - 'tart', 07:11:02 INFO - 'tscrollx', 07:11:02 INFO - 'cart', 07:11:02 INFO - 'tsvg_static']}, 07:11:02 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:11:02 INFO - 'talos_options': ['--disable-e10s'], 07:11:02 INFO - 'tests': ['tp5o']}, 07:11:02 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:11:02 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:11:02 INFO - 'talos_options': ['--disable-e10s', 07:11:02 INFO - '--xperf_path', 07:11:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:11:02 INFO - 'tests': ['tp5n']}, 07:11:02 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:11:02 INFO - 'talos_options': ['--xperf_path', 07:11:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:11:02 INFO - 'tests': ['tp5n']}}, 07:11:02 INFO - 'talos.zip': {'path': '', 07:11:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:11:02 INFO - Running post-action listener: _resource_record_post_action 07:11:02 INFO - [mozharness: 2017-06-08 14:11:02.684538Z] Finished populate-webroot step (success) 07:11:02 INFO - [mozharness: 2017-06-08 14:11:02.684713Z] Running create-virtualenv step. 07:11:02 INFO - Running pre-action listener: _resource_record_pre_action 07:11:02 INFO - Running main action method: create_virtualenv 07:11:02 INFO - Creating virtualenv /builds/slave/test/build/venv 07:11:02 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 07:11:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:11:02 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 07:11:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:11:02 INFO - Using real prefix '/tools/python27' 07:11:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:11:03 INFO - Installing distribute.............................................................................................................................................................................................done. 07:11:06 INFO - Installing pip.................done. 07:11:06 INFO - Return code: 0 07:11:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:11:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:11:06 INFO - Reading from file tmpfile_stdout 07:11:06 INFO - Output received: 07:11:06 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) 07:11:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:11:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100ce8c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb530592ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a3ed30>, '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 0x100a08cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a2fbe8>, '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.zH8OBTQTWa/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.fiWnHMYMv8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 07:11:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:11:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:11:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zH8OBTQTWa/Render', 07:11:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:07 INFO - 'HOME': '/Users/cltbld', 07:11:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:07 INFO - 'LOGNAME': 'cltbld', 07:11:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:07 INFO - 'MOZ_NO_REMOTE': '1', 07:11:07 INFO - 'NO_EM_RESTART': '1', 07:11:07 INFO - 'PAGER': '/bin/cat', 07:11:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:07 INFO - 'PWD': '/builds/slave/test', 07:11:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:07 INFO - 'SHELL': '/bin/bash', 07:11:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fiWnHMYMv8/Listeners', 07:11:07 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 07:11:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:07 INFO - 'USER': 'cltbld', 07:11:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:07 INFO - 'XPC_FLAGS': '0x0', 07:11:07 INFO - 'XPC_SERVICE_NAME': '0', 07:11:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:07 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 07:11:07 INFO - Cleaning up... 07:11:07 INFO - Return code: 0 07:11:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:11:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:08 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 0x100ce8c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb530592ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a3ed30>, '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 0x100a08cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a2fbe8>, '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.zH8OBTQTWa/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.fiWnHMYMv8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 07:11:08 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 07:11:08 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 07:11:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zH8OBTQTWa/Render', 07:11:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:08 INFO - 'HOME': '/Users/cltbld', 07:11:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:08 INFO - 'LOGNAME': 'cltbld', 07:11:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:08 INFO - 'MOZ_NO_REMOTE': '1', 07:11:08 INFO - 'NO_EM_RESTART': '1', 07:11:08 INFO - 'PAGER': '/bin/cat', 07:11:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:08 INFO - 'PWD': '/builds/slave/test', 07:11:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:08 INFO - 'SHELL': '/bin/bash', 07:11:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fiWnHMYMv8/Listeners', 07:11:08 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 07:11:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:08 INFO - 'USER': 'cltbld', 07:11:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:08 INFO - 'XPC_FLAGS': '0x0', 07:11:08 INFO - 'XPC_SERVICE_NAME': '0', 07:11:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:08 INFO - Downloading/unpacking psutil>=3.1.1 07:11:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:11:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:11:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:11:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:11:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:11:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:11:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:11:10 INFO - Installing collected packages: psutil 07:11:10 INFO - Running setup.py install for psutil 07:11:10 INFO - building 'psutil._psutil_osx' extension 07:11:10 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 07:11:11 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 07:11:11 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 07:11:11 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 07:11:11 INFO - building 'psutil._psutil_posix' extension 07:11:11 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 07:11:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:11:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:11:11 INFO - ^ 07:11:11 INFO - 1 warning generated. 07:11:11 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 07:11:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:11:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:11:11 INFO - Successfully installed psutil 07:11:11 INFO - Cleaning up... 07:11:11 INFO - Return code: 0 07:11:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:11: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')]} 07:11:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11: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')]} 07:11:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:11 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 0x100ce8c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb530592ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a3ed30>, '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 0x100a08cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a2fbe8>, '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.zH8OBTQTWa/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.fiWnHMYMv8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 07:11:11 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 07:11:11 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 07:11:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zH8OBTQTWa/Render', 07:11:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:11 INFO - 'HOME': '/Users/cltbld', 07:11:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:11 INFO - 'LOGNAME': 'cltbld', 07:11:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:11 INFO - 'MOZ_NO_REMOTE': '1', 07:11:11 INFO - 'NO_EM_RESTART': '1', 07:11:11 INFO - 'PAGER': '/bin/cat', 07:11:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:11 INFO - 'PWD': '/builds/slave/test', 07:11:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:11 INFO - 'SHELL': '/bin/bash', 07:11:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fiWnHMYMv8/Listeners', 07:11:11 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 07:11:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:11 INFO - 'USER': 'cltbld', 07:11:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:11 INFO - 'XPC_FLAGS': '0x0', 07:11:11 INFO - 'XPC_SERVICE_NAME': '0', 07:11:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:11:12 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 07:11:12 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 07:11:12 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 07:11:12 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 07:11:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:11:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:11:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:11:13 INFO - Installing collected packages: mozsystemmonitor 07:11:13 INFO - Running setup.py install for mozsystemmonitor 07:11:13 INFO - Successfully installed mozsystemmonitor 07:11:13 INFO - Cleaning up... 07:11:13 INFO - Return code: 0 07:11:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:11:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100ce8c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb530592ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a3ed30>, '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 0x100a08cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a2fbe8>, '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.zH8OBTQTWa/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.fiWnHMYMv8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 07:11:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:11:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:11:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zH8OBTQTWa/Render', 07:11:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:13 INFO - 'HOME': '/Users/cltbld', 07:11:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:13 INFO - 'LOGNAME': 'cltbld', 07:11:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:13 INFO - 'MOZ_NO_REMOTE': '1', 07:11:13 INFO - 'NO_EM_RESTART': '1', 07:11:13 INFO - 'PAGER': '/bin/cat', 07:11:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:13 INFO - 'PWD': '/builds/slave/test', 07:11:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:13 INFO - 'SHELL': '/bin/bash', 07:11:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fiWnHMYMv8/Listeners', 07:11:13 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 07:11:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:13 INFO - 'USER': 'cltbld', 07:11:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:13 INFO - 'XPC_FLAGS': '0x0', 07:11:13 INFO - 'XPC_SERVICE_NAME': '0', 07:11:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:14 INFO - Downloading/unpacking jsonschema==2.5.1 07:11:14 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 07:11:14 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 07:11:14 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 07:11:14 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 07:11:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:11:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:11:15 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 07:11:15 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 07:11:15 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 07:11:15 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 07:11:15 INFO - Downloading functools32-3.2.3-2.tar.gz 07:11:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:11:15 INFO - warning: no files found matching '*.txt' 07:11:15 INFO - no previously-included directories found matching 'build' 07:11:15 INFO - no previously-included directories found matching 'dist' 07:11:15 INFO - no previously-included directories found matching '.git*' 07:11:15 INFO - Installing collected packages: jsonschema, functools32 07:11:15 INFO - Running setup.py install for functools32 07:11:16 INFO - warning: no files found matching '*.txt' 07:11:16 INFO - no previously-included directories found matching 'build' 07:11:16 INFO - no previously-included directories found matching 'dist' 07:11:16 INFO - no previously-included directories found matching '.git*' 07:11:16 INFO - Successfully installed jsonschema functools32 07:11:16 INFO - Cleaning up... 07:11:16 INFO - Return code: 0 07:11:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:11:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100ce8c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb530592ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a3ed30>, '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 0x100a08cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a2fbe8>, '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.zH8OBTQTWa/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.fiWnHMYMv8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 07:11:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:11:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:11:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zH8OBTQTWa/Render', 07:11:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:16 INFO - 'HOME': '/Users/cltbld', 07:11:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:16 INFO - 'LOGNAME': 'cltbld', 07:11:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:16 INFO - 'MOZ_NO_REMOTE': '1', 07:11:16 INFO - 'NO_EM_RESTART': '1', 07:11:16 INFO - 'PAGER': '/bin/cat', 07:11:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:16 INFO - 'PWD': '/builds/slave/test', 07:11:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:16 INFO - 'SHELL': '/bin/bash', 07:11:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fiWnHMYMv8/Listeners', 07:11:16 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 07:11:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:16 INFO - 'USER': 'cltbld', 07:11:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:16 INFO - 'XPC_FLAGS': '0x0', 07:11:16 INFO - 'XPC_SERVICE_NAME': '0', 07:11:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:11:16 INFO - Cleaning up... 07:11:16 INFO - Return code: 0 07:11:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:11:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100ce8c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb530592ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a3ed30>, '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 0x100a08cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a2fbe8>, '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.zH8OBTQTWa/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.fiWnHMYMv8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 07:11:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:11:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:11:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zH8OBTQTWa/Render', 07:11:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:16 INFO - 'HOME': '/Users/cltbld', 07:11:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:16 INFO - 'LOGNAME': 'cltbld', 07:11:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:16 INFO - 'MOZ_NO_REMOTE': '1', 07:11:16 INFO - 'NO_EM_RESTART': '1', 07:11:16 INFO - 'PAGER': '/bin/cat', 07:11:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:16 INFO - 'PWD': '/builds/slave/test', 07:11:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:16 INFO - 'SHELL': '/bin/bash', 07:11:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fiWnHMYMv8/Listeners', 07:11:16 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 07:11:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:16 INFO - 'USER': 'cltbld', 07:11:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:16 INFO - 'XPC_FLAGS': '0x0', 07:11:16 INFO - 'XPC_SERVICE_NAME': '0', 07:11:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:16 INFO - Downloading/unpacking blobuploader==1.2.4 07:11:16 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 07:11:16 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 07:11:16 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 07:11:16 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 07:11:18 INFO - Downloading blobuploader-1.2.4.tar.gz 07:11:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:11:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:11: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 07:11: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 07:11: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 07:11: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 07:11:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:11:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:11: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 07:11: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 07:11: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 07:11: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 07:11:18 INFO - Downloading docopt-0.6.1.tar.gz 07:11:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:11:19 INFO - Installing collected packages: blobuploader, requests, docopt 07:11:19 INFO - Running setup.py install for blobuploader 07:11:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:11:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:11:19 INFO - Running setup.py install for requests 07:11:19 INFO - Running setup.py install for docopt 07:11:19 INFO - Successfully installed blobuploader requests docopt 07:11:19 INFO - Cleaning up... 07:11:19 INFO - Return code: 0 07:11:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:11:19 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')]} 07:11:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:19 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')]} 07:11:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:19 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 0x100ce8c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb530592ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a3ed30>, '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 0x100a08cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a2fbe8>, '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.zH8OBTQTWa/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.fiWnHMYMv8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 07:11:19 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 07:11:19 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 07:11:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zH8OBTQTWa/Render', 07:11:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:19 INFO - 'HOME': '/Users/cltbld', 07:11:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:19 INFO - 'LOGNAME': 'cltbld', 07:11:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:19 INFO - 'MOZ_NO_REMOTE': '1', 07:11:19 INFO - 'NO_EM_RESTART': '1', 07:11:19 INFO - 'PAGER': '/bin/cat', 07:11:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:19 INFO - 'PWD': '/builds/slave/test', 07:11:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:19 INFO - 'SHELL': '/bin/bash', 07:11:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fiWnHMYMv8/Listeners', 07:11:19 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 07:11:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:19 INFO - 'USER': 'cltbld', 07:11:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:19 INFO - 'XPC_FLAGS': '0x0', 07:11:19 INFO - 'XPC_SERVICE_NAME': '0', 07:11:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:11:20 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-xNaMNX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:11:20 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-ZlZYDx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:11:20 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-3f5V4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:11:20 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-KLhpxw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:11:20 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-hEq3N1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:11:20 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-w3B2rB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:11:20 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-J5fps1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:11:20 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-SNylnV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:11:20 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-oFB3Pf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:11:21 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-TZjbym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:11:21 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-XreWXH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:11:21 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-V5JzsQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:11:21 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-MmTwEC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:11:21 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-iHLtJT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:11:21 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-J5ZHcV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:11:21 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-BfqOs3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:11:21 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-RmhDbR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:11:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:11:21 INFO - Running setup.py install for manifestparser 07:11:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:11:22 INFO - Running setup.py install for mozcrash 07:11:22 INFO - Running setup.py install for mozdebug 07:11:22 INFO - Running setup.py install for mozdevice 07:11:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:11:22 INFO - Running setup.py install for mozfile 07:11:22 INFO - Running setup.py install for mozhttpd 07:11:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:11:22 INFO - Running setup.py install for mozinfo 07:11:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:11:22 INFO - Running setup.py install for mozInstall 07:11:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:11:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:11:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:11:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:11:23 INFO - Running setup.py install for mozleak 07:11:23 INFO - Running setup.py install for mozlog 07:11:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:11:23 INFO - Running setup.py install for moznetwork 07:11:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:11:23 INFO - Running setup.py install for mozprocess 07:11:23 INFO - Running setup.py install for mozprofile 07:11:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:11:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:11:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:11:23 INFO - Running setup.py install for mozrunner 07:11:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:11:24 INFO - Running setup.py install for mozscreenshot 07:11:24 INFO - Running setup.py install for moztest 07:11:24 INFO - Running setup.py install for mozversion 07:11:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:11:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:11:24 INFO - Cleaning up... 07:11:24 INFO - Return code: 0 07:11:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:11:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:24 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 0x100ce8c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb530592ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a3ed30>, '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 0x100a08cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a2fbe8>, '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.zH8OBTQTWa/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.fiWnHMYMv8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 07:11:24 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 07:11:24 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 07:11:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zH8OBTQTWa/Render', 07:11:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:24 INFO - 'HOME': '/Users/cltbld', 07:11:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:24 INFO - 'LOGNAME': 'cltbld', 07:11:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:24 INFO - 'MOZ_NO_REMOTE': '1', 07:11:24 INFO - 'NO_EM_RESTART': '1', 07:11:24 INFO - 'PAGER': '/bin/cat', 07:11:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:24 INFO - 'PWD': '/builds/slave/test', 07:11:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:24 INFO - 'SHELL': '/bin/bash', 07:11:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fiWnHMYMv8/Listeners', 07:11:24 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 07:11:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:24 INFO - 'USER': 'cltbld', 07:11:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:24 INFO - 'XPC_FLAGS': '0x0', 07:11:24 INFO - 'XPC_SERVICE_NAME': '0', 07:11:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:11:24 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-AeWowg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:11:24 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)) 07:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:11:24 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-2sIxKT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:11:24 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)) 07:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:11:24 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-IE6vY1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:11:25 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)) 07:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:11:25 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-f3qDce-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:11:25 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)) 07:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:11:25 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-qUmVLf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:11:25 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)) 07:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:11:25 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-_V8Ndq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:11:25 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)) 07:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:11:25 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-wKgVMe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:11:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:11:25 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-hMVw10-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:11:25 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)) 07:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:11:25 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-fUMAFg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:11:25 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)) 07:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:11:25 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-oqZQG1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:11:25 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)) 07:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:11:25 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-X6DQYe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:11:25 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)) 07:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:11:25 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-gwd8bn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:11:26 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)) 07:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:11:26 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-hk3ujX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:11:26 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)) 07:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:11:26 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-3OoKUJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:11:26 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)) 07:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:11:26 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-IZqGd3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:11:26 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)) 07:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:11:26 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-Ofqog6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:11:26 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)) 07:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:11:26 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-CnPhZe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:11:26 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)) 07:11:26 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)) 07:11:26 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)) 07:11:26 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)) 07:11:26 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)) 07:11:26 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)) 07:11:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:11: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 07:11: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 07:11: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 07:11: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 07:11:27 INFO - Downloading blessings-1.6.tar.gz 07:11:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:11:28 INFO - Installing collected packages: blessings 07:11:28 INFO - Running setup.py install for blessings 07:11:28 INFO - Successfully installed blessings 07:11:28 INFO - Cleaning up... 07:11:28 INFO - Return code: 0 07:11:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:11:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:11:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:11:28 INFO - Reading from file tmpfile_stdout 07:11:28 INFO - Current package versions: 07:11:28 INFO - blessings == 1.6 07:11:28 INFO - blobuploader == 1.2.4 07:11:28 INFO - docopt == 0.6.1 07:11:28 INFO - functools32 == 3.2.3-2 07:11:28 INFO - jsonschema == 2.5.1 07:11:28 INFO - manifestparser == 1.1 07:11:28 INFO - mozInstall == 1.12 07:11:28 INFO - mozcrash == 1.0 07:11:28 INFO - mozdebug == 0.1 07:11:28 INFO - mozdevice == 0.51 07:11:28 INFO - mozfile == 1.2 07:11:28 INFO - mozhttpd == 0.7 07:11:28 INFO - mozinfo == 0.10 07:11:28 INFO - mozleak == 0.1 07:11:28 INFO - mozlog == 3.4 07:11:28 INFO - moznetwork == 0.27 07:11:28 INFO - mozprocess == 0.25 07:11:28 INFO - mozprofile == 0.28 07:11:28 INFO - mozrunner == 6.13 07:11:28 INFO - mozscreenshot == 0.1 07:11:28 INFO - mozsystemmonitor == 0.3 07:11:28 INFO - moztest == 0.8 07:11:28 INFO - mozversion == 1.4 07:11:28 INFO - psutil == 3.1.1 07:11:28 INFO - requests == 1.2.3 07:11:28 INFO - wsgiref == 0.1.2 07:11:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:11:28 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')]} 07:11:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:28 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')]} 07:11:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:28 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 0x100ce8c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb530592ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a3ed30>, '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 0x100a08cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a2fbe8>, '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.zH8OBTQTWa/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.fiWnHMYMv8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 07:11:28 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 07:11:28 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 07:11:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zH8OBTQTWa/Render', 07:11:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:28 INFO - 'HOME': '/Users/cltbld', 07:11:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:28 INFO - 'LOGNAME': 'cltbld', 07:11:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:28 INFO - 'MOZ_NO_REMOTE': '1', 07:11:28 INFO - 'NO_EM_RESTART': '1', 07:11:28 INFO - 'PAGER': '/bin/cat', 07:11:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:28 INFO - 'PWD': '/builds/slave/test', 07:11:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:28 INFO - 'SHELL': '/bin/bash', 07:11:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fiWnHMYMv8/Listeners', 07:11:28 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 07:11:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:28 INFO - 'USER': 'cltbld', 07:11:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:28 INFO - 'XPC_FLAGS': '0x0', 07:11:28 INFO - 'XPC_SERVICE_NAME': '0', 07:11:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:28 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)) 07:11:28 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)) 07:11:28 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)) 07:11:28 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)) 07:11:28 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)) 07:11:28 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)) 07:11:28 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)) 07:11:28 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)) 07:11:28 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)) 07:11:28 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 07:11: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 07:11: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 07:11: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 07:11: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 07:11:30 INFO - Downloading marionette_driver-1.4.0.tar.gz 07:11:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 07:11:30 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)) 07:11:30 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)) 07:11:30 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)) 07:11:30 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)) 07:11:30 INFO - Installing collected packages: marionette-driver 07:11:30 INFO - Running setup.py install for marionette-driver 07:11:30 INFO - Successfully installed marionette-driver 07:11:30 INFO - Cleaning up... 07:11:30 INFO - Return code: 0 07:11:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:11:30 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')]} 07:11:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:30 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')]} 07:11:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:30 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 0x100ce8c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb530592ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a3ed30>, '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 0x100a08cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a2fbe8>, '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.zH8OBTQTWa/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.fiWnHMYMv8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 07:11:30 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 07:11:30 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 07:11:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zH8OBTQTWa/Render', 07:11:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:30 INFO - 'HOME': '/Users/cltbld', 07:11:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:30 INFO - 'LOGNAME': 'cltbld', 07:11:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:30 INFO - 'MOZ_NO_REMOTE': '1', 07:11:30 INFO - 'NO_EM_RESTART': '1', 07:11:30 INFO - 'PAGER': '/bin/cat', 07:11:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:30 INFO - 'PWD': '/builds/slave/test', 07:11:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:30 INFO - 'SHELL': '/bin/bash', 07:11:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fiWnHMYMv8/Listeners', 07:11:30 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 07:11:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:30 INFO - 'USER': 'cltbld', 07:11:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:30 INFO - 'XPC_FLAGS': '0x0', 07:11:30 INFO - 'XPC_SERVICE_NAME': '0', 07:11:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:11:30 INFO - Cleaning up... 07:11:30 INFO - Return code: 0 07:11:30 INFO - Running post-action listener: _resource_record_post_action 07:11:30 INFO - Running post-action listener: _start_resource_monitoring 07:11:30 INFO - Starting resource monitoring. 07:11:30 INFO - [mozharness: 2017-06-08 14:11:30.937758Z] Finished create-virtualenv step (success) 07:11:30 INFO - [mozharness: 2017-06-08 14:11:30.938792Z] Running install step. 07:11:30 INFO - Running pre-action listener: _resource_record_pre_action 07:11:30 INFO - Running main action method: install 07:11:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:11:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:11:31 INFO - Reading from file tmpfile_stdout 07:11:31 INFO - Detecting whether we're running mozinstall >=1.0... 07:11:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:11:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:11:31 INFO - Reading from file tmpfile_stdout 07:11:31 INFO - Output received: 07:11:31 INFO - Usage: mozinstall [options] installer 07:11:31 INFO - Options: 07:11:31 INFO - -h, --help show this help message and exit 07:11:31 INFO - -d DEST, --destination=DEST 07:11:31 INFO - Directory to install application into. [default: 07:11:31 INFO - "/builds/slave/test"] 07:11:31 INFO - --app=APP Application being installed. [default: firefox] 07:11:31 INFO - mkdir: /builds/slave/test/build/application 07:11:31 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'] 07:11:31 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 07:11:52 INFO - Reading from file tmpfile_stdout 07:11:52 INFO - Output received: 07:11:52 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:11:52 INFO - Running post-action listener: _resource_record_post_action 07:11:52 INFO - [mozharness: 2017-06-08 14:11:52.709318Z] Finished install step (success) 07:11:52 INFO - [mozharness: 2017-06-08 14:11:52.709428Z] Running setup-mitmproxy step. 07:11:52 INFO - Running pre-action listener: _resource_record_pre_action 07:11:52 INFO - Running main action method: setup_mitmproxy 07:11:52 INFO - Skipping: mitmproxy is not required 07:11:52 INFO - Running post-action listener: _resource_record_post_action 07:11:52 INFO - [mozharness: 2017-06-08 14:11:52.709934Z] Finished setup-mitmproxy step (success) 07:11:52 INFO - [mozharness: 2017-06-08 14:11:52.710047Z] Running run-tests step. 07:11:52 INFO - Running pre-action listener: _resource_record_pre_action 07:11:52 INFO - Running main action method: run_tests 07:11:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:11:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:11:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:11:52 INFO - 2017-06-08 07:11:52.722 screenresolution[1623:7488] starting screenresolution argv=screenresolution get 07:11:52 INFO - 2017-06-08 07:11:52.725 screenresolution[1623:7488] Display 0: 1600x1200x32@60 07:11:52 INFO - 2017-06-08 07:11:52.730 screenresolution[1624:7490] starting screenresolution argv=screenresolution list 07:11:52 INFO - Available Modes on Display 0 07:11:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:11:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:11:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:11:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:11:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:11:52 INFO - Intel Iris: 07:11:52 INFO - Chipset Model: Intel Iris 07:11:52 INFO - Type: GPU 07:11:52 INFO - Bus: Built-In 07:11:52 INFO - VRAM (Dynamic, Max): 1536 MB 07:11:52 INFO - Vendor: Intel (0x8086) 07:11:52 INFO - Device ID: 0x0a2e 07:11:52 INFO - Revision ID: 0x0009 07:11:52 INFO - Displays: 07:11:52 INFO - Display: 07:11:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:11:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:11:52 INFO - Display Serial Number: 1600x1200 60 07:11:52 INFO - Main Display: Yes 07:11:52 INFO - Mirror: Off 07:11:52 INFO - Online: Yes 07:11:52 INFO - Rotation: Supported 07:11:52 INFO - Return code: 0 07:11:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:11:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:11:52 INFO - Python 2.7.3 07:11:52 INFO - Return code: 0 07:11:52 INFO - grabbing minidump binary from tooltool 07:11:52 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')]} 07:11:52 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 0x100a3ed30>, '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 0x100a08cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a2fbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:11:52 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 07:11:52 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 07:11:52 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 07:11:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:11:53 INFO - Return code: 0 07:11:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:11:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:11:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:11:53 INFO - ENV: RUST_BACKTRACE is now 1 07:11:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:11:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:11:53 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/HJEzF5LWQKyemT9rsoGw7w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0123', '--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 07:11:53 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/HJEzF5LWQKyemT9rsoGw7w/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0123 --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 07:11:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zH8OBTQTWa/Render', 07:11:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:53 INFO - 'HOME': '/Users/cltbld', 07:11:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:53 INFO - 'LOGNAME': 'cltbld', 07:11:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:11:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:11:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:53 INFO - 'MOZ_NO_REMOTE': '1', 07:11:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:11:53 INFO - 'NO_EM_RESTART': '1', 07:11:53 INFO - 'PAGER': '/bin/cat', 07:11:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:53 INFO - 'PWD': '/builds/slave/test', 07:11:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:11:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:53 INFO - 'RUST_BACKTRACE': '1', 07:11:53 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 07:11:53 INFO - 'SHELL': '/bin/bash', 07:11:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fiWnHMYMv8/Listeners', 07:11:53 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 07:11:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:53 INFO - 'USER': 'cltbld', 07:11:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:53 INFO - 'XPC_FLAGS': '0x0', 07:11:53 INFO - 'XPC_SERVICE_NAME': '0', 07:11:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:53 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/HJEzF5LWQKyemT9rsoGw7w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0123', '--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 07:11:53 INFO - mozversion application_buildid: 20170608054555 07:11:53 INFO - mozversion application_changeset: f223e1fd2044a026c740434df95f37a7f7accf48 07:11:53 INFO - mozversion application_display_name: Nightly 07:11:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:11:53 INFO - mozversion application_name: Firefox 07:11:53 INFO - mozversion application_remotingname: firefox 07:11:53 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:11:53 INFO - mozversion application_vendor: Mozilla 07:11:53 INFO - mozversion application_version: 55.0a1 07:11:53 INFO - mozversion platform_buildid: 20170608054555 07:11:53 INFO - mozversion platform_changeset: f223e1fd2044a026c740434df95f37a7f7accf48 07:11:53 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:11:53 INFO - mozversion platform_version: 55.0a1 07:11:53 INFO - using testdate: 1496931113 07:11:53 INFO - actual date: 1496931113 07:11:53 INFO - starting webserver on 'localhost:49228' 07:11:53 INFO - SUITE-START | Running 2 tests 07:11:53 INFO - TEST-START | dromaeo_css 07:11:53 INFO - operating with platform_type : mac_ 07:11:53 INFO - Initialising browser for dromaeo_css test... 07:11:53 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpBVnxc7/profile 07:11:53 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 07:12:27 INFO - TEST-INFO | 1634: killed by SIGTERM 07:12:27 INFO - Browser initialized. 07:12:27 INFO - Running cycle 1/1 for dromaeo_css test... 07:12:27 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpBVnxc7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:12:29 INFO - PID 1641 | 2017-06-08 07:12:29.413 plugin-container[1642:7968] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.tsm.portname' 07:12:29 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:12:29 INFO - PID 1641 | 2017-06-08 07:12:29.413 plugin-container[1642:7968] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa00f, name = 'com.apple.CFPasteboardClient' 07:12:29 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:12:29 INFO - PID 1641 | 2017-06-08 07:12:29.413 plugin-container[1642:7968] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:12:29 INFO - PID 1641 | 2017-06-08 07:12:29.921 plugin-container[1643:8018] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 07:12:29 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:12:29 INFO - PID 1641 | 2017-06-08 07:12:29.922 plugin-container[1643:8018] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 07:12:29 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:12:29 INFO - PID 1641 | 2017-06-08 07:12:29.922 plugin-container[1643:8018] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:12:39 INFO - PID 1641 | RSS: Main: 253014016 07:12:39 INFO - PID 1641 | 07:15:42 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49228/tests/dromaeo/cssquery-ext.html) 07:15:44 INFO - PID 1641 | RSS: Main: 263962624 07:15:44 INFO - PID 1641 | 07:18:46 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-ext.html (next: http://localhost:49228/tests/dromaeo/cssquery-jquery.html) 07:18:46 INFO - PID 1641 | RSS: Main: 274862080 07:18:46 INFO - PID 1641 | 07:21:48 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49228/tests/dromaeo/cssquery-mootools.html) 07:21:49 INFO - PID 1641 | RSS: Main: 276770816 07:21:49 INFO - PID 1641 | 07:24:50 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49228/tests/dromaeo/cssquery-prototype.html) 07:24:51 INFO - PID 1641 | RSS: Main: 281661440 07:24:51 INFO - PID 1641 | 07:27:54 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49228/tests/dromaeo/cssquery-yui.html) 07:27:55 INFO - PID 1641 | RSS: Main: 279609344 07:27:55 INFO - PID 1641 | 07:30:56 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-yui.html (next: http://localhost:49228/tests/dromaeo/cssquery-dojo.html) 07:30:57 INFO - PID 1641 | RSS: Main: 278798336 07:30:57 INFO - PID 1641 | 07:30:57 INFO - PID 1641 | __start_tp_report 07:30:57 INFO - PID 1641 | _x_x_mozilla_page_load 07:30:57 INFO - PID 1641 | _x_x_mozilla_page_load_details 07:30:57 INFO - PID 1641 | |i|pagename|runs| 07:30:57 INFO - PID 1641 | |0;dojo.html;3594.9820250898742;3612.9277414451694;3636.745427820052;3655.579608345041;3656.652618001289;5070.214116811891;5803.709814509273;6171.938280617203;6286.717097730601;6310.621362718234;6243.500519958404;6248.562600617958;6265.498760099193;6280.748770049193;6357.459615932639;5420.83737487876;5479.972600137014;5498.752556134964;5503.807366742165;5513.2005859150395;5563.13771365437;5614.971925140389;5623.718814059301;5626.156076588524;5639.097744360903;5360.651557648779;5426.647267927572;5526.364468086141;5560.666360018412;5619.129034999562;5473.31583552056;5505.642133261365;5555.500004999529;5563.749631266603;5573.637713548606;5477.5070243677865;5481.397046324901;5502.944970550283;5517.282753242052;5537.529310008654;4745.789823594996;6210.875782484325;6300.653464059475;6312.305646378894;6354.904676429857;5422.295101636762;6615.17310336208;6669.933300666979;6790;6806.761763338309;6948.096747422853;6954.304569543056;6954.582725036514;6974.26770189133;6987.615681137536;6418.133551970518;6445.677716114223;6446.871062578816;6455.903161452582;6461.838454038687;6305.180326557518;6592.011198320194;6895.482838787111;6933.37599616037;7026.156861176692;6505.544611877218;6520.119783829123;6551.770688025897;6690;6751.527393082535;6736.46108311334;6758.087658166085;6862.073620061228;6862.828429289308;6878.3465570770695;6751.966240168767;6822.044913712084;6825.249222585511;6825.35159159879;6860;6362.204724409449;6684.997250412477;6713.69788359525;6835.487338449636;6852.588844669336;5342.5993050521365;5372.704501252469;5386.91919621206;5399.514043736082;5411.594130440233;3746.3069332173636;4799;4801.95198048015;4814.831480898151;4906.939085584063;5682.948654498931;6192.721327540272;6344.333844946306;6360.491160707133;6388.073729309224;6477.384648458389;6811.93188068113;6888.276730943279;6915.51591388598;6922.5154239203775;6361.173047503888;6377.489800815923;6390.712417941204;6402.743890244431;6404.935950640527;5852.6195797273895;6073.240844894388;6102.7558897644485;6111.388861113942;6128.295246046672;5582.91625625616;6955.1653801544135;6980.650967451708;7049.436045116481;7080.8937865933085;6848.931510685029;6917.342852429011;6917.481188910751;6956.2000369958105;6981.02265682815;7044.8591028178125;7079.469039822137;7081.893771593327;7086.716531338689;7109;6552.148220731275;6646.335366463509;6691.5315927884585;6800.591964482147;6807.829804254933;6907.447404207752;6945.68744406513;6998.3608523566445;7001.0198572198915;7005.719771209095;4987.9525299687475;6652.101966234496;6698.296678848846;6704.4301234395625;6705.430038446591;5798.56510761703;6547.607143571401;6564.737410503717;6617.272100069084;6708.02733603615;6466.547341686037;6597;6620.973749068902;6626.5030122741955;6636.668166591748;6723.899141512783;6734.6295953721055;6734.797956061326;6743.5953842769595;6757.797266082025;6328.019157030668;6394.840128996626;6500.967495162494;6572.047053177167;6590.7693230736695 07:30:57 INFO - PID 1641 | |1;ext.html;784.4587234807982;809.9554524501152;814.6904176412962;1059.5443959097586;1228.5147366790113;30166.547501787463;37802.24395512088;37846.0538486538;37931.43102853459;37933;38213.23573528528;38409.61590384095;38479.422808657895;38483.80758096213;38507.61492385082;7599.164091949881;7759.107702614215;8034.196580341977;8280.213379728915;8291.502509849399;8166.510009399425;8226.712065077723;8241.217084376975;8244.464109832872;8262.339012878985;8209.876851847228;8238.876416853753;8261.256486916176;8271.25558699717;8285.627146778379;8236.752897413087;8239.464434811747;8243.83512329756;8254.587270636473;8260;6694.966525167343;7641.274078962545;8073.354131669453;8114.148014458515;8196.631151598192;31206;35231.94304170853;36590.26819463622;36780.44829327562;36909.5236190552;3587.031501494582;3599.1362073102528;3608.548931383577;3612.8916132516065;3615.023943535231;30368.937087202063;30535;30567;30582.08253752391;30586.38827223443;3025.7125573070525;3059.83170925599;3081.337512434815;3087.4751292280143;3099.5970523831757;251562.74218628788;253601.7319913407;288122;311193;312375;42480;43691.12617747691;47175.292370614465;47317.526824732;47390.76304618455;49148.262776058385;49234;49234.507654923706;49286.75356623194;49554.75222623864;13797.862021379859;13857.307134643228;13889.236092015037;13976.860231397759;14076.225807580682;11172.944135279271;11230.7192320191;11246.325220486797;11265.098792096613;11273.985341319321;13841.754242118257;13854;13881.861181388056;13909.860901390857;14045.859541404456;6104;6171.691415429301;6175.660338681326;6180.165677633552;6182.072689096582;956.9951550871557;966.8694726212012;967.6371360739722;1016.5933626549439;1023.9436830974367;23421.765782341958;23736.525269494512;24734.639594822456;24933.002679892965;24970.625440618405;981.7005813227034;1096.9396683182501;1105.2189818206648;1145.7593905279819;1160.7910576096215;11978.401079946143;12462.03469273507;12618.55835045769;12643.431045602785;12689.428975696115;333992;335512;335973;336703.3164834209;336717;42828.78585607115;53217.467825322026;53593.73203133959;53633.463665363626;53716.46283537193;1487.5239923224465;1501.4144483651603;1525.4889611979888;1532.6551525906582;1547.81426228851;22392.32823015312;22946.885265573565;23175.536489270457;24079.51840963206;24218;37190.44214336841;37511.062223444635;37547.43678844765;48900;50595;290981;294306;294464;294796;295006;36073;36281;36313.818430907675;36418.63581364152;36442.27115457729;42749.572504275806;42837.57162428336;42859.571404286806;44540.55459445494;44647;27901.860490697418;29809.552856706734;29811.403771924874;30849;31486;29921.952731654288;30415.54376684307;30592.388152237276;31460.68539314669;31673.683263167994;36983;37061;37883.62116378801;37943.430848536744;37979.62020379761;30035;30092.849535752182;30778.846105769328;30946.690533095283;31365.372692546476;23308.6503702448;24347.026118955044;24441.38896527601;24725.752742472345;24825.503489930463 07:30:57 INFO - PID 1641 | |2;jquery.html;487.381026096857;488.68299931684106;498.9223277720125;507.6218067945527;525.5690333926182;21804.018819153138;21931.561368772615;22702.88648556759;22738.204162854283;24822.62766058506;24212.515749684997;24926.878290476925;24968;24976.875115624443;24977;6364.204474440695;6520.869582608345;6533.575317604341;6533.901991470132;6536.215654121488;6244;6415.262244841856;6463.644499552523;6479.254885688159;6484.254310754276;6328.778492752732;6371.044343348489;6377.968110159442;6378.776742814002;6403.263624683151;5785.1033089870925;5923.614965027259;6367.395097465734;6370.617762934239;6373.362663733613;5186.936677981005;5484.451554844483;5804.448577385142;5834.883302333929;5845.7369418376275;29337.853310733524;30060.8496957516;30132.397352053056;30142.095737127704;30468.54297185544;1851.3057603398724;2014.405750303664;2233.4974630708125;2235.2847088931385;2237.9328620141423;19387.709184362247;20022.099005544784;20031.599368012703;20041.498962525908;20066.698999514872;2184.748753893307;2214.490667146565;2253.5492901419616;2296.8851557422063;2297.0811675329833;170745;171493;179334;181175;198847;14785.852141478663;15079.396824126912;15152.908714646983;15268.3892644293;15621.750259979175;20348.79651203469;20595.970201489865;20610.278640247518;20620.896895515427;20676.966151692355;7999;9208.585613647401;9775.657851975147;9823.361481503822;9844.96627854065;8885.333599979898;8908.554572271361;8912.55437228136;8963.327750418614;8978.68574599899;9422.528873556295;9512.809743805223;9526.809463810685;9545.570449329796;9581.377210481296;2249.2127755285846;2647.444036752265;2669.8665066746585;2680.289723223347;2814.746672799458;906.40177482861;945.5272363818091;951.2437612098261;976.3117002513211;996.1582462818907;21658.78341216599;21702.674459883114;22167.78077205737;22403.887980560317;22426.663600046013;977.7653363192783;996.9850452243222;1016.9949150254349;1017.1608423050865;1028.290479569095;10854.511546980404;10863.45682715861;10936.453177341102;11471.082313414907;11525.308481491138;550614;554208;554461;561754;563161;109045;143172.28413857863;144115;144270.55729442782;145168;1144.1533265383557;1155.8959693627614;1176.4235524592889;1181.6868529839599;1181.6986668399504;18852.717209242146;19021.144048517574;19202.903985479985;19269.8073019268;19924.60150797005;59337.7033114849;62396.5041398339;67475.3252467469;70931.58136837337;71560;94173.5291323539;98802;103151.48424257831;103323.4833825826;104044;11579.131565132817;11821.054315654555;12003.459844307201;12061;12075.758484830078;17713.380031699344;18521.444356669323;18574.164162612444;19043.333483328017;19299;15440.691186176438;15607.765883511529;15660.903736738319;15675.92162039228;15784.052956822627;13726.382312796148;13958.651033723832;13987.23070231147;14101.858981410054;14140.43438262458;15482.612934676723;15526.767098493307;15828.920855395649;15835.287412066715;15870.2064896757;9947.552360143665;11707.473163707484;11823.408829558315;11853.288802671867;11943.462544185359;14209.50266740659;14663.780043299146;14996.47512337063;15067.623309417353;15097.32062057232 07:30:57 INFO - PID 1641 | |3;mootools.html;1347.4879545772606;1394.281944798429;1399.2304232672027;1407.6832712639664;1415.8867290616752;12871.935640321797;13073.346332683352;13481.865181348183;13513.662158446044;13933.93033034836;13486;13699.657508562266;13716.31418429077;13756.03707740456;13762;2672.6258323834677;2740.369714965559;2743.862806859659;2746.8763905624196;2749.2989287731702;2629.421527263999;2666.6000099984963;2682.28919336376;2684.288663504163;2687.8387296762257;2644.9471010579873;2645.470905818834;2700.891964321425;2703.2836298380944;2730.5085084684747;2629.6449979252843;2650.5229058769414;2664.2273740615196;2666.546687063204;2667.293167310664;2200.5378870437225;2295.644175152854;2649.1787545860843;2655.6282120503147;2662.786977041832;12925.224486530838;13327.73344533104;13335.799963000563;13338.266395348252;13357.13178643385;7308.123025237006;7590.544567325978;7591.013168288143;7612.972248746404;7624.466287359887;4629.791659375336;4758.262469317261;4802.30366596845;4874.073925954058;4918.606511479073;1362.7002059546883;1385.9466805227999;1412.78808178774;1413.8162038934975;1420.133718431766;15962.920185399114;19776.604467910704;19856.70214946777;19934.10296536645;19972.400827975183;5702.716888700009;6043.700604370081;6089.330173680892;6486.254080780771;6551.44312733423;13058.869411305956;14132.576022719335;14181.078229915023;14185.716285674434;14240.857591424161;7092.9290707093305;7331.780046598504;7352.375048120863;7372.2259162788205;7378;6633.635150066792;6750.493712971547;6811.318868113149;6838.965805171042;6845.383915447534;4862.367892173995;5013.799448022111;5080.085584594808;5091.210862316347;5101.846944591668;1450.1226757811583;1450.2966061460183;1465.1282486920265;1467.1637166815026;1480.7408703477063;810.7810891059381;812.6180695073306;837.677494164751;839.8152406470567;858.7981824271292;6952.374286314256;6955.513114081966;7092.5744455331815;7318.377937875341;7405.222451642608;650.1970066967292;658.338369938209;672.5527524196452;679.11375654771;691.2983321928247;4386.627136693353;4518.073794872043;4636.6058884995155;4745.881352966135;5320.893582128336;17565;17939.641207175784;17999.460016199537;18381.448556543324;18393.80440271378;17826;17859.43110872695;18019.099045047697;18344.908275458536;18386.724199137025;7553.509021913668;9197;9398.20115290208;9531.99914009033;9544.236461083097;7584.582847943416;7593.810154746176;7657.540547567164;7683.462157648911;8076;15905.761413579037;16043.2780524879;16155.59611009687;16156.434524791575;16266.186690665183;15950.840491594936;16202.189890505664;16522.42171523991;16551.00693958366;16827.579310517336;10774.622888198875;10784.838227426751;10941.45292735344;10953.507092181031;10963.177761668067;12314.014878809852;12533.435995380412;12541.31022793755;12542.372881355714;12566.057545684293;10089.697309080739;10331.896681033093;10374.533146008296;10388;10495.10791582724;9921.107100360714;9952.950235248778;10026.298159128792;10049.748756280858;10068.496575171066;9096.363254572116;9304.813903721752;9316.114969077928;9331.206847418045;9348.719538413858;6386;6526.053722210348;6920.653967301715;6991.370776630128;7048.823779405556;6840.589564626139;6932.410745086724;7066.611336376343;7249.673764680497;7251.927480725125 07:30:57 INFO - PID 1641 | |4;prototype.html;449.1511044126601;452.5474525474525;455.12161528250465;456.634692246203;467.8830292426893;5898.469137777599;5938.495227905628;6095.085737139426;6128.774245150971;6623.867522649544;5871.207387002753;6203.472704820096;6452.128962590061;6503.869922601545;6531.412172904437;4784.473707892138;4828.5412885775795;4829.468758436569;4841.540053694911;4842.2252439609665;4141.917161656765;4440.733555986635;4811.59101476375;4812.518748125177;4812.639052071091;3778.527684039495;4507.729536227836;4561.612262957636;4760.92879102201;4789.10830222062;3911.061345277127;4247.851325203603;4712;4775.785089670973;4795.304680821261;4154.896127596804;4224.640905523034;4226.901005738499;4227.894302642428;4238.682098842568;6013.75944962201;6019.779418011387;6346.904796428057;6387.297397286295;6390.488760899117;5110.71891045996;5674.574406919456;5747.913781293284;5758.251427314464;5758.971205143989;6512.902306465407;6909.930900690979;7003.894941575881;7026.824329391755;7048.189458212269;4826.686265392738;4832.855014349575;4833.564979151894;4848.442429120626;4854.320395144682;6046.96976515119;7108.964455177743;7380.1512826025155;7399.112106547195;7447.218042105609;4766.356541866873;4878.536539028786;4888.315635810989;4908.9263661045115;4933.580645645088;4193.5387107418155;5004.124278251292;5009.023240468074;5028.069807085701;5059.342285502935;4737.95262047382;5689.6586204827845;5700.230468886647;5740.1344563016355;5744.138379243063;5868.941310586925;6532.771353002622;7021.789346319619;7024.332688394595;7051;1679.4205998930595;1714.9142542873055;1716.1247763640383;1716.2362748577013;1723.83623555762;1114.437209209344;1128.3624752015085;1149.1438878035917;1151.9539218431337;1157.6584907452323;5468.535174510212;6528.67356632176;6536.869262614721;6553.675702946679;6577.671116444254;6588.835279117964;7249;7262.237465066092;7268.200497945222;7272.418206543463;6009.1587177795145;6461.450776683941;6478.870422591522;6487.740490380426;6511.38141876521;3593.443016332473;3757.5866654667966;3760.5675347335405;3793.9241215175543;3821.1402434452025;7338.339549440576;7340.44946629005;7344.779656610311;7347;7381.151167615685;6056.454919057306;7327.377172940253;7394.112706475257;7436.516626419266;7481.925180748123;6710;7305.3790427813165;7361.490894366642;7368.152662443673;7395.7041718330665;6264.655443950444;7164.283571642794;7166.820829479305;7203.2076471589135;7214.819629509304;4834.758262086952;5897.705114744332;5917.497012753965;7054.470914681317;7148.17795953472;6189.297943065693;7391.595596836584;7422.406207503494;7454.813629659302;7522.962385188258;4117.106131179691;4481.7535035573355;4963.726995015309;4963.897564022486;4983.975080124576;6377.489800816016;6395.680215989275;7271.854562908607;7291.744788932362;7327.963360183165;6759.662016899234;6923.065386173003;7033.015377847208;7039;7046.541974771829;6730.461563074844;6991.196012458632;7024.929750702428;7033.894491582528;7081.362677359062;6671;6822.692978815866;6856.794296171123;6866.313368663094;6887.7244910203035;5575.853296480803;6649.800505984828;6939.82650433723;6982.33667801558;7020.333068358498;5601.80393686219;6158.815235542941;6161.938380616316;6613.64420293829;6781.491388146008 07:30:57 INFO - PID 1641 | |5;yui.html;799.156889481597;821.1419067074906;824.8556502612041;853.7961474321208;868.8957325120984;16925.32298708052;17158.742618860728;17352.913235433836;17396.913015434922;17403.82596174038;15614.921925390385;15817.209139543034;16125.516234512983;16153.43462978796;16424.75362869555;992.7567745902246;1019.8827134879473;1022.274185328414;1024.416082832782;1025.9538320775546;978.1001478639669;992.171536766797;1003.4631472162395;1007.2294694558668;1009.45489435705;1039.937603743774;1049.029647575993;1064.0476773287903;1067.7917806027829;1068.64200492835;1031.5615863258085;1041.5521325829852;1043.4834756795397;1046.246702374297;1046.5604446132604;758.9165191828895;772.5326177662546;773.993808049539;776.8601651702692;778.4084096086958;17035.659286814196;17907.73138402925;17917.462476125736;17957.191926363215;18094.457166285032;14603.269836508238;15120.924395377953;17271.91364043184;17390.043547604877;17890.731639025424;12861.935690321581;14877.18175500347;14905.925470372687;14911;14914.105153690705;3247.1070455624654;3514.1390359361862;3580.6419358063986;3602.837872295753;3651.835667394974;119829;151727.24136379358;152710;153349;155163;11814.94092529532;12920.353982300847;13451.46194152223;13487.32563371847;13674.384652690653;21564.784352156592;26930.865345673144;26963.865180673973;27018.459630807272;27067.864660676572;7788.065432148065;7799.49303295284;7809.648565814438;7837.333826624801;7911.446198766146;3500.1949551603275;3981.402789581586;4055.858044968412;4059.715819892579;4064.552899181088;4624.583787459145;4681.227597446383;4710.199266124767;4719.622430205575;4935.728534930613;1009.3842755918884;1018.4805749067881;1027.8047171037479;1036.8548403223556;1046.638909576195;655.131950166032;664.7706541243264;666.4901350466897;675.7972608217515;688.1604442580044;9449.582562615526;10598.629047983284;11598.246114002563;13160.934195328962;14220.791232745332;667.7662818013754;668.4151367553391;686.0224180542707;689.6448329110513;691;4637.976810115928;5713.6857472838565;5721.084626459747;5779.162021506858;5894.8821023579285;125047.3747631256;130212;130762.34618827036;131146;131158;88661.55669221612;107548.46225768821;109096;110432;112158;91396.5430172845;91919;92392.53803730938;94971.52514237523;99650;8565.61454734526;11196.552137914392;11704.70738231551;11713.828617138219;11755.588554400554;44374.55625443704;45316.640500785026;46226.76886615546;47161.7641911802;47378.76310618425;60051;61821.38178618156;62059.37940620536;62132.68933655303;62731;14692.118472891661;14721.705565888837;14951.700965980837;15112.244387780787;15224.162671052756;16188.514344569681;16399.016059036498;16465.588360291087;16576.917115414344;16787.74818377701;14913.685886274487;15264.618384540032;15269.6182595436;15304.923475382551;15394.15332156742;13535.661608459868;14019.439222431396;14065.578032659037;14083.788743168656;14114.78827817563;14776.704465910407;14801.481948131765;14930.552083437515;15035.699286014436;15108.848911510744;9858.915519293016;9939.751506212402;10472.842907356244;11190.776184476103;11307.943460282646;9218.585163667516;10831.566737330733;11373.374464404536;11850.288982661068;12069.396530173632 07:30:57 INFO - PID 1641 | __end_tp_report 07:30:57 INFO - PID 1641 | __start_cc_report 07:30:57 INFO - PID 1641 | _x_x_mozilla_cycle_collect,450 07:30:57 INFO - PID 1641 | __end_cc_report 07:30:57 INFO - PID 1641 | __startTimestamp1496932257055__endTimestamp 07:30:57 INFO - PID 1641 | 07:30:57 INFO - PID 1641 | ------- Summary: start ------- 07:30:57 INFO - PID 1641 | Number of tests: 6 07:30:57 INFO - PID 1641 | 07:30:57 INFO - PID 1641 | [#0] dojo.html Cycles:165 Average:6225.78 Median:6461.84 stddev:781.53 (12.1%) stddev-sans-first:756.18 07:30:57 INFO - PID 1641 | Values: 3595.0 3612.9 3636.7 3655.6 3656.7 5070.2 5803.7 6171.9 6286.7 6310.6 6243.5 6248.6 6265.5 6280.7 6357.5 5420.8 5480.0 5498.8 5503.8 5513.2 5563.1 5615.0 5623.7 5626.2 5639.1 5360.7 5426.6 5526.4 5560.7 5619.1 5473.3 5505.6 5555.5 5563.7 5573.6 5477.5 5481.4 5502.9 5517.3 5537.5 4745.8 6210.9 6300.7 6312.3 6354.9 5422.3 6615.2 6669.9 6790.0 6806.8 6948.1 6954.3 6954.6 6974.3 6987.6 6418.1 6445.7 6446.9 6455.9 6461.8 6305.2 6592.0 6895.5 6933.4 7026.2 6505.5 6520.1 6551.8 6690.0 6751.5 6736.5 6758.1 6862.1 6862.8 6878.3 6752.0 6822.0 6825.2 6825.4 6860.0 6362.2 6685.0 6713.7 6835.5 6852.6 5342.6 5372.7 5386.9 5399.5 5411.6 3746.3 4799.0 4802.0 4814.8 4906.9 5682.9 6192.7 6344.3 6360.5 6388.1 6477.4 6811.9 6888.3 6915.5 6922.5 6361.2 6377.5 6390.7 6402.7 6404.9 5852.6 6073.2 6102.8 6111.4 6128.3 5582.9 6955.2 6980.7 7049.4 7080.9 6848.9 6917.3 6917.5 6956.2 6981.0 7044.9 7079.5 7081.9 7086.7 7109.0 6552.1 6646.3 6691.5 6800.6 6807.8 6907.4 6945.7 6998.4 7001.0 7005.7 4988.0 6652.1 6698.3 6704.4 6705.4 5798.6 6547.6 6564.7 6617.3 6708.0 6466.5 6597.0 6621.0 6626.5 6636.7 6723.9 6734.6 6734.8 6743.6 6757.8 6328.0 6394.8 6501.0 6572.0 6590.8 07:30:57 INFO - PID 1641 | 07:30:57 INFO - PID 1641 | [#1] ext.html Cycles:180 Average:45350.21 Median:19409.49 stddev:80207.06 (413.2%) stddev-sans-first:80362.27 07:30:57 INFO - PID 1641 | Values: 784.5 810.0 814.7 1059.5 1228.5 30166.5 37802.2 37846.1 37931.4 37933.0 38213.2 38409.6 38479.4 38483.8 38507.6 7599.2 7759.1 8034.2 8280.2 8291.5 8166.5 8226.7 8241.2 8244.5 8262.3 8209.9 8238.9 8261.3 8271.3 8285.6 8236.8 8239.5 8243.8 8254.6 8260.0 6695.0 7641.3 8073.4 8114.1 8196.6 31206.0 35231.9 36590.3 36780.4 36909.5 3587.0 3599.1 3608.5 3612.9 3615.0 30368.9 30535.0 30567.0 30582.1 30586.4 3025.7 3059.8 3081.3 3087.5 3099.6 251562.7 253601.7 288122.0 311193.0 312375.0 42480.0 43691.1 47175.3 47317.5 47390.8 49148.3 49234.0 49234.5 49286.8 49554.8 13797.9 13857.3 13889.2 13976.9 14076.2 11172.9 11230.7 11246.3 11265.1 11274.0 13841.8 13854.0 13881.9 13909.9 14045.9 6104.0 6171.7 6175.7 6180.2 6182.1 957.0 966.9 967.6 1016.6 1023.9 23421.8 23736.5 24734.6 24933.0 24970.6 981.7 1096.9 1105.2 1145.8 1160.8 11978.4 12462.0 12618.6 12643.4 12689.4 333992.0 335512.0 335973.0 336703.3 336717.0 42828.8 53217.5 53593.7 53633.5 53716.5 1487.5 1501.4 1525.5 1532.7 1547.8 22392.3 22946.9 23175.5 24079.5 24218.0 37190.4 37511.1 37547.4 48900.0 50595.0 290981.0 294306.0 294464.0 294796.0 295006.0 36073.0 36281.0 36313.8 36418.6 36442.3 42749.6 42837.6 42859.6 44540.6 44647.0 27901.9 29809.6 29811.4 30849.0 31486.0 29922.0 30415.5 30592.4 31460.7 31673.7 36983.0 37061.0 37883.6 37943.4 37979.6 30035.0 30092.8 30778.8 30946.7 31365.4 23308.7 24347.0 24441.4 24725.8 24825.5 07:30:57 INFO - PID 1641 | 07:30:57 INFO - PID 1641 | [#2] jquery.html Cycles:180 Average:38852.92 Median:2236.61 stddev:95721.98 (4279.8%) stddev-sans-first:95947.16 07:30:57 INFO - PID 1641 | Values: 487.4 488.7 498.9 507.6 525.6 21804.0 21931.6 22702.9 22738.2 24822.6 24212.5 24926.9 24968.0 24976.9 24977.0 6364.2 6520.9 6533.6 6533.9 6536.2 6244.0 6415.3 6463.6 6479.3 6484.3 6328.8 6371.0 6378.0 6378.8 6403.3 5785.1 5923.6 6367.4 6370.6 6373.4 5186.9 5484.5 5804.4 5834.9 5845.7 29337.9 30060.8 30132.4 30142.1 30468.5 1851.3 2014.4 2233.5 2235.3 2237.9 19387.7 20022.1 20031.6 20041.5 20066.7 2184.7 2214.5 2253.5 2296.9 2297.1 170745.0 171493.0 179334.0 181175.0 198847.0 14785.9 15079.4 15152.9 15268.4 15621.8 20348.8 20596.0 20610.3 20620.9 20677.0 7999.0 9208.6 9775.7 9823.4 9845.0 8885.3 8908.6 8912.6 8963.3 8978.7 9422.5 9512.8 9526.8 9545.6 9581.4 2249.2 2647.4 2669.9 2680.3 2814.7 906.4 945.5 951.2 976.3 996.2 21658.8 21702.7 22167.8 22403.9 22426.7 977.8 997.0 1017.0 1017.2 1028.3 10854.5 10863.5 10936.5 11471.1 11525.3 550614.0 554208.0 554461.0 561754.0 563161.0 109045.0 143172.3 144115.0 144270.6 145168.0 1144.2 1155.9 1176.4 1181.7 1181.7 18852.7 19021.1 19202.9 19269.8 19924.6 59337.7 62396.5 67475.3 70931.6 71560.0 94173.5 98802.0 103151.5 103323.5 104044.0 11579.1 11821.1 12003.5 12061.0 12075.8 17713.4 18521.4 18574.2 19043.3 19299.0 15440.7 15607.8 15660.9 15675.9 15784.1 13726.4 13958.7 13987.2 14101.9 14140.4 15482.6 15526.8 15828.9 15835.3 15870.2 9947.6 11707.5 11823.4 11853.3 11943.5 14209.5 14663.8 14996.5 15067.6 15097.3 07:30:57 INFO - PID 1641 | 07:30:57 INFO - PID 1641 | [#3] mootools.html Cycles:180 Average:8170.27 Median:2666.95 stddev:5409.75 (202.8%) stddev-sans-first:5400.63 07:30:57 INFO - PID 1641 | Values: 1347.5 1394.3 1399.2 1407.7 1415.9 12871.9 13073.3 13481.9 13513.7 13933.9 13486.0 13699.7 13716.3 13756.0 13762.0 2672.6 2740.4 2743.9 2746.9 2749.3 2629.4 2666.6 2682.3 2684.3 2687.8 2644.9 2645.5 2700.9 2703.3 2730.5 2629.6 2650.5 2664.2 2666.5 2667.3 2200.5 2295.6 2649.2 2655.6 2662.8 12925.2 13327.7 13335.8 13338.3 13357.1 7308.1 7590.5 7591.0 7613.0 7624.5 4629.8 4758.3 4802.3 4874.1 4918.6 1362.7 1385.9 1412.8 1413.8 1420.1 15962.9 19776.6 19856.7 19934.1 19972.4 5702.7 6043.7 6089.3 6486.3 6551.4 13058.9 14132.6 14181.1 14185.7 14240.9 7092.9 7331.8 7352.4 7372.2 7378.0 6633.6 6750.5 6811.3 6839.0 6845.4 4862.4 5013.8 5080.1 5091.2 5101.8 1450.1 1450.3 1465.1 1467.2 1480.7 810.8 812.6 837.7 839.8 858.8 6952.4 6955.5 7092.6 7318.4 7405.2 650.2 658.3 672.6 679.1 691.3 4386.6 4518.1 4636.6 4745.9 5320.9 17565.0 17939.6 17999.5 18381.4 18393.8 17826.0 17859.4 18019.1 18344.9 18386.7 7553.5 9197.0 9398.2 9532.0 9544.2 7584.6 7593.8 7657.5 7683.5 8076.0 15905.8 16043.3 16155.6 16156.4 16266.2 15950.8 16202.2 16522.4 16551.0 16827.6 10774.6 10784.8 10941.5 10953.5 10963.2 12314.0 12533.4 12541.3 12542.4 12566.1 10089.7 10331.9 10374.5 10388.0 10495.1 9921.1 9953.0 10026.3 10049.7 10068.5 9096.4 9304.8 9316.1 9331.2 9348.7 6386.0 6526.1 6920.7 6991.4 7048.8 6840.6 6932.4 7066.6 7249.7 7251.9 07:30:57 INFO - PID 1641 | 07:30:57 INFO - PID 1641 | [#4] prototype.html Cycles:180 Average:5609.75 Median:6111.93 stddev:1741.35 (28.5%) stddev-sans-first:1702.61 07:30:57 INFO - PID 1641 | Values: 449.2 452.5 455.1 456.6 467.9 5898.5 5938.5 6095.1 6128.8 6623.9 5871.2 6203.5 6452.1 6503.9 6531.4 4784.5 4828.5 4829.5 4841.5 4842.2 4141.9 4440.7 4811.6 4812.5 4812.6 3778.5 4507.7 4561.6 4760.9 4789.1 3911.1 4247.9 4712.0 4775.8 4795.3 4154.9 4224.6 4226.9 4227.9 4238.7 6013.8 6019.8 6346.9 6387.3 6390.5 5110.7 5674.6 5747.9 5758.3 5759.0 6512.9 6909.9 7003.9 7026.8 7048.2 4826.7 4832.9 4833.6 4848.4 4854.3 6047.0 7109.0 7380.2 7399.1 7447.2 4766.4 4878.5 4888.3 4908.9 4933.6 4193.5 5004.1 5009.0 5028.1 5059.3 4738.0 5689.7 5700.2 5740.1 5744.1 5868.9 6532.8 7021.8 7024.3 7051.0 1679.4 1714.9 1716.1 1716.2 1723.8 1114.4 1128.4 1149.1 1152.0 1157.7 5468.5 6528.7 6536.9 6553.7 6577.7 6588.8 7249.0 7262.2 7268.2 7272.4 6009.2 6461.5 6478.9 6487.7 6511.4 3593.4 3757.6 3760.6 3793.9 3821.1 7338.3 7340.4 7344.8 7347.0 7381.2 6056.5 7327.4 7394.1 7436.5 7481.9 6710.0 7305.4 7361.5 7368.2 7395.7 6264.7 7164.3 7166.8 7203.2 7214.8 4834.8 5897.7 5917.5 7054.5 7148.2 6189.3 7391.6 7422.4 7454.8 7523.0 4117.1 4481.8 4963.7 4963.9 4984.0 6377.5 6395.7 7271.9 7291.7 7328.0 6759.7 6923.1 7033.0 7039.0 7046.5 6730.5 6991.2 7024.9 7033.9 7081.4 6671.0 6822.7 6856.8 6866.3 6887.7 5575.9 6649.8 6939.8 6982.3 7020.3 5601.8 6158.8 6161.9 6613.6 6781.5 07:30:57 INFO - PID 1641 | 07:30:57 INFO - PID 1641 | [#5] yui.html Cycles:180 Average:23842.48 Median:16521.25 stddev:36627.29 (221.7%) stddev-sans-first:36689.17 07:30:57 INFO - PID 1641 | Values: 799.2 821.1 824.9 853.8 868.9 16925.3 17158.7 17352.9 17396.9 17403.8 15614.9 15817.2 16125.5 16153.4 16424.8 992.8 1019.9 1022.3 1024.4 1026.0 978.1 992.2 1003.5 1007.2 1009.5 1039.9 1049.0 1064.0 1067.8 1068.6 1031.6 1041.6 1043.5 1046.2 1046.6 758.9 772.5 774.0 776.9 778.4 17035.7 17907.7 17917.5 17957.2 18094.5 14603.3 15120.9 17271.9 17390.0 17890.7 12861.9 14877.2 14905.9 14911.0 14914.1 3247.1 3514.1 3580.6 3602.8 3651.8 119829.0 151727.2 152710.0 153349.0 155163.0 11814.9 12920.4 13451.5 13487.3 13674.4 21564.8 26930.9 26963.9 27018.5 27067.9 7788.1 7799.5 7809.6 7837.3 7911.4 3500.2 3981.4 4055.9 4059.7 4064.6 4624.6 4681.2 4710.2 4719.6 4935.7 1009.4 1018.5 1027.8 1036.9 1046.6 655.1 664.8 666.5 675.8 688.2 9449.6 10598.6 11598.2 13160.9 14220.8 667.8 668.4 686.0 689.6 691.0 4638.0 5713.7 5721.1 5779.2 5894.9 125047.4 130212.0 130762.3 131146.0 131158.0 88661.6 107548.5 109096.0 110432.0 112158.0 91396.5 91919.0 92392.5 94971.5 99650.0 8565.6 11196.6 11704.7 11713.8 11755.6 44374.6 45316.6 46226.8 47161.8 47378.8 60051.0 61821.4 62059.4 62132.7 62731.0 14692.1 14721.7 14951.7 15112.2 15224.2 16188.5 16399.0 16465.6 16576.9 16787.7 14913.7 15264.6 15269.6 15304.9 15394.2 13535.7 14019.4 14065.6 14083.8 14114.8 14776.7 14801.5 14930.6 15035.7 15108.8 9858.9 9939.8 10472.8 11190.8 11307.9 9218.6 10831.6 11373.4 11850.3 12069.4 07:30:57 INFO - PID 1641 | -------- Summary: end -------- 07:30:57 INFO - PID 1641 | 07:30:57 INFO - TEST-INFO | 1641: exit 0 07:30:57 INFO - TEST-OK | dromaeo_css | took 1144304ms 07:30:57 INFO - TEST-START | kraken 07:30:57 INFO - operating with platform_type : mac_ 07:30:57 INFO - Initialising browser for kraken test... 07:30:57 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpJYxagK/profile 07:30:57 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 07:31:31 INFO - TEST-INFO | 1655: killed by SIGTERM 07:31:31 INFO - Browser initialized. 07:31:31 INFO - Running cycle 1/1 for kraken test... 07:31:31 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpJYxagK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:31:33 INFO - PID 1659 | 2017-06-08 07:31:33.627 plugin-container[1660:10763] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 07:31:33 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:31:33 INFO - PID 1659 | 2017-06-08 07:31:33.628 plugin-container[1660:10763] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa00b, name = 'com.apple.CFPasteboardClient' 07:31:33 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:31:33 INFO - PID 1659 | 2017-06-08 07:31:33.628 plugin-container[1660:10763] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:31:34 INFO - PID 1659 | 2017-06-08 07:31:34.033 plugin-container[1661:10814] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 07:31:34 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:31:34 INFO - PID 1659 | 2017-06-08 07:31:34.034 plugin-container[1661:10814] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 07:31:34 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:31:34 INFO - PID 1659 | 2017-06-08 07:31:34.034 plugin-container[1661:10814] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:31:43 INFO - PID 1659 | RSS: Main: 253083648 07:31:43 INFO - PID 1659 | 07:31:46 INFO - PID 1659 | 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) 07:31:47 INFO - PID 1659 | RSS: Main: 266375168 07:31:47 INFO - PID 1659 | 07:31:49 INFO - PID 1659 | 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) 07:31:50 INFO - PID 1659 | RSS: Main: 277417984 07:31:50 INFO - PID 1659 | 07:31:53 INFO - PID 1659 | 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) 07:31:54 INFO - PID 1659 | RSS: Main: 280702976 07:31:54 INFO - PID 1659 | 07:31:56 INFO - PID 1659 | 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) 07:31:57 INFO - PID 1659 | RSS: Main: 281341952 07:31:57 INFO - PID 1659 | 07:31:59 INFO - PID 1659 | 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) 07:31:59 INFO - PID 1659 | RSS: Main: 281845760 07:31:59 INFO - PID 1659 | 07:32:02 INFO - PID 1659 | 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) 07:32:03 INFO - PID 1659 | RSS: Main: 286605312 07:32:03 INFO - PID 1659 | 07:32:06 INFO - PID 1659 | 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) 07:32:06 INFO - PID 1659 | RSS: Main: 288546816 07:32:06 INFO - PID 1659 | 07:32:09 INFO - PID 1659 | 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) 07:32:10 INFO - PID 1659 | RSS: Main: 287162368 07:32:10 INFO - PID 1659 | 07:32:11 INFO - PID 1659 | 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) 07:32:12 INFO - PID 1659 | RSS: Main: 288264192 07:32:12 INFO - PID 1659 | 07:32:14 INFO - PID 1659 | 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) 07:32:14 INFO - PID 1659 | RSS: Main: 288247808 07:32:14 INFO - PID 1659 | 07:32:17 INFO - PID 1659 | 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) 07:32:17 INFO - PID 1659 | RSS: Main: 288034816 07:32:17 INFO - PID 1659 | 07:32:20 INFO - PID 1659 | 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) 07:32:21 INFO - PID 1659 | RSS: Main: 287424512 07:32:21 INFO - PID 1659 | 07:32:24 INFO - PID 1659 | 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) 07:32:24 INFO - PID 1659 | RSS: Main: 287694848 07:32:24 INFO - PID 1659 | 07:32:26 INFO - PID 1659 | 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) 07:32:26 INFO - PID 1659 | RSS: Main: 289689600 07:32:26 INFO - PID 1659 | 07:32:26 INFO - PID 1659 | __start_tp_report 07:32:26 INFO - PID 1659 | _x_x_mozilla_page_load 07:32:26 INFO - PID 1659 | _x_x_mozilla_page_load_details 07:32:26 INFO - PID 1659 | |i|pagename|runs| 07:32:26 INFO - PID 1659 | |0;ai-astar;112;115;98;115;116;136;165;156;112;112 07:32:26 INFO - PID 1659 | |1;audio-beat-detection;140;115;138;164;110;129;130;112;166;114 07:32:26 INFO - PID 1659 | |2;audio-dft;196;204;200;219;222;224;215;268;282;199 07:32:26 INFO - PID 1659 | |3;audio-fft;68;81;75;153;83;84;60;61;128;63 07:32:26 INFO - PID 1659 | |4;audio-oscillator;69;71;114;72;69;80;110;78;72;114 07:32:26 INFO - PID 1659 | |5;imaging-gaussian-blur;89;89;88;88;93;89;96;89;89;89 07:32:26 INFO - PID 1659 | |6;imaging-darkroom;95;97;95;96;103;95;109;148;96;126 07:32:26 INFO - PID 1659 | |7;imaging-desaturate;90;94;92;91;92;91;90;92;91;103 07:32:26 INFO - PID 1659 | |8;json-parse-financial;50;52;51;52;52;52;54;51;50;53 07:32:26 INFO - PID 1659 | |9;json-stringify-tinderbox;53;59;56;59;60;60;58;54;55;55 07:32:26 INFO - PID 1659 | |10;stanford-crypto-aes;84;82;83;85;83;86;87;91;111;87 07:32:26 INFO - PID 1659 | |11;stanford-crypto-ccm;158;155;131;137;133;135;134;152;163;145 07:32:26 INFO - PID 1659 | |12;stanford-crypto-pbkdf2;178;176;178;181;182;175;179;174;185;181 07:32:26 INFO - PID 1659 | |13;stanford-crypto-sha256-iterative;64;65;67;67;69;63;64;66;67;64 07:32:26 INFO - PID 1659 | __end_tp_report 07:32:26 INFO - PID 1659 | __start_cc_report 07:32:26 INFO - PID 1659 | _x_x_mozilla_cycle_collect,1191 07:32:26 INFO - PID 1659 | __end_cc_report 07:32:26 INFO - PID 1659 | __startTimestamp1496932346891__endTimestamp 07:32:26 INFO - PID 1659 | 07:32:26 INFO - PID 1659 | ------- Summary: start ------- 07:32:26 INFO - PID 1659 | Number of tests: 14 07:32:26 INFO - PID 1659 | 07:32:26 INFO - PID 1659 | [#0] ai-astar Cycles:10 Average:123.70 Median:126.00 stddev:21.56 (17.1%) stddev-sans-first:22.44 07:32:26 INFO - PID 1659 | Values: 112.0 115.0 98.0 115.0 116.0 136.0 165.0 156.0 112.0 112.0 07:32:26 INFO - PID 1659 | 07:32:26 INFO - PID 1659 | [#1] audio-beat-detection Cycles:10 Average:131.80 Median:134.00 stddev:20.52 (15.3%) stddev-sans-first:21.55 07:32:26 INFO - PID 1659 | Values: 140.0 115.0 138.0 164.0 110.0 129.0 130.0 112.0 166.0 114.0 07:32:26 INFO - PID 1659 | 07:32:26 INFO - PID 1659 | [#2] audio-dft Cycles:10 Average:222.90 Median:220.50 stddev:29.41 (13.3%) stddev-sans-first:29.54 07:32:26 INFO - PID 1659 | Values: 196.0 204.0 200.0 219.0 222.0 224.0 215.0 268.0 282.0 199.0 07:32:26 INFO - PID 1659 | 07:32:26 INFO - PID 1659 | [#3] audio-fft Cycles:10 Average:85.60 Median:71.50 stddev:30.85 (43.1%) stddev-sans-first:32.06 07:32:26 INFO - PID 1659 | Values: 68.0 81.0 75.0 153.0 83.0 84.0 60.0 61.0 128.0 63.0 07:32:26 INFO - PID 1659 | 07:32:26 INFO - PID 1659 | [#4] audio-oscillator Cycles:10 Average:84.90 Median:71.50 stddev:19.51 (27.3%) stddev-sans-first:19.83 07:32:26 INFO - PID 1659 | Values: 69.0 71.0 114.0 72.0 69.0 80.0 110.0 78.0 72.0 114.0 07:32:26 INFO - PID 1659 | 07:32:26 INFO - PID 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:89.90 Median:89.00 stddev:2.56 (2.9%) stddev-sans-first:2.69 07:32:26 INFO - PID 1659 | Values: 89.0 89.0 88.0 88.0 93.0 89.0 96.0 89.0 89.0 89.0 07:32:26 INFO - PID 1659 | 07:32:26 INFO - PID 1659 | [#6] imaging-darkroom Cycles:10 Average:106.00 Median:95.00 stddev:17.72 (18.7%) stddev-sans-first:18.34 07:32:26 INFO - PID 1659 | Values: 95.0 97.0 95.0 96.0 103.0 95.0 109.0 148.0 96.0 126.0 07:32:26 INFO - PID 1659 | 07:32:26 INFO - PID 1659 | [#7] imaging-desaturate Cycles:10 Average:92.60 Median:91.50 stddev:3.84 (4.2%) stddev-sans-first:3.95 07:32:26 INFO - PID 1659 | Values: 90.0 94.0 92.0 91.0 92.0 91.0 90.0 92.0 91.0 103.0 07:32:26 INFO - PID 1659 | 07:32:26 INFO - PID 1659 | [#8] json-parse-financial Cycles:10 Average:51.70 Median:52.00 stddev:1.25 (2.4%) stddev-sans-first:1.17 07:32:26 INFO - PID 1659 | Values: 50.0 52.0 51.0 52.0 52.0 52.0 54.0 51.0 50.0 53.0 07:32:26 INFO - PID 1659 | 07:32:26 INFO - PID 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:56.90 Median:58.50 stddev:2.60 (4.4%) stddev-sans-first:2.35 07:32:26 INFO - PID 1659 | Values: 53.0 59.0 56.0 59.0 60.0 60.0 58.0 54.0 55.0 55.0 07:32:26 INFO - PID 1659 | 07:32:26 INFO - PID 1659 | [#10] stanford-crypto-aes Cycles:10 Average:87.90 Median:85.50 stddev:8.53 (10.0%) stddev-sans-first:8.93 07:32:26 INFO - PID 1659 | Values: 84.0 82.0 83.0 85.0 83.0 86.0 87.0 91.0 111.0 87.0 07:32:26 INFO - PID 1659 | 07:32:26 INFO - PID 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:144.30 Median:148.50 stddev:11.84 (8.0%) stddev-sans-first:11.48 07:32:26 INFO - PID 1659 | Values: 158.0 155.0 131.0 137.0 133.0 135.0 134.0 152.0 163.0 145.0 07:32:26 INFO - PID 1659 | 07:32:26 INFO - PID 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.90 Median:180.00 stddev:3.41 (1.9%) stddev-sans-first:3.61 07:32:26 INFO - PID 1659 | Values: 178.0 176.0 178.0 181.0 182.0 175.0 179.0 174.0 185.0 181.0 07:32:26 INFO - PID 1659 | 07:32:26 INFO - PID 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:66.50 stddev:1.90 (2.9%) stddev-sans-first:1.92 07:32:26 INFO - PID 1659 | Values: 64.0 65.0 67.0 67.0 69.0 63.0 64.0 66.0 67.0 64.0 07:32:26 INFO - PID 1659 | -------- Summary: end -------- 07:32:26 INFO - PID 1659 | 07:32:27 INFO - TEST-INFO | 1659: exit 0 07:32:27 INFO - TEST-OK | kraken | took 90092ms 07:32:27 INFO - SUITE-END | took 1234s 07:32:28 INFO - Completed test suite (00:20:34) 07:32:28 INFO - javascript benchmark 07:32:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8291.267481434428, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3594.9820250898742, 3612.9277414451694, 3636.745427820052, 3655.579608345041, 3656.652618001289, 5070.214116811891, 5803.709814509273, 6171.938280617203, 6286.717097730601, 6310.621362718234, 6243.500519958404, 6248.562600617958, 6265.498760099193, 6280.748770049193, 6357.459615932639, 5420.83737487876, 5479.972600137014, 5498.752556134964, 5503.807366742165, 5513.2005859150395, 5563.13771365437, 5614.971925140389, 5623.718814059301, 5626.156076588524, 5639.097744360903, 5360.651557648779, 5426.647267927572, 5526.364468086141, 5560.666360018412, 5619.129034999562, 5473.31583552056, 5505.642133261365, 5555.500004999529, 5563.749631266603, 5573.637713548606, 5477.5070243677865, 5481.397046324901, 5502.944970550283, 5517.282753242052, 5537.529310008654, 4745.789823594996, 6210.875782484325, 6300.653464059475, 6312.305646378894, 6354.904676429857, 5422.295101636762, 6615.17310336208, 6669.933300666979, 6790.0, 6806.761763338309, 6948.096747422853, 6954.304569543056, 6954.582725036514, 6974.26770189133, 6987.615681137536, 6418.133551970518, 6445.677716114223, 6446.871062578816, 6455.903161452582, 6461.838454038687, 6305.180326557518, 6592.011198320194, 6895.482838787111, 6933.37599616037, 7026.156861176692, 6505.544611877218, 6520.119783829123, 6551.770688025897, 6690.0, 6751.527393082535, 6736.46108311334, 6758.087658166085, 6862.073620061228, 6862.828429289308, 6878.3465570770695, 6751.966240168767, 6822.044913712084, 6825.249222585511, 6825.35159159879, 6860.0, 6362.204724409449, 6684.997250412477, 6713.69788359525, 6835.487338449636, 6852.588844669336, 5342.5993050521365, 5372.704501252469, 5386.91919621206, 5399.514043736082, 5411.594130440233, 3746.3069332173636, 4799.0, 4801.95198048015, 4814.831480898151, 4906.939085584063, 5682.948654498931, 6192.721327540272, 6344.333844946306, 6360.491160707133, 6388.073729309224, 6477.384648458389, 6811.93188068113, 6888.276730943279, 6915.51591388598, 6922.5154239203775, 6361.173047503888, 6377.489800815923, 6390.712417941204, 6402.743890244431, 6404.935950640527, 5852.6195797273895, 6073.240844894388, 6102.7558897644485, 6111.388861113942, 6128.295246046672, 5582.91625625616, 6955.1653801544135, 6980.650967451708, 7049.436045116481, 7080.8937865933085, 6848.931510685029, 6917.342852429011, 6917.481188910751, 6956.2000369958105, 6981.02265682815, 7044.8591028178125, 7079.469039822137, 7081.893771593327, 7086.716531338689, 7109.0, 6552.148220731275, 6646.335366463509, 6691.5315927884585, 6800.591964482147, 6807.829804254933, 6907.447404207752, 6945.68744406513, 6998.3608523566445, 7001.0198572198915, 7005.719771209095, 4987.9525299687475, 6652.101966234496, 6698.296678848846, 6704.4301234395625, 6705.430038446591, 5798.56510761703, 6547.607143571401, 6564.737410503717, 6617.272100069084, 6708.02733603615, 6466.547341686037, 6597.0, 6620.973749068902, 6626.5030122741955, 6636.668166591748, 6723.899141512783, 6734.6295953721055, 6734.797956061326, 6743.5953842769595, 6757.797266082025, 6328.019157030668, 6394.840128996626, 6500.967495162494, 6572.047053177167, 6590.7693230736695], "value": 6174.71641393254, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [784.4587234807982, 809.9554524501152, 814.6904176412962, 1059.5443959097586, 1228.5147366790113, 30166.547501787463, 37802.24395512088, 37846.0538486538, 37931.43102853459, 37933.0, 38213.23573528528, 38409.61590384095, 38479.422808657895, 38483.80758096213, 38507.61492385082, 7599.164091949881, 7759.107702614215, 8034.196580341977, 8280.213379728915, 8291.502509849399, 8166.510009399425, 8226.712065077723, 8241.217084376975, 8244.464109832872, 8262.339012878985, 8209.876851847228, 8238.876416853753, 8261.256486916176, 8271.25558699717, 8285.627146778379, 8236.752897413087, 8239.464434811747, 8243.83512329756, 8254.587270636473, 8260.0, 6694.966525167343, 7641.274078962545, 8073.354131669453, 8114.148014458515, 8196.631151598192, 31206.0, 35231.94304170853, 36590.26819463622, 36780.44829327562, 36909.5236190552, 3587.031501494582, 3599.1362073102528, 3608.548931383577, 3612.8916132516065, 3615.023943535231, 30368.937087202063, 30535.0, 30567.0, 30582.08253752391, 30586.38827223443, 3025.7125573070525, 3059.83170925599, 3081.337512434815, 3087.4751292280143, 3099.5970523831757, 251562.74218628788, 253601.7319913407, 288122.0, 311193.0, 312375.0, 42480.0, 43691.12617747691, 47175.292370614465, 47317.526824732, 47390.76304618455, 49148.262776058385, 49234.0, 49234.507654923706, 49286.75356623194, 49554.75222623864, 13797.862021379859, 13857.307134643228, 13889.236092015037, 13976.860231397759, 14076.225807580682, 11172.944135279271, 11230.7192320191, 11246.325220486797, 11265.098792096613, 11273.985341319321, 13841.754242118257, 13854.0, 13881.861181388056, 13909.860901390857, 14045.859541404456, 6104.0, 6171.691415429301, 6175.660338681326, 6180.165677633552, 6182.072689096582, 956.9951550871557, 966.8694726212012, 967.6371360739722, 1016.5933626549439, 1023.9436830974367, 23421.765782341958, 23736.525269494512, 24734.639594822456, 24933.002679892965, 24970.625440618405, 981.7005813227034, 1096.9396683182501, 1105.2189818206648, 1145.7593905279819, 1160.7910576096215, 11978.401079946143, 12462.03469273507, 12618.55835045769, 12643.431045602785, 12689.428975696115, 333992.0, 335512.0, 335973.0, 336703.3164834209, 336717.0, 42828.78585607115, 53217.467825322026, 53593.73203133959, 53633.463665363626, 53716.46283537193, 1487.5239923224465, 1501.4144483651603, 1525.4889611979888, 1532.6551525906582, 1547.81426228851, 22392.32823015312, 22946.885265573565, 23175.536489270457, 24079.51840963206, 24218.0, 37190.44214336841, 37511.062223444635, 37547.43678844765, 48900.0, 50595.0, 290981.0, 294306.0, 294464.0, 294796.0, 295006.0, 36073.0, 36281.0, 36313.818430907675, 36418.63581364152, 36442.27115457729, 42749.572504275806, 42837.57162428336, 42859.571404286806, 44540.55459445494, 44647.0, 27901.860490697418, 29809.552856706734, 29811.403771924874, 30849.0, 31486.0, 29921.952731654288, 30415.54376684307, 30592.388152237276, 31460.68539314669, 31673.683263167994, 36983.0, 37061.0, 37883.62116378801, 37943.430848536744, 37979.62020379761, 30035.0, 30092.849535752182, 30778.846105769328, 30946.690533095283, 31365.372692546476, 23308.6503702448, 24347.026118955044, 24441.38896527601, 24725.752742472345, 24825.503489930463], "value": 17421.445388989276, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [487.381026096857, 488.68299931684106, 498.9223277720125, 507.6218067945527, 525.5690333926182, 21804.018819153138, 21931.561368772615, 22702.88648556759, 22738.204162854283, 24822.62766058506, 24212.515749684997, 24926.878290476925, 24968.0, 24976.875115624443, 24977.0, 6364.204474440695, 6520.869582608345, 6533.575317604341, 6533.901991470132, 6536.215654121488, 6244.0, 6415.262244841856, 6463.644499552523, 6479.254885688159, 6484.254310754276, 6328.778492752732, 6371.044343348489, 6377.968110159442, 6378.776742814002, 6403.263624683151, 5785.1033089870925, 5923.614965027259, 6367.395097465734, 6370.617762934239, 6373.362663733613, 5186.936677981005, 5484.451554844483, 5804.448577385142, 5834.883302333929, 5845.7369418376275, 29337.853310733524, 30060.8496957516, 30132.397352053056, 30142.095737127704, 30468.54297185544, 1851.3057603398724, 2014.405750303664, 2233.4974630708125, 2235.2847088931385, 2237.9328620141423, 19387.709184362247, 20022.099005544784, 20031.599368012703, 20041.498962525908, 20066.698999514872, 2184.748753893307, 2214.490667146565, 2253.5492901419616, 2296.8851557422063, 2297.0811675329833, 170745.0, 171493.0, 179334.0, 181175.0, 198847.0, 14785.852141478663, 15079.396824126912, 15152.908714646983, 15268.3892644293, 15621.750259979175, 20348.79651203469, 20595.970201489865, 20610.278640247518, 20620.896895515427, 20676.966151692355, 7999.0, 9208.585613647401, 9775.657851975147, 9823.361481503822, 9844.96627854065, 8885.333599979898, 8908.554572271361, 8912.55437228136, 8963.327750418614, 8978.68574599899, 9422.528873556295, 9512.809743805223, 9526.809463810685, 9545.570449329796, 9581.377210481296, 2249.2127755285846, 2647.444036752265, 2669.8665066746585, 2680.289723223347, 2814.746672799458, 906.40177482861, 945.5272363818091, 951.2437612098261, 976.3117002513211, 996.1582462818907, 21658.78341216599, 21702.674459883114, 22167.78077205737, 22403.887980560317, 22426.663600046013, 977.7653363192783, 996.9850452243222, 1016.9949150254349, 1017.1608423050865, 1028.290479569095, 10854.511546980404, 10863.45682715861, 10936.453177341102, 11471.082313414907, 11525.308481491138, 550614.0, 554208.0, 554461.0, 561754.0, 563161.0, 109045.0, 143172.28413857863, 144115.0, 144270.55729442782, 145168.0, 1144.1533265383557, 1155.8959693627614, 1176.4235524592889, 1181.6868529839599, 1181.6986668399504, 18852.717209242146, 19021.144048517574, 19202.903985479985, 19269.8073019268, 19924.60150797005, 59337.7033114849, 62396.5041398339, 67475.3252467469, 70931.58136837337, 71560.0, 94173.5291323539, 98802.0, 103151.48424257831, 103323.4833825826, 104044.0, 11579.131565132817, 11821.054315654555, 12003.459844307201, 12061.0, 12075.758484830078, 17713.380031699344, 18521.444356669323, 18574.164162612444, 19043.333483328017, 19299.0, 15440.691186176438, 15607.765883511529, 15660.903736738319, 15675.92162039228, 15784.052956822627, 13726.382312796148, 13958.651033723832, 13987.23070231147, 14101.858981410054, 14140.43438262458, 15482.612934676723, 15526.767098493307, 15828.920855395649, 15835.287412066715, 15870.2064896757, 9947.552360143665, 11707.473163707484, 11823.408829558315, 11853.288802671867, 11943.462544185359, 14209.50266740659, 14663.780043299146, 14996.47512337063, 15067.623309417353, 15097.32062057232], "value": 11723.10887408228, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1347.4879545772606, 1394.281944798429, 1399.2304232672027, 1407.6832712639664, 1415.8867290616752, 12871.935640321797, 13073.346332683352, 13481.865181348183, 13513.662158446044, 13933.93033034836, 13486.0, 13699.657508562266, 13716.31418429077, 13756.03707740456, 13762.0, 2672.6258323834677, 2740.369714965559, 2743.862806859659, 2746.8763905624196, 2749.2989287731702, 2629.421527263999, 2666.6000099984963, 2682.28919336376, 2684.288663504163, 2687.8387296762257, 2644.9471010579873, 2645.470905818834, 2700.891964321425, 2703.2836298380944, 2730.5085084684747, 2629.6449979252843, 2650.5229058769414, 2664.2273740615196, 2666.546687063204, 2667.293167310664, 2200.5378870437225, 2295.644175152854, 2649.1787545860843, 2655.6282120503147, 2662.786977041832, 12925.224486530838, 13327.73344533104, 13335.799963000563, 13338.266395348252, 13357.13178643385, 7308.123025237006, 7590.544567325978, 7591.013168288143, 7612.972248746404, 7624.466287359887, 4629.791659375336, 4758.262469317261, 4802.30366596845, 4874.073925954058, 4918.606511479073, 1362.7002059546883, 1385.9466805227999, 1412.78808178774, 1413.8162038934975, 1420.133718431766, 15962.920185399114, 19776.604467910704, 19856.70214946777, 19934.10296536645, 19972.400827975183, 5702.716888700009, 6043.700604370081, 6089.330173680892, 6486.254080780771, 6551.44312733423, 13058.869411305956, 14132.576022719335, 14181.078229915023, 14185.716285674434, 14240.857591424161, 7092.9290707093305, 7331.780046598504, 7352.375048120863, 7372.2259162788205, 7378.0, 6633.635150066792, 6750.493712971547, 6811.318868113149, 6838.965805171042, 6845.383915447534, 4862.367892173995, 5013.799448022111, 5080.085584594808, 5091.210862316347, 5101.846944591668, 1450.1226757811583, 1450.2966061460183, 1465.1282486920265, 1467.1637166815026, 1480.7408703477063, 810.7810891059381, 812.6180695073306, 837.677494164751, 839.8152406470567, 858.7981824271292, 6952.374286314256, 6955.513114081966, 7092.5744455331815, 7318.377937875341, 7405.222451642608, 650.1970066967292, 658.338369938209, 672.5527524196452, 679.11375654771, 691.2983321928247, 4386.627136693353, 4518.073794872043, 4636.6058884995155, 4745.881352966135, 5320.893582128336, 17565.0, 17939.641207175784, 17999.460016199537, 18381.448556543324, 18393.80440271378, 17826.0, 17859.43110872695, 18019.099045047697, 18344.908275458536, 18386.724199137025, 7553.509021913668, 9197.0, 9398.20115290208, 9531.99914009033, 9544.236461083097, 7584.582847943416, 7593.810154746176, 7657.540547567164, 7683.462157648911, 8076.0, 15905.761413579037, 16043.2780524879, 16155.59611009687, 16156.434524791575, 16266.186690665183, 15950.840491594936, 16202.189890505664, 16522.42171523991, 16551.00693958366, 16827.579310517336, 10774.622888198875, 10784.838227426751, 10941.45292735344, 10953.507092181031, 10963.177761668067, 12314.014878809852, 12533.435995380412, 12541.31022793755, 12542.372881355714, 12566.057545684293, 10089.697309080739, 10331.896681033093, 10374.533146008296, 10388.0, 10495.10791582724, 9921.107100360714, 9952.950235248778, 10026.298159128792, 10049.748756280858, 10068.496575171066, 9096.363254572116, 9304.813903721752, 9316.114969077928, 9331.206847418045, 9348.719538413858, 6386.0, 6526.053722210348, 6920.653967301715, 6991.370776630128, 7048.823779405556, 6840.589564626139, 6932.410745086724, 7066.611336376343, 7249.673764680497, 7251.927480725125], "value": 5985.546744155147, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [449.1511044126601, 452.5474525474525, 455.12161528250465, 456.634692246203, 467.8830292426893, 5898.469137777599, 5938.495227905628, 6095.085737139426, 6128.774245150971, 6623.867522649544, 5871.207387002753, 6203.472704820096, 6452.128962590061, 6503.869922601545, 6531.412172904437, 4784.473707892138, 4828.5412885775795, 4829.468758436569, 4841.540053694911, 4842.2252439609665, 4141.917161656765, 4440.733555986635, 4811.59101476375, 4812.518748125177, 4812.639052071091, 3778.527684039495, 4507.729536227836, 4561.612262957636, 4760.92879102201, 4789.10830222062, 3911.061345277127, 4247.851325203603, 4712.0, 4775.785089670973, 4795.304680821261, 4154.896127596804, 4224.640905523034, 4226.901005738499, 4227.894302642428, 4238.682098842568, 6013.75944962201, 6019.779418011387, 6346.904796428057, 6387.297397286295, 6390.488760899117, 5110.71891045996, 5674.574406919456, 5747.913781293284, 5758.251427314464, 5758.971205143989, 6512.902306465407, 6909.930900690979, 7003.894941575881, 7026.824329391755, 7048.189458212269, 4826.686265392738, 4832.855014349575, 4833.564979151894, 4848.442429120626, 4854.320395144682, 6046.96976515119, 7108.964455177743, 7380.1512826025155, 7399.112106547195, 7447.218042105609, 4766.356541866873, 4878.536539028786, 4888.315635810989, 4908.9263661045115, 4933.580645645088, 4193.5387107418155, 5004.124278251292, 5009.023240468074, 5028.069807085701, 5059.342285502935, 4737.95262047382, 5689.6586204827845, 5700.230468886647, 5740.1344563016355, 5744.138379243063, 5868.941310586925, 6532.771353002622, 7021.789346319619, 7024.332688394595, 7051.0, 1679.4205998930595, 1714.9142542873055, 1716.1247763640383, 1716.2362748577013, 1723.83623555762, 1114.437209209344, 1128.3624752015085, 1149.1438878035917, 1151.9539218431337, 1157.6584907452323, 5468.535174510212, 6528.67356632176, 6536.869262614721, 6553.675702946679, 6577.671116444254, 6588.835279117964, 7249.0, 7262.237465066092, 7268.200497945222, 7272.418206543463, 6009.1587177795145, 6461.450776683941, 6478.870422591522, 6487.740490380426, 6511.38141876521, 3593.443016332473, 3757.5866654667966, 3760.5675347335405, 3793.9241215175543, 3821.1402434452025, 7338.339549440576, 7340.44946629005, 7344.779656610311, 7347.0, 7381.151167615685, 6056.454919057306, 7327.377172940253, 7394.112706475257, 7436.516626419266, 7481.925180748123, 6710.0, 7305.3790427813165, 7361.490894366642, 7368.152662443673, 7395.7041718330665, 6264.655443950444, 7164.283571642794, 7166.820829479305, 7203.2076471589135, 7214.819629509304, 4834.758262086952, 5897.705114744332, 5917.497012753965, 7054.470914681317, 7148.17795953472, 6189.297943065693, 7391.595596836584, 7422.406207503494, 7454.813629659302, 7522.962385188258, 4117.106131179691, 4481.7535035573355, 4963.726995015309, 4963.897564022486, 4983.975080124576, 6377.489800816016, 6395.680215989275, 7271.854562908607, 7291.744788932362, 7327.963360183165, 6759.662016899234, 6923.065386173003, 7033.015377847208, 7039.0, 7046.541974771829, 6730.461563074844, 6991.196012458632, 7024.929750702428, 7033.894491582528, 7081.362677359062, 6671.0, 6822.692978815866, 6856.794296171123, 6866.313368663094, 6887.7244910203035, 5575.853296480803, 6649.800505984828, 6939.82650433723, 6982.33667801558, 7020.333068358498, 5601.80393686219, 6158.815235542941, 6161.938380616316, 6613.64420293829, 6781.491388146008], "value": 5086.061328648604, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [799.156889481597, 821.1419067074906, 824.8556502612041, 853.7961474321208, 868.8957325120984, 16925.32298708052, 17158.742618860728, 17352.913235433836, 17396.913015434922, 17403.82596174038, 15614.921925390385, 15817.209139543034, 16125.516234512983, 16153.43462978796, 16424.75362869555, 992.7567745902246, 1019.8827134879473, 1022.274185328414, 1024.416082832782, 1025.9538320775546, 978.1001478639669, 992.171536766797, 1003.4631472162395, 1007.2294694558668, 1009.45489435705, 1039.937603743774, 1049.029647575993, 1064.0476773287903, 1067.7917806027829, 1068.64200492835, 1031.5615863258085, 1041.5521325829852, 1043.4834756795397, 1046.246702374297, 1046.5604446132604, 758.9165191828895, 772.5326177662546, 773.993808049539, 776.8601651702692, 778.4084096086958, 17035.659286814196, 17907.73138402925, 17917.462476125736, 17957.191926363215, 18094.457166285032, 14603.269836508238, 15120.924395377953, 17271.91364043184, 17390.043547604877, 17890.731639025424, 12861.935690321581, 14877.18175500347, 14905.925470372687, 14911.0, 14914.105153690705, 3247.1070455624654, 3514.1390359361862, 3580.6419358063986, 3602.837872295753, 3651.835667394974, 119829.0, 151727.24136379358, 152710.0, 153349.0, 155163.0, 11814.94092529532, 12920.353982300847, 13451.46194152223, 13487.32563371847, 13674.384652690653, 21564.784352156592, 26930.865345673144, 26963.865180673973, 27018.459630807272, 27067.864660676572, 7788.065432148065, 7799.49303295284, 7809.648565814438, 7837.333826624801, 7911.446198766146, 3500.1949551603275, 3981.402789581586, 4055.858044968412, 4059.715819892579, 4064.552899181088, 4624.583787459145, 4681.227597446383, 4710.199266124767, 4719.622430205575, 4935.728534930613, 1009.3842755918884, 1018.4805749067881, 1027.8047171037479, 1036.8548403223556, 1046.638909576195, 655.131950166032, 664.7706541243264, 666.4901350466897, 675.7972608217515, 688.1604442580044, 9449.582562615526, 10598.629047983284, 11598.246114002563, 13160.934195328962, 14220.791232745332, 667.7662818013754, 668.4151367553391, 686.0224180542707, 689.6448329110513, 691.0, 4637.976810115928, 5713.6857472838565, 5721.084626459747, 5779.162021506858, 5894.8821023579285, 125047.3747631256, 130212.0, 130762.34618827036, 131146.0, 131158.0, 88661.55669221612, 107548.46225768821, 109096.0, 110432.0, 112158.0, 91396.5430172845, 91919.0, 92392.53803730938, 94971.52514237523, 99650.0, 8565.61454734526, 11196.552137914392, 11704.70738231551, 11713.828617138219, 11755.588554400554, 44374.55625443704, 45316.640500785026, 46226.76886615546, 47161.7641911802, 47378.76310618425, 60051.0, 61821.38178618156, 62059.37940620536, 62132.68933655303, 62731.0, 14692.118472891661, 14721.705565888837, 14951.700965980837, 15112.244387780787, 15224.162671052756, 16188.514344569681, 16399.016059036498, 16465.588360291087, 16576.917115414344, 16787.74818377701, 14913.685886274487, 15264.618384540032, 15269.6182595436, 15304.923475382551, 15394.15332156742, 13535.661608459868, 14019.439222431396, 14065.578032659037, 14083.788743168656, 14114.78827817563, 14776.704465910407, 14801.481948131765, 14930.552083437515, 15035.699286014436, 15108.848911510744, 9858.915519293016, 9939.751506212402, 10472.842907356244, 11190.776184476103, 11307.943460282646, 9218.585163667516, 10831.566737330733, 11373.374464404536, 11850.288982661068, 12069.396530173632], "value": 8461.918610388238, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1522.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 115.0, 98.0, 115.0, 116.0, 136.0, 165.0, 156.0, 112.0, 112.0], "value": 123.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 115.0, 138.0, 164.0, 110.0, 129.0, 130.0, 112.0, 166.0, 114.0], "value": 131.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 204.0, 200.0, 219.0, 222.0, 224.0, 215.0, 268.0, 282.0, 199.0], "value": 222.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 81.0, 75.0, 153.0, 83.0, 84.0, 60.0, 61.0, 128.0, 63.0], "value": 85.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 114.0, 72.0, 69.0, 80.0, 110.0, 78.0, 72.0, 114.0], "value": 84.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 88.0, 88.0, 93.0, 89.0, 96.0, 89.0, 89.0, 89.0], "value": 89.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 95.0, 96.0, 103.0, 95.0, 109.0, 148.0, 96.0, 126.0], "value": 106.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 94.0, 92.0, 91.0, 92.0, 91.0, 90.0, 92.0, 91.0, 103.0], "value": 92.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 52.0, 51.0, 52.0, 52.0, 52.0, 54.0, 51.0, 50.0, 53.0], "value": 51.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 59.0, 56.0, 59.0, 60.0, 60.0, 58.0, 54.0, 55.0, 55.0], "value": 56.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 83.0, 85.0, 83.0, 86.0, 87.0, 91.0, 111.0, 87.0], "value": 87.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 155.0, 131.0, 137.0, 133.0, 135.0, 134.0, 152.0, 163.0, 145.0], "value": 144.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 176.0, 178.0, 181.0, 182.0, 175.0, 179.0, 174.0, 185.0, 181.0], "value": 178.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 67.0, 67.0, 69.0, 63.0, 64.0, 66.0, 67.0, 64.0], "value": 65.6, "unit": "score"}]}]} 07:32:28 INFO - Return code: 0 07:32:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:32:28 INFO - # TBPL SUCCESS # 07:32:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:32:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:32:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:32:28 INFO - 2017-06-08 07:32:28.296 screenresolution[1667:11202] starting screenresolution argv=screenresolution get 07:32:28 INFO - 2017-06-08 07:32:28.298 screenresolution[1667:11202] Display 0: 1600x1200x32@60 07:32:28 INFO - 2017-06-08 07:32:28.304 screenresolution[1668:11205] starting screenresolution argv=screenresolution list 07:32:28 INFO - Available Modes on Display 0 07:32:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:32:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:32:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:32:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:32:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:32:28 INFO - Intel Iris: 07:32:28 INFO - Chipset Model: Intel Iris 07:32:28 INFO - Type: GPU 07:32:28 INFO - Bus: Built-In 07:32:28 INFO - VRAM (Dynamic, Max): 1536 MB 07:32:28 INFO - Vendor: Intel (0x8086) 07:32:28 INFO - Device ID: 0x0a2e 07:32:28 INFO - Revision ID: 0x0009 07:32:28 INFO - Displays: 07:32:28 INFO - Display: 07:32:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:32:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:32:28 INFO - Display Serial Number: 1600x1200 60 07:32:28 INFO - Main Display: Yes 07:32:28 INFO - Mirror: Off 07:32:28 INFO - Online: Yes 07:32:28 INFO - Rotation: Supported 07:32:28 INFO - Return code: 0 07:32:28 INFO - Running post-action listener: _resource_record_post_action 07:32:28 INFO - [mozharness: 2017-06-08 14:32:28.540843Z] Finished run-tests step (success) 07:32:28 INFO - Running post-run listener: _resource_record_post_run 07:32:28 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 46763008; Write bytes: 570222592; Read time: 499; Write time: 1898 07:32:28 INFO - TinderboxPrint: CPU usage
53.1% 07:32:28 INFO - TinderboxPrint: I/O read bytes / time
46,763,008 / 499 07:32:28 INFO - TinderboxPrint: I/O write bytes / time
570,222,592 / 1,898 07:32:28 INFO - TinderboxPrint: CPU idle
2,358.0 (46.9%) 07:32:28 INFO - TinderboxPrint: CPU system
1,015.8 (20.2%) 07:32:28 INFO - TinderboxPrint: CPU user
1,655.9 (32.9%) 07:32:28 INFO - TinderboxPrint: Swap in / out
288,509,952 / 0 07:32:28 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 231977472; Write bytes: 229494784; Read time: 16774; Write time: 410 07:32:28 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 07:32:28 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 45730816; Write bytes: 322283520; Read time: 479; Write time: 1446 07:32:29 INFO - Running post-run listener: _upload_blobber_files 07:32:29 INFO - Blob upload gear active. 07:32:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:32:29 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 07:32:29 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'] 07:32:29 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 07:32:29 INFO - (blobuploader) - INFO - Open directory for files ... 07:32:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:32:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:32:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:32:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:32:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:32:30 INFO - (blobuploader) - INFO - Done attempting. 07:32:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:32:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:32:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:32:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:32:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:32:31 INFO - (blobuploader) - INFO - Done attempting. 07:32:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:32:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:32:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:32:32 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:32:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:32:32 INFO - (blobuploader) - INFO - Done attempting. 07:32:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:32:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:32:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:32:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:32:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:32:34 INFO - (blobuploader) - INFO - Done attempting. 07:32:34 INFO - (blobuploader) - INFO - Iteration through files over. 07:32:34 INFO - Return code: 0 07:32:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:32:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:32:34 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb0e75e971695ac3ff398e64c5408285fed03d78bdf4bce0800cb2f5b89ddc737017d941964d1e6a687b1c8f1a464ecf98b2deb5a3dcf08a2589b51dc976a458", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dd0c380bcf13a820cff26d799984c61c21a9326435cc909de2cd3298b7ff8968e85c245778deb12d838a483ae0112410bb17fe1feffa9a1c79456610dcb95483", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/620c5ead3f70d6651ed81c25a86594f07f01ce8abe1f1bb4fdcc4962fcf1147cc0d85315d820155f6460c95932bd9b8a4052b9443123500583602afd969a131b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ae3da51a6129bbde17c476380a1a0cb7b94fb7cb5c740214615d3f64f55086a3fa8a4bc25613d8894162f7971b83069af59dc8d8cac4fe15ea30ab96ddbb6aab"} 07:32:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:32:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:32:34 INFO - Contents: 07:32:34 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb0e75e971695ac3ff398e64c5408285fed03d78bdf4bce0800cb2f5b89ddc737017d941964d1e6a687b1c8f1a464ecf98b2deb5a3dcf08a2589b51dc976a458", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dd0c380bcf13a820cff26d799984c61c21a9326435cc909de2cd3298b7ff8968e85c245778deb12d838a483ae0112410bb17fe1feffa9a1c79456610dcb95483", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/620c5ead3f70d6651ed81c25a86594f07f01ce8abe1f1bb4fdcc4962fcf1147cc0d85315d820155f6460c95932bd9b8a4052b9443123500583602afd969a131b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ae3da51a6129bbde17c476380a1a0cb7b94fb7cb5c740214615d3f64f55086a3fa8a4bc25613d8894162f7971b83069af59dc8d8cac4fe15ea30ab96ddbb6aab"} 07:32:34 INFO - Running post-run listener: copy_logs_to_upload_dir 07:32:34 INFO - Copying logs to upload dir... 07:32:34 INFO - mkdir: /builds/slave/test/build/upload/logs 07:32:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1341.545211 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 21 secs) (at 2017-06-08 07:32:34.923047) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-08 07:32:34.926484) ========= 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.zH8OBTQTWa/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.fiWnHMYMv8/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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/cb0e75e971695ac3ff398e64c5408285fed03d78bdf4bce0800cb2f5b89ddc737017d941964d1e6a687b1c8f1a464ecf98b2deb5a3dcf08a2589b51dc976a458", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dd0c380bcf13a820cff26d799984c61c21a9326435cc909de2cd3298b7ff8968e85c245778deb12d838a483ae0112410bb17fe1feffa9a1c79456610dcb95483", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/620c5ead3f70d6651ed81c25a86594f07f01ce8abe1f1bb4fdcc4962fcf1147cc0d85315d820155f6460c95932bd9b8a4052b9443123500583602afd969a131b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ae3da51a6129bbde17c476380a1a0cb7b94fb7cb5c740214615d3f64f55086a3fa8a4bc25613d8894162f7971b83069af59dc8d8cac4fe15ea30ab96ddbb6aab"} build_url:https://queue.taskcluster.net/v1/task/HJEzF5LWQKyemT9rsoGw7w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011069 build_url: 'https://queue.taskcluster.net/v1/task/HJEzF5LWQKyemT9rsoGw7w/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/cb0e75e971695ac3ff398e64c5408285fed03d78bdf4bce0800cb2f5b89ddc737017d941964d1e6a687b1c8f1a464ecf98b2deb5a3dcf08a2589b51dc976a458", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dd0c380bcf13a820cff26d799984c61c21a9326435cc909de2cd3298b7ff8968e85c245778deb12d838a483ae0112410bb17fe1feffa9a1c79456610dcb95483", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/620c5ead3f70d6651ed81c25a86594f07f01ce8abe1f1bb4fdcc4962fcf1147cc0d85315d820155f6460c95932bd9b8a4052b9443123500583602afd969a131b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ae3da51a6129bbde17c476380a1a0cb7b94fb7cb5c740214615d3f64f55086a3fa8a4bc25613d8894162f7971b83069af59dc8d8cac4fe15ea30ab96ddbb6aab"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-08 07:32:34.959732) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 07:32:34.960136) ========= 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.zH8OBTQTWa/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.fiWnHMYMv8/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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.005196 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 07:32:35.008850) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-08 07:32:35.009172) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-08 07:32:35.009596) ========= ========= Total master_lag: 0.18 =========