builder: cedar_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0384 starttime: 1497311564.64 results: success (0) revision: 1a88c8de416089a09f5e67ed1dab148156b9151b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-12 16:52:44.644784) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-12 16:52:44.645224) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-12 16:52:44.645500) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-12 16:52:44.670318) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 16:52:44.670699) ========= 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.oDoijyvLlt/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.2wFZ7MDzgR/Listeners TMPDIR=/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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-12 16:52:44-- 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% 18.7M=0.001s 2017-06-12 16:52:44 (18.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.090441 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 16:52:44.783704) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 16:52:44.787071) ========= 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.oDoijyvLlt/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.2wFZ7MDzgR/Listeners TMPDIR=/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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.026409 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 16:52:44.835857) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-12 16:52:44.838638) ========= bash -c 'python archiver_client.py mozharness --repo projects/cedar --rev 1a88c8de416089a09f5e67ed1dab148156b9151b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo projects/cedar --rev 1a88c8de416089a09f5e67ed1dab148156b9151b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.oDoijyvLlt/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.2wFZ7MDzgR/Listeners TMPDIR=/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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-12 16:52:44,914 truncating revision to first 12 chars 2017-06-12 16:52:44,914 Setting DEBUG logging. 2017-06-12 16:52:44,914 attempt 1/10 2017-06-12 16:52:44,914 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/cedar/1a88c8de4160?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-12 16:52:46,700 unpacking tar archive at: cedar-1a88c8de4160/testing/mozharness/ program finished with exit code 0 elapsedTime=2.069042 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-12 16:52:46.928703) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-12 16:52:46.929057) ========= script_repo_revision: 1a88c8de416089a09f5e67ed1dab148156b9151b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-12 16:52:46.929414) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-12 16:52:46.929677) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-12 16:52:46.948012) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-06-12 16:52:46.948310) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 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', 'Cedar', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Cedar'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.oDoijyvLlt/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.2wFZ7MDzgR/Listeners TMPDIR=/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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 16:52:47 INFO - MultiFileLogger online at 20170612 16:52:47 in /builds/slave/test 16:52:47 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 16:52:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:52:47 INFO - {'append_to_log': False, 16:52:47 INFO - 'base_work_dir': '/builds/slave/test', 16:52:47 INFO - 'blob_upload_branch': 'Cedar', 16:52:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:52:47 INFO - 'branch': 'Cedar', 16:52:47 INFO - 'buildbot_json_path': 'buildprops.json', 16:52:47 INFO - 'config_files': ('talos/mac_config.py',), 16:52:47 INFO - 'default_actions': ('clobber', 16:52:47 INFO - 'read-buildbot-config', 16:52:47 INFO - 'download-and-extract', 16:52:47 INFO - 'populate-webroot', 16:52:47 INFO - 'create-virtualenv', 16:52:47 INFO - 'install', 16:52:47 INFO - 'setup-mitmproxy', 16:52:47 INFO - 'run-tests'), 16:52:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:52:47 INFO - 'download_minidump_stackwalk': True, 16:52:47 INFO - 'download_symbols': 'ondemand', 16:52:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:52:47 INFO - 'tooltool.py': '/tools/tooltool.py', 16:52:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:52:47 INFO - '/tools/misc-python/virtualenv.py')}, 16:52:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:52:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:52:47 INFO - 'gecko_profile': False, 16:52:47 INFO - 'gecko_profile_interval': 0, 16:52:47 INFO - 'installer_path': 'installer.exe', 16:52:47 INFO - 'log_level': 'info', 16:52:47 INFO - 'log_name': 'talos', 16:52:47 INFO - 'log_to_console': True, 16:52:47 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:52:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:52:47 INFO - 'opt_config_files': (), 16:52:47 INFO - 'pip_index': False, 16:52:47 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:52:47 INFO - 'cmd': ('bash', 16:52:47 INFO - '-c', 16:52:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:52:47 INFO - 'enabled': True, 16:52:47 INFO - 'halt_on_failure': False, 16:52:47 INFO - 'name': 'check_screen_resolution'},), 16:52:47 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:52:47 INFO - 'cmd': ('bash', 16:52:47 INFO - '-c', 16:52:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:52:47 INFO - 'enabled': True, 16:52:47 INFO - 'halt_on_failure': False, 16:52:47 INFO - 'name': 'check_screen_resolution'},), 16:52:47 INFO - 'run_cmd_checks_enabled': True, 16:52:47 INFO - 'suite': 'dromaeojs-e10s', 16:52:47 INFO - 'system_bits': '32', 16:52:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:52:47 INFO - 'title': 't-yosemite-r7-0384', 16:52:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:52:47 INFO - 'use_talos_json': True, 16:52:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:52:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:52:47 INFO - 'work_dir': 'build'} 16:52:47 INFO - [mozharness: 2017-06-12 23:52:47.135646Z] Running clobber step. 16:52:47 INFO - Running pre-action listener: _resource_record_pre_action 16:52:47 INFO - Running main action method: clobber 16:52:47 INFO - rmtree: /builds/slave/test/build 16:52:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:52:49 INFO - Running post-action listener: _resource_record_post_action 16:52:49 INFO - [mozharness: 2017-06-12 23:52:49.684721Z] Finished clobber step (success) 16:52:49 INFO - [mozharness: 2017-06-12 23:52:49.684978Z] Running read-buildbot-config step. 16:52:49 INFO - Running pre-action listener: _resource_record_pre_action 16:52:49 INFO - Running main action method: read_buildbot_config 16:52:49 INFO - Using buildbot properties: 16:52:49 INFO - { 16:52:49 INFO - "project": "", 16:52:49 INFO - "product": "firefox", 16:52:49 INFO - "who": "mconley@mozilla.com", 16:52:49 INFO - "installer_path": "public/build/firefox-56.0a1.en-US.mac.dmg", 16:52:49 INFO - "builddir": "cedar_yosemite_r7_test-dromaeojs-e10s", 16:52:49 INFO - "repository": "https://hg.mozilla.org/projects/cedar/", 16:52:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 cedar talos dromaeojs-e10s", 16:52:49 INFO - "stage_platform": "macosx64", 16:52:49 INFO - "basedir": "/builds/slave/test", 16:52:49 INFO - "buildnumber": 2, 16:52:49 INFO - "platform": "yosemite_r7", 16:52:49 INFO - "parent_task_id": "JEIcrPHUQY-a6gBFETciEw", 16:52:49 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 16:52:49 INFO - "slavebuilddir": "test", 16:52:49 INFO - "taskId": "X0C6rlMDQNah9sQZmbRDBg", 16:52:49 INFO - "branch": "cedar", 16:52:49 INFO - "script_repo_revision": "default", 16:52:49 INFO - "revision": "1a88c8de416089a09f5e67ed1dab148156b9151b", 16:52:49 INFO - "slavename": "t-yosemite-r7-0384", 16:52:49 INFO - "repo_path": "projects/cedar" 16:52:49 INFO - } 16:52:49 INFO - Finding installer, test and symbols from parent task. 16:52:49 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X0C6rlMDQNah9sQZmbRDBg'}, attempt #1 16:52:50 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X0C6rlMDQNah9sQZmbRDBg'}, attempt #1 16:52:50 INFO - Task dependencies: bRTgbHDWS8WTkK0O_48qjQ JEIcrPHUQY-a6gBFETciEw 16:52:50 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JEIcrPHUQY-a6gBFETciEw'}, attempt #1 16:52:51 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/JEIcrPHUQY-a6gBFETciEw/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 16:52:51 INFO - Set test_url: https://queue.taskcluster.net/v1/task/JEIcrPHUQY-a6gBFETciEw/artifacts/public/build/test_packages.json 16:52:51 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/JEIcrPHUQY-a6gBFETciEw/artifacts/public/build/target.crashreporter-symbols.zip 16:52:51 INFO - Running post-action listener: _resource_record_post_action 16:52:51 INFO - [mozharness: 2017-06-12 23:52:51.091718Z] Finished read-buildbot-config step (success) 16:52:51 INFO - [mozharness: 2017-06-12 23:52:51.091858Z] Running download-and-extract step. 16:52:51 INFO - Running pre-action listener: _resource_record_pre_action 16:52:51 INFO - Running main action method: download_and_extract 16:52:51 INFO - mkdir: /builds/slave/test/build/tests 16:52:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:52:51 INFO - trying https://queue.taskcluster.net/v1/task/JEIcrPHUQY-a6gBFETciEw/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json 16:52:51 INFO - Downloading https://queue.taskcluster.net/v1/task/JEIcrPHUQY-a6gBFETciEw/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json 16:52:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JEIcrPHUQY-a6gBFETciEw/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json'}, attempt #1 16:52:52 INFO - Downloaded 1328 bytes. 16:52:52 INFO - Reading from file /builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json 16:52:52 INFO - Using the following test package requirements: 16:52:52 INFO - {u'awsy': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 16:52:52 INFO - u'firefox-56.0a1.en-US.mac.awsy.tests.zip'], 16:52:52 INFO - u'common': [u'firefox-56.0a1.en-US.mac.common.tests.zip'], 16:52:52 INFO - u'cppunittest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 16:52:52 INFO - u'firefox-56.0a1.en-US.mac.cppunittest.tests.zip'], 16:52:52 INFO - u'gtest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 16:52:52 INFO - u'firefox-56.0a1.en-US.mac.gtest.tests.zip'], 16:52:52 INFO - u'jittest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 16:52:52 INFO - u'jsshell-mac.zip'], 16:52:52 INFO - u'mochitest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 16:52:52 INFO - u'firefox-56.0a1.en-US.mac.mochitest.tests.zip'], 16:52:52 INFO - u'mozbase': [u'firefox-56.0a1.en-US.mac.common.tests.zip'], 16:52:52 INFO - u'reftest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 16:52:52 INFO - u'firefox-56.0a1.en-US.mac.reftest.tests.zip'], 16:52:52 INFO - u'talos': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 16:52:52 INFO - u'firefox-56.0a1.en-US.mac.talos.tests.zip'], 16:52:52 INFO - u'web-platform': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 16:52:52 INFO - u'firefox-56.0a1.en-US.mac.web-platform.tests.tar.gz'], 16:52:52 INFO - u'xpcshell': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 16:52:52 INFO - u'firefox-56.0a1.en-US.mac.xpcshell.tests.zip']} 16:52:52 INFO - Downloading packages: [u'firefox-56.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:52:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JEIcrPHUQY-a6gBFETciEw/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip 16:52:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JEIcrPHUQY-a6gBFETciEw/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:52:52 INFO - Fetch https://queue.taskcluster.net/v1/task/JEIcrPHUQY-a6gBFETciEw/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip into memory 16:52:53 INFO - Content-Length response header: 59540091 16:52:53 INFO - Bytes received: 59540091 16:53:12 INFO - Downloading packages: [u'firefox-56.0a1.en-US.mac.common.tests.zip', u'firefox-56.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 16:53:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JEIcrPHUQY-a6gBFETciEw/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip 16:53:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JEIcrPHUQY-a6gBFETciEw/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:53:12 INFO - Fetch https://queue.taskcluster.net/v1/task/JEIcrPHUQY-a6gBFETciEw/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip into memory 16:53:13 INFO - Content-Length response header: 59540091 16:53:13 INFO - Bytes received: 59540091 16:53:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JEIcrPHUQY-a6gBFETciEw/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip 16:53:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JEIcrPHUQY-a6gBFETciEw/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:53:31 INFO - Fetch https://queue.taskcluster.net/v1/task/JEIcrPHUQY-a6gBFETciEw/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip into memory 16:53:32 INFO - Content-Length response header: 14450010 16:53:32 INFO - Bytes received: 14450010 16:53:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:53:32 INFO - trying https://queue.taskcluster.net/v1/task/JEIcrPHUQY-a6gBFETciEw/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 16:53:32 INFO - Downloading https://queue.taskcluster.net/v1/task/JEIcrPHUQY-a6gBFETciEw/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg 16:53:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JEIcrPHUQY-a6gBFETciEw/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg'}, attempt #1 16:53:34 INFO - Downloaded 69305235 bytes. 16:53:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JEIcrPHUQY-a6gBFETciEw/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 16:53:34 INFO - mkdir: /builds/slave/test/properties 16:53:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:53:34 INFO - Writing to file /builds/slave/test/properties/build_url 16:53:34 INFO - Contents: 16:53:34 INFO - build_url:https://queue.taskcluster.net/v1/task/JEIcrPHUQY-a6gBFETciEw/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 16:53:34 INFO - Running post-action listener: _resource_record_post_action 16:53:34 INFO - Running post-action listener: set_extra_try_arguments 16:53:34 INFO - [mozharness: 2017-06-12 23:53:34.547903Z] Finished download-and-extract step (success) 16:53:34 INFO - [mozharness: 2017-06-12 23:53:34.548012Z] Running populate-webroot step. 16:53:34 INFO - Running pre-action listener: _resource_record_pre_action 16:53:34 INFO - Running main action method: populate_webroot 16:53:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:53:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:53:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:53:34 INFO - '--tppagecycles', 16:53:34 INFO - '7'], 16:53:34 INFO - 'tests': ['tsvgm']}}, 16:53:34 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:53:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:53:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:53:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:53:34 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:53:34 INFO - 'talos_options': ['--disable-e10s'], 16:53:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:53:34 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:53:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:53:34 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:53:34 INFO - 'talos_options': ['--disable-e10s'], 16:53:34 INFO - 'tests': ['damp', 'tps']}, 16:53:34 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:53:34 INFO - 'tests': ['damp', 'tps']}, 16:53:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:53:34 INFO - 'tests': ['dromaeo_dom']}, 16:53:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:53:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:53:34 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 16:53:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:53:34 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:53:34 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:53:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:53:34 INFO - 'tests': ['a11yr', 16:53:34 INFO - 'ts_paint', 16:53:34 INFO - 'tpaint', 16:53:34 INFO - 'sessionrestore', 16:53:34 INFO - 'sessionrestore_no_auto_restore', 16:53:34 INFO - 'tabpaint']}, 16:53:34 INFO - 'other-e10s': {'tests': ['a11yr', 16:53:34 INFO - 'ts_paint', 16:53:34 INFO - 'tpaint', 16:53:34 INFO - 'sessionrestore', 16:53:34 INFO - 'sessionrestore_no_auto_restore', 16:53:34 INFO - 'tabpaint']}, 16:53:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:53:34 INFO - 'ts_paint', 16:53:34 INFO - 'tpaint', 16:53:34 INFO - 'sessionrestore', 16:53:34 INFO - 'sessionrestore_no_auto_restore', 16:53:34 INFO - 'tabpaint']}, 16:53:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:53:34 INFO - 'ts_paint', 16:53:34 INFO - 'tpaint', 16:53:34 INFO - 'sessionrestore', 16:53:34 INFO - 'sessionrestore_no_auto_restore', 16:53:34 INFO - 'tabpaint']}, 16:53:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:53:34 INFO - 'tests': ['a11yr', 16:53:34 INFO - 'ts_paint', 16:53:34 INFO - 'tpaint', 16:53:34 INFO - 'sessionrestore', 16:53:34 INFO - 'sessionrestore_no_auto_restore', 16:53:34 INFO - 'tabpaint']}, 16:53:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:53:34 INFO - 'tests': ['a11yr', 16:53:34 INFO - 'ts_paint', 16:53:34 INFO - 'tpaint', 16:53:34 INFO - 'sessionrestore', 16:53:34 INFO - 'sessionrestore_no_auto_restore', 16:53:34 INFO - 'tabpaint']}, 16:53:34 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 16:53:34 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 16:53:34 INFO - 'bloom_basic_ref']}, 16:53:34 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:53:34 INFO - 'talos_options': ['--mitmproxy', 16:53:34 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:53:34 INFO - '--firstNonBlankPaint'], 16:53:34 INFO - 'tests': ['quantum_pageload_google', 16:53:34 INFO - 'quantum_pageload_youtube', 16:53:34 INFO - 'quantum_pageload_amazon', 16:53:34 INFO - 'quantum_pageload_facebook']}, 16:53:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:53:34 INFO - 'tests': ['tsvgx', 16:53:34 INFO - 'tsvgr_opacity', 16:53:34 INFO - 'tart', 16:53:34 INFO - 'tscrollx', 16:53:34 INFO - 'cart', 16:53:34 INFO - 'tsvg_static']}, 16:53:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:53:34 INFO - 'tsvgr_opacity', 16:53:34 INFO - 'tart', 16:53:34 INFO - 'tscrollx', 16:53:34 INFO - 'cart', 16:53:34 INFO - 'tsvg_static']}, 16:53:34 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:53:34 INFO - 'talos_options': ['--disable-e10s'], 16:53:34 INFO - 'tests': ['tp5o']}, 16:53:34 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:53:34 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:53:34 INFO - 'talos_options': ['--disable-e10s', 16:53:34 INFO - '--xperf_path', 16:53:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:53:34 INFO - 'tests': ['tp5n']}, 16:53:34 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:53:34 INFO - 'talos_options': ['--xperf_path', 16:53:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:53:34 INFO - 'tests': ['tp5n']}}, 16:53:34 INFO - 'talos.zip': {'path': '', 16:53:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:53:34 INFO - Running post-action listener: _resource_record_post_action 16:53:34 INFO - [mozharness: 2017-06-12 23:53:34.570926Z] Finished populate-webroot step (success) 16:53:34 INFO - [mozharness: 2017-06-12 23:53:34.571053Z] Running create-virtualenv step. 16:53:34 INFO - Running pre-action listener: _resource_record_pre_action 16:53:34 INFO - Running main action method: create_virtualenv 16:53:34 INFO - Creating virtualenv /builds/slave/test/build/venv 16:53:34 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 16:53:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:53:34 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:53:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:53:34 INFO - Using real prefix '/tools/python27' 16:53:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:53:35 INFO - Installing distribute.............................................................................................................................................................................................done. 16:53:38 INFO - Installing pip.................done. 16:53:38 INFO - Return code: 0 16:53:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:53:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:53:38 INFO - Reading from file tmpfile_stdout 16:53:38 INFO - Output received: 16:53:38 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) 16:53:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:53:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:53:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:53:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:53:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:53:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f750c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f754768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe491c4f300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4a6d30>, '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 0x10f470cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f497be8>, '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.oDoijyvLlt/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.2wFZ7MDzgR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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 16:53:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:53:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:53:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oDoijyvLlt/Render', 16:53:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:53:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:53:38 INFO - 'HOME': '/Users/cltbld', 16:53:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:53:38 INFO - 'LOGNAME': 'cltbld', 16:53:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:53:38 INFO - 'MOZ_NO_REMOTE': '1', 16:53:38 INFO - 'NO_EM_RESTART': '1', 16:53:38 INFO - 'PAGER': '/bin/cat', 16:53:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:53:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:53:38 INFO - 'PWD': '/builds/slave/test', 16:53:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:53:38 INFO - 'SHELL': '/bin/bash', 16:53:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2wFZ7MDzgR/Listeners', 16:53:38 INFO - 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/', 16:53:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:53:38 INFO - 'USER': 'cltbld', 16:53:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:53:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:53:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:53:38 INFO - 'XPC_FLAGS': '0x0', 16:53:38 INFO - 'XPC_SERVICE_NAME': '0', 16:53:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:53:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:53:38 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 16:53:38 INFO - Cleaning up... 16:53:38 INFO - Return code: 0 16:53:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:53:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:53:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:53:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:53:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:53:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f750c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f754768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe491c4f300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4a6d30>, '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 0x10f470cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f497be8>, '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.oDoijyvLlt/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.2wFZ7MDzgR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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 16:53:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:53:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:53:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oDoijyvLlt/Render', 16:53:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:53:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:53:38 INFO - 'HOME': '/Users/cltbld', 16:53:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:53:38 INFO - 'LOGNAME': 'cltbld', 16:53:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:53:38 INFO - 'MOZ_NO_REMOTE': '1', 16:53:38 INFO - 'NO_EM_RESTART': '1', 16:53:38 INFO - 'PAGER': '/bin/cat', 16:53:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:53:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:53:38 INFO - 'PWD': '/builds/slave/test', 16:53:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:53:38 INFO - 'SHELL': '/bin/bash', 16:53:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2wFZ7MDzgR/Listeners', 16:53:38 INFO - 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/', 16:53:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:53:38 INFO - 'USER': 'cltbld', 16:53:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:53:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:53:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:53:38 INFO - 'XPC_FLAGS': '0x0', 16:53:38 INFO - 'XPC_SERVICE_NAME': '0', 16:53:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:53:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:53:39 INFO - Downloading/unpacking psutil>=3.1.1 16:53:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:53:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:53:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:53:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:53:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:53:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:53:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:53:41 INFO - Installing collected packages: psutil 16:53:41 INFO - Running setup.py install for psutil 16:53:41 INFO - building 'psutil._psutil_osx' extension 16:53:41 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 16:53:41 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 16:53:42 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 16:53:42 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 16:53:42 INFO - building 'psutil._psutil_posix' extension 16:53:42 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 16:53:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:53:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:53:42 INFO - ^ 16:53:42 INFO - 1 warning generated. 16:53:42 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 16:53:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:53:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:53:42 INFO - Successfully installed psutil 16:53:42 INFO - Cleaning up... 16:53:42 INFO - Return code: 0 16:53:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:53:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:53:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:53:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:53:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:53:42 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 0x10f750c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f754768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe491c4f300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4a6d30>, '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 0x10f470cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f497be8>, '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.oDoijyvLlt/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.2wFZ7MDzgR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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 16:53:42 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 16:53:42 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 16:53:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oDoijyvLlt/Render', 16:53:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:53:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:53:42 INFO - 'HOME': '/Users/cltbld', 16:53:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:53:42 INFO - 'LOGNAME': 'cltbld', 16:53:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:53:42 INFO - 'MOZ_NO_REMOTE': '1', 16:53:42 INFO - 'NO_EM_RESTART': '1', 16:53:42 INFO - 'PAGER': '/bin/cat', 16:53:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:53:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:53:42 INFO - 'PWD': '/builds/slave/test', 16:53:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:53:42 INFO - 'SHELL': '/bin/bash', 16:53:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2wFZ7MDzgR/Listeners', 16:53:42 INFO - 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/', 16:53:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:53:42 INFO - 'USER': 'cltbld', 16:53:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:53:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:53:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:53:42 INFO - 'XPC_FLAGS': '0x0', 16:53:42 INFO - 'XPC_SERVICE_NAME': '0', 16:53:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:53:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:53:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:53:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:53:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:53:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:53:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:53:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:53:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:53:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:53:44 INFO - Installing collected packages: mozsystemmonitor 16:53:44 INFO - Running setup.py install for mozsystemmonitor 16:53:44 INFO - Successfully installed mozsystemmonitor 16:53:44 INFO - Cleaning up... 16:53:45 INFO - Return code: 0 16:53:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:53:45 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')]} 16:53:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:53:45 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')]} 16:53:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:53:45 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 0x10f750c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f754768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe491c4f300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4a6d30>, '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 0x10f470cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f497be8>, '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.oDoijyvLlt/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.2wFZ7MDzgR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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 16:53:45 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 16:53:45 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 16:53:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oDoijyvLlt/Render', 16:53:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:53:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:53:45 INFO - 'HOME': '/Users/cltbld', 16:53:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:53:45 INFO - 'LOGNAME': 'cltbld', 16:53:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:53:45 INFO - 'MOZ_NO_REMOTE': '1', 16:53:45 INFO - 'NO_EM_RESTART': '1', 16:53:45 INFO - 'PAGER': '/bin/cat', 16:53:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:53:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:53:45 INFO - 'PWD': '/builds/slave/test', 16:53:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:53:45 INFO - 'SHELL': '/bin/bash', 16:53:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2wFZ7MDzgR/Listeners', 16:53:45 INFO - 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/', 16:53:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:53:45 INFO - 'USER': 'cltbld', 16:53:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:53:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:53:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:53:45 INFO - 'XPC_FLAGS': '0x0', 16:53:45 INFO - 'XPC_SERVICE_NAME': '0', 16:53:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:53:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:53:45 INFO - Downloading/unpacking jsonschema==2.5.1 16:53:45 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 16:53:45 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 16:53:45 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 16:53:45 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 16:53:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:53:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:53:46 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 16:53:46 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 16:53:46 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 16:53:46 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 16:53:46 INFO - Downloading functools32-3.2.3-2.tar.gz 16:53:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:53:46 INFO - warning: no files found matching '*.txt' 16:53:46 INFO - no previously-included directories found matching 'build' 16:53:46 INFO - no previously-included directories found matching 'dist' 16:53:46 INFO - no previously-included directories found matching '.git*' 16:53:46 INFO - Installing collected packages: jsonschema, functools32 16:53:47 INFO - Running setup.py install for functools32 16:53:47 INFO - warning: no files found matching '*.txt' 16:53:47 INFO - no previously-included directories found matching 'build' 16:53:47 INFO - no previously-included directories found matching 'dist' 16:53:47 INFO - no previously-included directories found matching '.git*' 16:53:47 INFO - Successfully installed jsonschema functools32 16:53:47 INFO - Cleaning up... 16:53:47 INFO - Return code: 0 16:53:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:53:47 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')]} 16:53:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:53:47 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')]} 16:53:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:53:47 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 0x10f750c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f754768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe491c4f300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4a6d30>, '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 0x10f470cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f497be8>, '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.oDoijyvLlt/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.2wFZ7MDzgR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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 16:53:47 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 16:53:47 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 16:53:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oDoijyvLlt/Render', 16:53:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:53:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:53:47 INFO - 'HOME': '/Users/cltbld', 16:53:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:53:47 INFO - 'LOGNAME': 'cltbld', 16:53:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:53:47 INFO - 'MOZ_NO_REMOTE': '1', 16:53:47 INFO - 'NO_EM_RESTART': '1', 16:53:47 INFO - 'PAGER': '/bin/cat', 16:53:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:53:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:53:47 INFO - 'PWD': '/builds/slave/test', 16:53:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:53:47 INFO - 'SHELL': '/bin/bash', 16:53:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2wFZ7MDzgR/Listeners', 16:53:47 INFO - 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/', 16:53:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:53:47 INFO - 'USER': 'cltbld', 16:53:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:53:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:53:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:53:47 INFO - 'XPC_FLAGS': '0x0', 16:53:47 INFO - 'XPC_SERVICE_NAME': '0', 16:53:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:53:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:53:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:53:47 INFO - Cleaning up... 16:53:47 INFO - Return code: 0 16:53:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:53:47 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')]} 16:53:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:53:47 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')]} 16:53:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:53:47 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 0x10f750c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f754768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe491c4f300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4a6d30>, '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 0x10f470cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f497be8>, '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.oDoijyvLlt/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.2wFZ7MDzgR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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 16:53:47 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 16:53:47 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 16:53:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oDoijyvLlt/Render', 16:53:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:53:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:53:47 INFO - 'HOME': '/Users/cltbld', 16:53:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:53:47 INFO - 'LOGNAME': 'cltbld', 16:53:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:53:47 INFO - 'MOZ_NO_REMOTE': '1', 16:53:47 INFO - 'NO_EM_RESTART': '1', 16:53:47 INFO - 'PAGER': '/bin/cat', 16:53:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:53:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:53:47 INFO - 'PWD': '/builds/slave/test', 16:53:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:53:47 INFO - 'SHELL': '/bin/bash', 16:53:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2wFZ7MDzgR/Listeners', 16:53:47 INFO - 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/', 16:53:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:53:47 INFO - 'USER': 'cltbld', 16:53:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:53:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:53:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:53:47 INFO - 'XPC_FLAGS': '0x0', 16:53:47 INFO - 'XPC_SERVICE_NAME': '0', 16:53:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:53:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:53:47 INFO - Downloading/unpacking blobuploader==1.2.4 16:53:47 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 16:53:47 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 16:53:47 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 16:53:47 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 16:53:49 INFO - Downloading blobuploader-1.2.4.tar.gz 16:53:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:53:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:53:49 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 16:53:49 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 16:53:49 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 16:53:49 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 16:53:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:53:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:53:49 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 16:53:49 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 16:53:49 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 16:53:49 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 16:53:50 INFO - Downloading docopt-0.6.1.tar.gz 16:53:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:53:50 INFO - Installing collected packages: blobuploader, requests, docopt 16:53:50 INFO - Running setup.py install for blobuploader 16:53:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:53:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:53:50 INFO - Running setup.py install for requests 16:53:50 INFO - Running setup.py install for docopt 16:53:50 INFO - Successfully installed blobuploader requests docopt 16:53:50 INFO - Cleaning up... 16:53:50 INFO - Return code: 0 16:53:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:53:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:53:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:53:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:53:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:53:50 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 0x10f750c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f754768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe491c4f300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4a6d30>, '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 0x10f470cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f497be8>, '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.oDoijyvLlt/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.2wFZ7MDzgR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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 16:53:50 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 16:53:50 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 16:53:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oDoijyvLlt/Render', 16:53:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:53:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:53:50 INFO - 'HOME': '/Users/cltbld', 16:53:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:53:50 INFO - 'LOGNAME': 'cltbld', 16:53:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:53:50 INFO - 'MOZ_NO_REMOTE': '1', 16:53:50 INFO - 'NO_EM_RESTART': '1', 16:53:50 INFO - 'PAGER': '/bin/cat', 16:53:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:53:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:53:50 INFO - 'PWD': '/builds/slave/test', 16:53:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:53:50 INFO - 'SHELL': '/bin/bash', 16:53:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2wFZ7MDzgR/Listeners', 16:53:50 INFO - 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/', 16:53:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:53:50 INFO - 'USER': 'cltbld', 16:53:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:53:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:53:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:53:50 INFO - 'XPC_FLAGS': '0x0', 16:53:50 INFO - 'XPC_SERVICE_NAME': '0', 16:53:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:53:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:53:51 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-VwIImp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:53:51 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-LKlf5k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:53:51 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-eScIBx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:53:51 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-NrNK1P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:53:51 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-gQMbWe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:53:51 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-raNqfw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:53:51 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-NHMNGP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:53:51 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-8rqSMf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:53:52 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-i6UeuH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:53:52 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-ZATuc8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:53:52 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-PyR2oe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:53:52 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-F3iKt8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:53:52 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-5Lyqqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:53:52 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-Rzlikx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:53:52 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-QsDpSf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:53:52 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-y77CFh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:53:52 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-i5dMyX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:53:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:53:53 INFO - Running setup.py install for manifestparser 16:53:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:53:53 INFO - Running setup.py install for mozcrash 16:53:53 INFO - Running setup.py install for mozdebug 16:53:53 INFO - Running setup.py install for mozdevice 16:53:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:53:53 INFO - Running setup.py install for mozfile 16:53:53 INFO - Running setup.py install for mozhttpd 16:53:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:53:53 INFO - Running setup.py install for mozinfo 16:53:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:53:54 INFO - Running setup.py install for mozInstall 16:53:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:53:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:53:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:53:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:53:54 INFO - Running setup.py install for mozleak 16:53:54 INFO - Running setup.py install for mozlog 16:53:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:53:54 INFO - Running setup.py install for moznetwork 16:53:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:53:54 INFO - Running setup.py install for mozprocess 16:53:54 INFO - Running setup.py install for mozprofile 16:53:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:53:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:53:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:53:54 INFO - Running setup.py install for mozrunner 16:53:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:53:55 INFO - Running setup.py install for mozscreenshot 16:53:55 INFO - Running setup.py install for moztest 16:53:55 INFO - Running setup.py install for mozversion 16:53:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:53:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:53:55 INFO - Cleaning up... 16:53:55 INFO - Return code: 0 16:53:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:53:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:53:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:53:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:53:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:53:55 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 0x10f750c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f754768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe491c4f300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4a6d30>, '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 0x10f470cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f497be8>, '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.oDoijyvLlt/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.2wFZ7MDzgR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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 16:53:55 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 16:53:55 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 16:53:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oDoijyvLlt/Render', 16:53:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:53:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:53:55 INFO - 'HOME': '/Users/cltbld', 16:53:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:53:55 INFO - 'LOGNAME': 'cltbld', 16:53:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:53:55 INFO - 'MOZ_NO_REMOTE': '1', 16:53:55 INFO - 'NO_EM_RESTART': '1', 16:53:55 INFO - 'PAGER': '/bin/cat', 16:53:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:53:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:53:55 INFO - 'PWD': '/builds/slave/test', 16:53:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:53:55 INFO - 'SHELL': '/bin/bash', 16:53:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2wFZ7MDzgR/Listeners', 16:53:55 INFO - 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/', 16:53:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:53:55 INFO - 'USER': 'cltbld', 16:53:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:53:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:53:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:53:55 INFO - 'XPC_FLAGS': '0x0', 16:53:55 INFO - 'XPC_SERVICE_NAME': '0', 16:53:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:53:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:53:55 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-pN15NH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:53:55 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)) 16:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:53:55 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-05TPEr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:53:56 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)) 16:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:53:56 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-ecvMA5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:53:56 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)) 16:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:53:56 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-a9maOZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:53:56 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)) 16:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:53:56 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-D5we4I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:53:56 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)) 16:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:53:56 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-v7AHWq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:53:56 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)) 16:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:53:56 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-CoG4yi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:53:56 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)) 16:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:53:56 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-p5Kl_a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:53:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:53:56 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-KGbZZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:53:56 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)) 16:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:53:56 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-rjGu92-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:53:56 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)) 16:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:53:56 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-KSKJNy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:53:56 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)) 16:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:53:57 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-ptexgc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:53:57 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)) 16:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:53:57 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-LkhCeB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:53:57 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)) 16:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:53:57 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-8Ijbp7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:53:57 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)) 16:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:53:57 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-wNmsv_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:53:57 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)) 16:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:53:57 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-Igy2KD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:53:57 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)) 16:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:53:57 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-QOQxzM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:53:57 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)) 16:53:57 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)) 16:53:57 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)) 16:53:57 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)) 16:53:57 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)) 16:53:57 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)) 16:53:57 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:53:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:53:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:53:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:53:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:53:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:53:59 INFO - Downloading blessings-1.6.tar.gz 16:53:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:53:59 INFO - Installing collected packages: blessings 16:53:59 INFO - Running setup.py install for blessings 16:53:59 INFO - Successfully installed blessings 16:53:59 INFO - Cleaning up... 16:53:59 INFO - Return code: 0 16:53:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:53:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:53:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:53:59 INFO - Reading from file tmpfile_stdout 16:53:59 INFO - Current package versions: 16:53:59 INFO - blessings == 1.6 16:53:59 INFO - blobuploader == 1.2.4 16:53:59 INFO - docopt == 0.6.1 16:53:59 INFO - functools32 == 3.2.3-2 16:53:59 INFO - jsonschema == 2.5.1 16:53:59 INFO - manifestparser == 1.1 16:53:59 INFO - mozInstall == 1.13 16:53:59 INFO - mozcrash == 1.0 16:53:59 INFO - mozdebug == 0.1 16:53:59 INFO - mozdevice == 0.51 16:53:59 INFO - mozfile == 1.2 16:53:59 INFO - mozhttpd == 0.7 16:53:59 INFO - mozinfo == 0.10 16:53:59 INFO - mozleak == 0.1 16:53:59 INFO - mozlog == 3.4 16:53:59 INFO - moznetwork == 0.27 16:53:59 INFO - mozprocess == 0.25 16:53:59 INFO - mozprofile == 0.28 16:53:59 INFO - mozrunner == 6.13 16:53:59 INFO - mozscreenshot == 0.1 16:53:59 INFO - mozsystemmonitor == 0.3 16:53:59 INFO - moztest == 0.8 16:53:59 INFO - mozversion == 1.4 16:53:59 INFO - psutil == 3.1.1 16:53:59 INFO - requests == 1.2.3 16:53:59 INFO - wsgiref == 0.1.2 16:53:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:53:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:53:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:53:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:53:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:53:59 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 0x10f750c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f754768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe491c4f300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4a6d30>, '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 0x10f470cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f497be8>, '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.oDoijyvLlt/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.2wFZ7MDzgR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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 16:53:59 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 16:53:59 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 16:53:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oDoijyvLlt/Render', 16:53:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:53:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:53:59 INFO - 'HOME': '/Users/cltbld', 16:53:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:53:59 INFO - 'LOGNAME': 'cltbld', 16:53:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:53:59 INFO - 'MOZ_NO_REMOTE': '1', 16:53:59 INFO - 'NO_EM_RESTART': '1', 16:53:59 INFO - 'PAGER': '/bin/cat', 16:53:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:53:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:53:59 INFO - 'PWD': '/builds/slave/test', 16:53:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:53:59 INFO - 'SHELL': '/bin/bash', 16:53:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2wFZ7MDzgR/Listeners', 16:53:59 INFO - 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/', 16:53:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:53:59 INFO - 'USER': 'cltbld', 16:53:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:53:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:53:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:53:59 INFO - 'XPC_FLAGS': '0x0', 16:53:59 INFO - 'XPC_SERVICE_NAME': '0', 16:53:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:53:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:53:59 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)) 16:53:59 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)) 16:53:59 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)) 16:53:59 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)) 16:53:59 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)) 16:53:59 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)) 16:53:59 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)) 16:53:59 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)) 16:53:59 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)) 16:53:59 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:53:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:53:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:53:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:53:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:54:01 INFO - Downloading marionette_driver-1.4.0.tar.gz 16:54:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 16:54:01 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)) 16:54:01 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)) 16:54:01 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)) 16:54:01 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)) 16:54:01 INFO - Installing collected packages: marionette-driver 16:54:01 INFO - Running setup.py install for marionette-driver 16:54:01 INFO - Successfully installed marionette-driver 16:54:01 INFO - Cleaning up... 16:54:01 INFO - Return code: 0 16:54:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:54:01 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')]} 16:54:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:54:01 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')]} 16:54:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:54:01 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 0x10f750c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f754768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe491c4f300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4a6d30>, '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 0x10f470cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f497be8>, '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.oDoijyvLlt/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.2wFZ7MDzgR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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 16:54:01 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 16:54:01 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 16:54:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oDoijyvLlt/Render', 16:54:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:54:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:54:01 INFO - 'HOME': '/Users/cltbld', 16:54:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:54:01 INFO - 'LOGNAME': 'cltbld', 16:54:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:54:01 INFO - 'MOZ_NO_REMOTE': '1', 16:54:01 INFO - 'NO_EM_RESTART': '1', 16:54:01 INFO - 'PAGER': '/bin/cat', 16:54:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:54:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:54:01 INFO - 'PWD': '/builds/slave/test', 16:54:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:54:01 INFO - 'SHELL': '/bin/bash', 16:54:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2wFZ7MDzgR/Listeners', 16:54:01 INFO - 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/', 16:54:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:54:01 INFO - 'USER': 'cltbld', 16:54:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:54:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:54:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:54:01 INFO - 'XPC_FLAGS': '0x0', 16:54:01 INFO - 'XPC_SERVICE_NAME': '0', 16:54:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:54:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:54:01 INFO - Cleaning up... 16:54:01 INFO - Return code: 0 16:54:01 INFO - Running post-action listener: _resource_record_post_action 16:54:01 INFO - Running post-action listener: _start_resource_monitoring 16:54:01 INFO - Starting resource monitoring. 16:54:01 INFO - [mozharness: 2017-06-12 23:54:01.985626Z] Finished create-virtualenv step (success) 16:54:01 INFO - [mozharness: 2017-06-12 23:54:01.986617Z] Running install step. 16:54:01 INFO - Running pre-action listener: _resource_record_pre_action 16:54:01 INFO - Running main action method: install 16:54:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:54:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:54:02 INFO - Reading from file tmpfile_stdout 16:54:02 INFO - Detecting whether we're running mozinstall >=1.0... 16:54:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:54:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:54:02 INFO - Reading from file tmpfile_stdout 16:54:02 INFO - Output received: 16:54:02 INFO - Usage: mozinstall [options] installer 16:54:02 INFO - Options: 16:54:02 INFO - -h, --help show this help message and exit 16:54:02 INFO - -d DEST, --destination=DEST 16:54:02 INFO - Directory to install application into. [default: 16:54:02 INFO - "/builds/slave/test"] 16:54:02 INFO - --app=APP Application being installed. [default: firefox] 16:54:02 INFO - mkdir: /builds/slave/test/build/application 16:54:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:54:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 16:54:24 INFO - Reading from file tmpfile_stdout 16:54:24 INFO - Output received: 16:54:24 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:54:24 INFO - Running post-action listener: _resource_record_post_action 16:54:24 INFO - [mozharness: 2017-06-12 23:54:24.119986Z] Finished install step (success) 16:54:24 INFO - [mozharness: 2017-06-12 23:54:24.120147Z] Running setup-mitmproxy step. 16:54:24 INFO - Running pre-action listener: _resource_record_pre_action 16:54:24 INFO - Running main action method: setup_mitmproxy 16:54:24 INFO - Skipping: mitmproxy is not required 16:54:24 INFO - Running post-action listener: _resource_record_post_action 16:54:24 INFO - [mozharness: 2017-06-12 23:54:24.120789Z] Finished setup-mitmproxy step (success) 16:54:24 INFO - [mozharness: 2017-06-12 23:54:24.120890Z] Running run-tests step. 16:54:24 INFO - Running pre-action listener: _resource_record_pre_action 16:54:24 INFO - Running main action method: run_tests 16:54:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:54:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:54:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:54:24 INFO - 2017-06-12 16:54:24.135 screenresolution[1630:7468] starting screenresolution argv=screenresolution get 16:54:24 INFO - 2017-06-12 16:54:24.138 screenresolution[1630:7468] Display 0: 1600x1200x32@60 16:54:24 INFO - 2017-06-12 16:54:24.144 screenresolution[1631:7471] starting screenresolution argv=screenresolution list 16:54:24 INFO - Available Modes on Display 0 16:54:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:54:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:54:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:54:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:54:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:54:24 INFO - Intel Iris: 16:54:24 INFO - Chipset Model: Intel Iris 16:54:24 INFO - Type: GPU 16:54:24 INFO - Bus: Built-In 16:54:24 INFO - VRAM (Dynamic, Max): 1536 MB 16:54:24 INFO - Vendor: Intel (0x8086) 16:54:24 INFO - Device ID: 0x0a2e 16:54:24 INFO - Revision ID: 0x0009 16:54:24 INFO - Displays: 16:54:24 INFO - Display: 16:54:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:54:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:54:24 INFO - Display Serial Number: 1600x1200 60 16:54:24 INFO - Main Display: Yes 16:54:24 INFO - Mirror: Off 16:54:24 INFO - Online: Yes 16:54:24 INFO - Rotation: Supported 16:54:24 INFO - Return code: 0 16:54:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:54:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:54:24 INFO - Python 2.7.3 16:54:24 INFO - Return code: 0 16:54:24 INFO - grabbing minidump binary from tooltool 16:54: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')]} 16:54:24 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 0x10f4a6d30>, '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 0x10f470cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f497be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:54:24 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 16:54:24 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 16:54:24 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 16:54:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:54:24 INFO - Return code: 0 16:54:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:54:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:54:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:54:24 INFO - ENV: RUST_BACKTRACE is now 1 16:54:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:54:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:54:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JEIcrPHUQY-a6gBFETciEw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0384', '--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 16:54:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Cedar --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JEIcrPHUQY-a6gBFETciEw/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0384 --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 16:54:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oDoijyvLlt/Render', 16:54:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:54:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:54:24 INFO - 'HOME': '/Users/cltbld', 16:54:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:54:24 INFO - 'LOGNAME': 'cltbld', 16:54:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:54:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:54:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:54:24 INFO - 'MOZ_NO_REMOTE': '1', 16:54:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:54:24 INFO - 'NO_EM_RESTART': '1', 16:54:24 INFO - 'PAGER': '/bin/cat', 16:54:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:54:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:54:24 INFO - 'PWD': '/builds/slave/test', 16:54:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:54:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:54:24 INFO - 'RUST_BACKTRACE': '1', 16:54:24 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:54:24 INFO - 'SHELL': '/bin/bash', 16:54:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2wFZ7MDzgR/Listeners', 16:54:24 INFO - 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/', 16:54:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:54:24 INFO - 'USER': 'cltbld', 16:54:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:54:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:54:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:54:24 INFO - 'XPC_FLAGS': '0x0', 16:54:24 INFO - 'XPC_SERVICE_NAME': '0', 16:54:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:54:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JEIcrPHUQY-a6gBFETciEw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0384', '--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 16:54:24 INFO - mozversion application_buildid: 20170612151508 16:54:24 INFO - mozversion application_changeset: 1a88c8de416089a09f5e67ed1dab148156b9151b 16:54:24 INFO - mozversion application_display_name: Nightly 16:54:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:54:24 INFO - mozversion application_name: Firefox 16:54:24 INFO - mozversion application_remotingname: firefox 16:54:24 INFO - mozversion application_repository: https://hg.mozilla.org/projects/cedar 16:54:24 INFO - mozversion application_vendor: Mozilla 16:54:24 INFO - mozversion application_version: 56.0a1 16:54:24 INFO - mozversion platform_buildid: 20170612151508 16:54:24 INFO - mozversion platform_changeset: 1a88c8de416089a09f5e67ed1dab148156b9151b 16:54:24 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/cedar 16:54:24 INFO - mozversion platform_version: 56.0a1 16:54:24 INFO - using testdate: 1497311664 16:54:24 INFO - actual date: 1497311664 16:54:24 INFO - starting webserver on 'localhost:49221' 16:54:24 INFO - SUITE-START | Running 2 tests 16:54:24 INFO - TEST-START | dromaeo_css 16:54:24 INFO - operating with platform_type : mac_ 16:54:24 INFO - Initialising browser for dromaeo_css test... 16:54:24 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49221/getInfo.html -foreground -profile /var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/tmpXk8UaO/profile 16:54:24 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49221/getInfo.html -foreground) 16:54:58 INFO - TEST-INFO | 1641: killed by SIGTERM 16:54:58 INFO - Browser initialized. 16:54:58 INFO - Running cycle 1/1 for dromaeo_css test... 16:54:58 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/tmpXk8UaO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:55:00 INFO - PID 1652 | 2017-06-12 16:55:00.691 plugin-container[1653:8223] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9843, name = 'com.apple.tsm.portname' 16:55:00 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:55:00 INFO - PID 1652 | 2017-06-12 16:55:00.692 plugin-container[1653:8223] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0f, name = 'com.apple.CFPasteboardClient' 16:55:00 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:55:00 INFO - PID 1652 | 2017-06-12 16:55:00.692 plugin-container[1653:8223] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:55:01 INFO - PID 1652 | 2017-06-12 16:55:01.470 plugin-container[1654:8276] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 16:55:01 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:55:01 INFO - PID 1652 | 2017-06-12 16:55:01.471 plugin-container[1654:8276] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 16:55:01 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:55:01 INFO - PID 1652 | 2017-06-12 16:55:01.471 plugin-container[1654:8276] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:55:10 INFO - PID 1652 | RSS: Main: 252354560 16:55:10 INFO - PID 1652 | 16:58:13 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49221/tests/dromaeo/cssquery-ext.html) 16:58:15 INFO - PID 1652 | RSS: Main: 263774208 16:58:15 INFO - PID 1652 | 17:01:17 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-ext.html (next: http://localhost:49221/tests/dromaeo/cssquery-jquery.html) 17:01:17 INFO - PID 1652 | RSS: Main: 274644992 17:01:17 INFO - PID 1652 | 17:04:19 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49221/tests/dromaeo/cssquery-mootools.html) 17:04:20 INFO - PID 1652 | RSS: Main: 278310912 17:04:20 INFO - PID 1652 | 17:07:21 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49221/tests/dromaeo/cssquery-prototype.html) 17:07:22 INFO - PID 1652 | RSS: Main: 278843392 17:07:22 INFO - PID 1652 | 17:10:25 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49221/tests/dromaeo/cssquery-yui.html) 17:10:25 INFO - PID 1652 | RSS: Main: 281407488 17:10:25 INFO - PID 1652 | 17:13:27 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-yui.html (next: http://localhost:49221/tests/dromaeo/cssquery-dojo.html) 17:13:27 INFO - PID 1652 | RSS: Main: 279248896 17:13:27 INFO - PID 1652 | 17:13:27 INFO - PID 1652 | __start_tp_report 17:13:27 INFO - PID 1652 | _x_x_mozilla_page_load 17:13:27 INFO - PID 1652 | _x_x_mozilla_page_load_details 17:13:27 INFO - PID 1652 | |i|pagename|runs| 17:13:27 INFO - PID 1652 | |0;dojo.html;3448.2758620689656;3550.1479644885226;3591.6767490925795;3598.8740394086194;3627.52842130523;6141.631502109871;6190.1643278157435;6192.690365481731;6205.255369355684;6242.687865606718;6209.658468784215;6232.84417889553;6299.401556852104;6300.716467758951;6303.873922521547;4416.646668266547;4971.776270067838;5521.723913804314;5527.834164975057;5653.6607803531715;5568.220449137124;5623.128415095646;5626.695199940284;5651.8587035324035;5658.236138121362;5396.541293989976;5439.265699130646;5527.723613819293;5561.110222364402;5589.329280486348;4403.8458653946955;5436.429174936654;5437.02133615949;5455.918161227585;5544.22380866679;5423.620346575742;5496.148097044965;5565.860853478655;5567.9977604031255;5580.720963951786;6151.200343955257;6197.194364732601;6214.844628884269;6233.937660623381;6262.96868515659;6663.566868153554;6695.799126026178;6707.932920670828;6708.865822683519;6725.2602213756445;5623.803166889139;6515.609063456208;6661.26726060133;6795.286494918061;6799.388055075067;6245.344238854855;6296.087067375205;6298.842528936768;6299.543178160521;6307.747690092391;6927.445804335742;6983.930160698328;7001.859962800715;7008.159020917421;7019.789406317818;6410.134631824737;6492.058651495507;6519.5436319457185;6593.769218077345;6626.900596491057;6617.106690596708;6622.602643841385;6706.83232919181;6740.865182696319;6791.72833086671;6673.833154171088;6695.598264104169;6702.83242918931;6708.161479815023;6713.966430167818;6696.06255124283;6706.396424321834;6733.46132309414;6734.0572319875255;6748.797536073926;5237.764300606482;5259.15853463444;5297.894042119136;5302.734863256822;5307.363116425976;4026.315526360526;4725.4565724941385;4748.19280722278;4751.809927602926;4776.617870570346;5673.461021202979;5892.045738253324;6037.396260373927;6255.280642726145;6320;6792.59244445325;6809.285025072395;6832.282610325944;6834;6857.794266172023;6321.65230912295;6360.427561519486;6360.809175724643;6372.171617689671;6407.679616019181;5775.88448231033;5962.493188079062;5978.790742323998;5987.491063259585;5997.910031349533;6817.488688348394;6882.380585747307;6920.4809639277255;6942.409895158968;6979.895301570379;5380.354357477127;6805.3875151236625;6836.589804611739;6846.212685541027;6852.623105729033;6987.441004719712;6991.405730512964;6998.230194678479;7001.964990175016;7053.977173309944;6643.867122657423;6651.301613330724;6687.431568316553;6691.899621505584;6704.128463299739;6585.703643335965;6645.568038077509;6657.900131497934;6675.499337549801;6815.69329380189;6412.23053233615;6467.062275970052;6542.149520562297;6550.148480697479;6582.572132811352;6049.606775559574;6259.3427690091885;6657.167854018247;6665.933340666531;6688.866222675617;5403.7838486460105;6389.520785941166;6491.707873145625;6503.6097834130105;6532.738690452328;6421;6459.61242325462;6476.5466417350335;6477.481801455967;6609.801705948829;6343.841403964938;6347.5239357047485;6365.204349456318;6396.456301214449;6410.326915673787 17:13:27 INFO - PID 1652 | |1;ext.html;789.2186735132217;817.4237162800226;818.8689809630458;839.3788596438635;851.3657325292654;36067.81966090169;36915;37072.814635926785;37114.62885371149;37615.05962350943;36381.81809090951;36810.07974800631;36915.89252322428;36959.630403695955;37038;7949.205079492048;8073.95963020187;8101.513909165439;8103.3112185464015;8103.594820258963;7929.762107136767;8017.398695097891;8032;8032.076311224197;8039.115697273295;8037.196280371945;8055.751358539435;8075.757727268162;8076.990376202974;8079.798005049862;7974.7208847690345;8025.879611805827;8033.919660803404;8054;8064.395170362217;7139.964300178466;7145.749898753519;7164.21193668696;7196.64016799158;7202.351788339075;34655.6534434655;34793.478097828556;34797.826010870034;34822.82588587041;34959;3553.9111522211892;3571.821408929543;3588.138846676805;3590.5870824855215;3592.4431713084514;28495.145145645663;28813.279668008257;28864;28872.27819304513;28878.42243155125;2439.902403903842;3017.5926249956196;3087.1201707513474;3090.2428904918215;3102.550130231104;251772;256998.99999999814;257144;315145;317761;36972;44838.77580612141;45029.874253143935;45272;45445;39384.01539961467;47363.05273894503;47916;48267.5173248263;48368.5163148364;13708.93145534266;13815.792763108562;13918.30408479572;13933.442662293395;13937.65155871091;11122.44387780624;11221.382823944597;11350.3757293348;11410;11417.48621312043;12137.69655758613;12259.69350766238;12950;12982.35088245584;13000.67498312531;5835.56233282497;5889.440503152194;5912.640092778599;5917.378675239124;5941.792037278675;967.6226271754022;1009.4952523738131;1012.6455740490917;1017.9491025448698;1066.2376400873413;19367.3221437249;21292.935353232275;22361.44151066067;22445.61169500693;22572.548549029256;957.2772556719632;1001.409168590535;1129.491728722062;1161.6631176958588;1170.1399471388522;11306.434678266054;11421.31472111676;11538.192326537062;11709.882901170879;12296.016318694483;328513;334743;336053;336417;336833;46706.299405508944;46820.7658961703;46960.530394696296;47092.5290747095;47205.29192062122;1477.793108964746;1512.6142833577367;1514.4547962733407;1519.027822193797;1537.5541093082882;21481.25854183238;21925.232616858335;22512.66231006569;22668.659970100132;23437.179698710464;36380;36497.27005459823;36500;36574.817125915266;36612.45081323729;238898;242302;242510;271017;271959;31901.042968710975;32007.839960800047;32077;32098;32223.838880805444;40541.39187912125;41256;41330.586694132675;41561.58438415577;41592;25829.609844985796;28012.43975120527;29030.27424314409;30675.539866901567;30828.22929426782;24507.754922450546;28794.28014299659;29491.705082949753;30013.69986300109;30033.849830750707;33981;35177.64822351846;36080.63919360773;36122.458163128096;36299.63700362963;26830.59754103651;29143.417131656824;29362.559561606166;29794.553081703358;31151.37697245997;18221.54446138857;22427.775722242568;24244.636330454705;24636.753632463446;25147 17:13:27 INFO - PID 1652 | |2;jquery.html;490.6197696784991;494.44622023333847;496.30021669446074;511.7952818872451;512.743628185907;21415.9292035398;21750.456238594023;22493.887530562344;22515.98678059487;22633;23789.405264868386;23927;23949.880250598722;24184.879075604596;24264.878675606597;6440.903386449207;6553.148090748196;6607.867842643144;6608.603483790988;6620.808254514185;6568.211814582257;6571.408573228408;6588.769393071243;6598;6611.008348747702;6051.001584738513;6201.162843016203;6422.133012043384;6459.257185423689;6460.450861676763;5485.890282194374;6392.840178995539;6439.774607888701;6454.967725161344;6463.57986730861;5018.498150184989;5019.899602007939;5901.23283973085;5906.143609176688;5925.259342582177;28345.433091338058;29503.852480737674;30282.69717302821;30483.847580762176;30534.694653053408;2191.791779780918;2202.9889850550803;2211.8672879627275;2230.7434645015705;2232;18487.352942646943;18841.717374239397;18955.052247387575;18961.957092359913;18986.52533686655;2165.2096984600666;2204.1954686539457;2225.677276794872;2228.6322756745117;2234.3297010896827;161047;166875;168325;168368;199102;13118.344082795822;13622.591322260349;14518.20149891766;14788.038777479645;14837.777433338508;19729.605407891762;19899.60200795976;19914.402567922985;20023.99880006023;20025;8260.802183683332;9625;9685.079917407835;9714.95142524297;9778.9022109778;7584.924150758422;8701.782455438664;8750.606222719993;8997.235235004968;9091.772705682279;8898.777530561658;9004.59479323432;9028.388644542236;9047.411918225425;9056;2167.5989941860807;2554.1315952576215;2562.5643640581147;2566.742296274849;2675.224184986332;977.9804403911882;981.8045468897989;982.9508524573743;1037.190991027;1037.7872536130076;20963.26628567993;21104;21240.893795530923;21326.36020919375;22183.66724499134;927.9536023198813;1019.90820826126;1025.4616326428536;1045.9320144190604;1046.3355769086684;8933.821323573493;11132.49903754333;11189.272697274651;11235.83146252807;11394.829077563843;570967;582567;589480;598662;604393;152487;153619;157804;158997.41002589825;159142;1017.1354348803458;1076.2896488317672;1167.2237961755507;1209.4859684633996;1257.0320852943182;18061.458156255332;22223;24308.75691243065;24333.634995474727;24979.625305620793;65097.67451162714;66344;66700;67956.86452922372;68443;89676;91026.54486727524;91174;91221;91510;11549.711257218301;11593.6521904343;11655.242409243372;11728.765424691288;11754.647360579196;17887.01621410783;18360.449186524427;18617.348392806187;18725.344612938483;18916.621667566298;12140.817887731868;15163.241837908281;15200.087994720352;15291.770623440434;15351.232438377812;13162;13265.403056862657;13314.800277996032;13432.798508022192;13807.447702091804;11213.60752373663;15284.617884552976;15419.922900385427;15591.610209744393;15599;9493.85759213626;10973.518574992273;11341.922517360837;11638.35989020612;11641.59254426091;14594.416223350947;14652.047616904867;14810.111393316436;14829.184394858386;14998.7000259992 17:13:27 INFO - PID 1652 | |3;mootools.html;1148.6266963236951;1329.9626291492634;1398.9370478328474;1423.7826658207232;1423.9715205695893;11216.214864959436;12851.29317887517;13248.801267980987;13460.932695336522;13466.865331346684;12910.806337904938;13255.867441325583;13290.069695121345;13301.866981330184;13322.000849936268;2252.966205506919;2751.312171957011;2754.848483333416;2757.5587905935054;2759.8206116602455;2673.7860971122263;2731.112388473744;2731.549294366437;2731.6721993360825;2734.5214587447217;1871.5227616957657;2340.958273568263;2669.2392668089597;2704.648395708555;2728.7544121029105;2277.464795772992;2311.7225932888077;2591.20968104728;2696.3528753099313;2703.4998525272886;2752.2156185487243;2755.6830964439146;2755.9724402756115;2756.0904901382496;2758.8344699318104;13134.080614356999;13217.206967581977;13231.073824832265;13234.86765132346;13264.204147751167;7218.963905180441;7447.069116360455;7496.887546686804;7515.286047825448;7518.210587888302;4479.350494178326;4657.883552911204;4657.95050177924;4752.714837109785;4758.881027974294;1411.6118067531409;1414.9812135262594;1420.936057877398;1431.391658545114;1445.407382972976;19556.511087222792;19650.90174549118;19655.606887862305;19784.901075494676;19843.107060182327;5276.709780962007;6376.872462550723;6492.285848556655;6587.571807832475;6598.076269322299;13903.235322057384;14086.15483071019;14086.71826563463;14110.50613228532;14123.223222722849;7104.538205016657;7243.674034668453;7244.891326630105;7260.2013778484325;7264.491485595958;6437.67811609427;6665.900011499832;6675;6686.732530698718;6747.156605424322;4841.2496063110275;4970.801167953314;5010.2735103409805;5057.494250574913;5069.138246498104;1421.0620990146454;1440.1503113163074;1444.8193975753031;1460.8612181842707;1466.8899832512604;789.2383849585178;804.2038382001776;819.815541503157;823.2755175445569;835.4778263585259;6914.723411063602;7098.467614928901;7132.215456299803;7207.89188162178;7227.421806255487;633.9746410143542;668.3216535216759;697.3584302441766;701.6596950479013;702.880510313248;3214.9196270093435;4317.6114149726045;5079.1365467870555;5211.843644690665;5258.399729778052;17512.824871751374;17986.5503362417;18154.727679084823;18185.45443636693;18239.17923693437;14524.419023238952;17950;17993;18107.54731131732;18116.547086322946;8803.779905502286;9276.211522020569;9321.673741419017;9519.762005949768;9531.285153613368;7463.813404664927;7569.394448444001;7824.491408058458;8116;8201.630926608197;14750.704985900436;15085;15817.288222029807;16021.519354419386;16184.919075404548;15676.372945082068;16091.356345746039;16257.67484650324;16420.671586568442;16498.42255521051;9898.901010989797;10863.293885897392;10866.836997445203;10884.510197040994;10900.182486313717;11953.163278570417;12160.756784864076;12191.939040304742;12196.024318054357;12224.755504889674;9634;9634.755710659765;9777;9931.354461959949;10030.29787914839;9457.905420945703;9652.372595781251;9814.803703926023;9844.2124630028;9992.750181245527;8909.777255568662;8945.105489451003;8996.685116020908;9012.909870901469;9016.549172541478;6107.206063211754;6416.647084410402;6550.312217217123;6594.604323740591;6856.41720453767;6483.837904052248;6599.769008084694;6875.931240687529;6907.309269073053;7047.316127166782 17:13:27 INFO - PID 1652 | |4;prototype.html;424.21943623732324;447.75597299471775;453.981840726371;466.384372628131;469.9496625043028;6296.1814964054665;6330.905036424451;6333.809985700431;6334.113224148623;6337.239531256244;5918.556108291885;6119.7246123924415;6150.923588372039;6164.445199932005;6190.659513726743;4685.2035154023815;4703.952960470403;4705.9058818823605;4708.9764551177195;4719.90560188796;4690.742009189493;4698.906021879578;4705.129551033053;4740.36005139307;4747.667663263555;4038.1519880825063;4423.911521769563;4680.25115981443;4703.365045718835;4709.835155769549;3794.981025094871;4485.932711009337;4659.88350291242;4669.2529195328925;4685.4143232095985;3711.46183803347;3758.4174452959833;4088.9386659200136;4098.8360465581345;4110.527289361732;5603.3836278009385;5938.970305148447;6039.969800151015;6042.365551617061;6063.575549711522;4792.400949881265;5250.369955605352;5551.1118221084835;5554.50009499143;5642.689652069134;6593.3736295051895;6659.467242620579;6679.697458995498;6698.5980841149685;6740.1237839081095;3871.380579107329;3895.590962948878;4243.66050715945;4260.360945858115;4700.976495117503;6417.518686098515;6458.257300410466;6472.773452929125;7070.434365250716;7148.392386647141;4343.978280108579;4617.3304870793545;4739;4762.76186190696;4772.904541909143;4859.319695242669;4865.172920603506;4873.366462359942;4880.170371036861;4915.705057696549;5548.278723765885;5566.916496252559;5570.554355651539;5591.30108736408;5602.97198514013;5853.853653658607;6384.265809431882;6715.985204438746;6728;6749.628770417674;1455.8398576156615;1586.7653634060357;1637.7625244339504;1653.7519372094282;1666.983330166707;1107.85044019058;1118.2395970739974;1131.643532287327;1136.170595465315;1139.2310190621297;5077.593792496591;5876.853078672982;6213.440790328802;6281.277653069864;6328.557001009977;6916.273791251947;6916.6541672916155;6935.826604334831;6944.02783610295;6955.504566518223;5529.944700553024;6319.6208227506495;6352.650604216812;6362;6393.296737358887;3369.359821633882;3455.5335029771163;3545.3618348697482;3571.2143328467696;3599.7321687337335;5855;6464.896849512166;7061.399781018671;7102.964485177541;7138.357547820721;6473.805785826433;6621.437177839841;6719.966400167968;7106.005159277705;7196.352328290479;5430.728463576885;6853.931460685429;7090.92909070913;7127.144742630814;7145.285471452917;5406.83779486616;5490.474381784424;6940.722371105099;6954.582725036514;7003.439724822104;5456.399796022513;6165.859316026549;6423.807285781434;6631.970111681633;6801;7164.516112917818;7166.820829479097;7173.784786456415;7177.102862142232;7189.856202875809;4701.623870090316;4756.857294281177;4764.809407623657;4767.833125840579;4777;5960.701964901651;6793.864122717617;6943.930560694328;6983.406410455168;7019.3682568569075;6016.127661488972;6667;6693.1298931138645;6757.493188011017;6784.660766961533;6139.754409823557;6617;6687.465002799861;6725.831854203684;6737.562058466184;5481.095619222783;5552.583556233218;6512.967435162794;6549.1838509441395;6593.967030164818;5526.778928842801;6149.323574406902;6621.668916554249;6676.899846502403;6678.365555272241;6128.693565321805;6237.656928868956;6280.717367718372;6388.169537960035;6524.771632992823 17:13:27 INFO - PID 1652 | |5;yui.html;783.3693876429473;820.6963423533293;824.9010118785746;852.475727427632;853.6981103816679;16754.83245167548;17051;17229.74155387667;17256.56858578536;17288.82711172888;12648.557300494485;13241.006924480678;13265.535706250283;15720;16568.420105296318;948.8908775490803;955.3837774635356;963.3738070254356;969.3360048366856;981.9410835349867;961.6393852305386;973.1922504321432;974.352055882837;977.4721650308825;979.8383266760976;1016.1768967136607;1024.748936510556;1027.291169093327;1028.5731421460084;1030.0472063341379;953.132649289147;981.2640519610293;1041.874975002997;1042.3485321673916;1046.1787496814964;774.6088225446169;778.6885245901628;780.8399278148023;781.6560713286136;783.6473586885924;14481.78277325841;16687.33250669984;17127.57181070483;17137.143142842808;17245;17302.22140003703;17544.94730316185;17605.207765650448;17647;17723.37968171121;13311.334433278298;14426.711465770732;14860.479883204036;14925.328360223708;15044.849551504454;3648.781073135594;3684.2631473705364;3684.7236457265813;3689.723270754704;3699.093722038091;112133.43933280362;130994;131319;138899;146536;13587.45650173982;13675.794863077062;13754.724905501835;13818.861811381757;13935.86064139366;25500.744992549837;25723.971041158144;25779.871100644377;25809.354766130997;26401.339966500993;7490.5505669658905;7573.46985710995;7659.655315510815;7667.501612395176;7827.491213071132;3921.274564205631;3964.3260645689725;3966.801659916993;4000.7999600019884;4007.7595344279434;4570.314452832035;4672.602828759525;4674.579287864109;4686.250199968057;4693.929591056137;976.8827740671023;1004.0712341084613;1014.452195814252;1014.8883622801486;1015.421209910344;568.2101878389043;620.7858288890326;636.8216899268213;649.5420728386476;661.5104822431463;11569.247998880046;11596.478158482889;11818.349990750592;11872.34702091376;12264.509419623115;608.2093278737624;635.2757856044113;650.8958566629411;652.1749986267375;687.5702685821361;5294;5929.792457264061;6171.783987560414;6254.093156492283;6714.731410743516;124783.37608312082;128412;133822.33088834689;135477;138683;101343.49328253312;103021.48489257655;105825;110241;113373.43313283546;96331.51834240784;98980;99215;100748.49625751824;102862.48568757258;9769.609215631295;10034.849477257701;12006.09954253417;12201.450934708137;12785.36073196355;34344.45449954708;34663.653363466045;37339.62660373362;45686.086278273586;46306;72852;78775;80418.59790701009;81650.59174704089;86144.13855861362;16917;17097.145142743062;17146.142692865058;17154.828451715322;17187.31250749956;18463.53841153982;18505.444836654922;18505.81494185095;18864.717029244846;18968.715469267696;13788.724225515634;16482.25829837684;16680.49958501247;17291.91354043222;17423.39018134359;14848;15228.923855380652;16680.24938877778;17280.913595432445;17567.209475573887;14352.354144063333;16235.350585976428;16747;16990.49028529146;17010.319587216374;10648.264405339054;11832.645020649394;11869.703257418634;12042.879571204527;12114.75770484603;12775.680607984874;12874.485020599446;12951.805722913976;12956.093073485143;13372.799408009083 17:13:27 INFO - PID 1652 | __end_tp_report 17:13:27 INFO - PID 1652 | __start_cc_report 17:13:27 INFO - PID 1652 | _x_x_mozilla_cycle_collect,465 17:13:27 INFO - PID 1652 | __end_cc_report 17:13:27 INFO - PID 1652 | __startTimestamp1497312807889__endTimestamp 17:13:27 INFO - PID 1652 | 17:13:27 INFO - PID 1652 | ------- Summary: start ------- 17:13:27 INFO - PID 1652 | Number of tests: 6 17:13:27 INFO - PID 1652 | 17:13:27 INFO - PID 1652 | [#0] dojo.html Cycles:165 Average:6164.75 Median:6389.52 stddev:764.47 (12.0%) stddev-sans-first:736.51 17:13:27 INFO - PID 1652 | Values: 3448.3 3550.1 3591.7 3598.9 3627.5 6141.6 6190.2 6192.7 6205.3 6242.7 6209.7 6232.8 6299.4 6300.7 6303.9 4416.6 4971.8 5521.7 5527.8 5653.7 5568.2 5623.1 5626.7 5651.9 5658.2 5396.5 5439.3 5527.7 5561.1 5589.3 4403.8 5436.4 5437.0 5455.9 5544.2 5423.6 5496.1 5565.9 5568.0 5580.7 6151.2 6197.2 6214.8 6233.9 6263.0 6663.6 6695.8 6707.9 6708.9 6725.3 5623.8 6515.6 6661.3 6795.3 6799.4 6245.3 6296.1 6298.8 6299.5 6307.7 6927.4 6983.9 7001.9 7008.2 7019.8 6410.1 6492.1 6519.5 6593.8 6626.9 6617.1 6622.6 6706.8 6740.9 6791.7 6673.8 6695.6 6702.8 6708.2 6714.0 6696.1 6706.4 6733.5 6734.1 6748.8 5237.8 5259.2 5297.9 5302.7 5307.4 4026.3 4725.5 4748.2 4751.8 4776.6 5673.5 5892.0 6037.4 6255.3 6320.0 6792.6 6809.3 6832.3 6834.0 6857.8 6321.7 6360.4 6360.8 6372.2 6407.7 5775.9 5962.5 5978.8 5987.5 5997.9 6817.5 6882.4 6920.5 6942.4 6979.9 5380.4 6805.4 6836.6 6846.2 6852.6 6987.4 6991.4 6998.2 7002.0 7054.0 6643.9 6651.3 6687.4 6691.9 6704.1 6585.7 6645.6 6657.9 6675.5 6815.7 6412.2 6467.1 6542.1 6550.1 6582.6 6049.6 6259.3 6657.2 6665.9 6688.9 5403.8 6389.5 6491.7 6503.6 6532.7 6421.0 6459.6 6476.5 6477.5 6609.8 6343.8 6347.5 6365.2 6396.5 6410.3 17:13:27 INFO - PID 1652 | 17:13:27 INFO - PID 1652 | [#1] ext.html Cycles:180 Average:42955.88 Median:336235.00 stddev:76663.61 (22.8%) stddev-sans-first:76813.29 17:13:27 INFO - PID 1652 | Values: 789.2 817.4 818.9 839.4 851.4 36067.8 36915.0 37072.8 37114.6 37615.1 36381.8 36810.1 36915.9 36959.6 37038.0 7949.2 8074.0 8101.5 8103.3 8103.6 7929.8 8017.4 8032.0 8032.1 8039.1 8037.2 8055.8 8075.8 8077.0 8079.8 7974.7 8025.9 8033.9 8054.0 8064.4 7140.0 7145.7 7164.2 7196.6 7202.4 34655.7 34793.5 34797.8 34822.8 34959.0 3553.9 3571.8 3588.1 3590.6 3592.4 28495.1 28813.3 28864.0 28872.3 28878.4 2439.9 3017.6 3087.1 3090.2 3102.6 251772.0 256999.0 257144.0 315145.0 317761.0 36972.0 44838.8 45029.9 45272.0 45445.0 39384.0 47363.1 47916.0 48267.5 48368.5 13708.9 13815.8 13918.3 13933.4 13937.7 11122.4 11221.4 11350.4 11410.0 11417.5 12137.7 12259.7 12950.0 12982.4 13000.7 5835.6 5889.4 5912.6 5917.4 5941.8 967.6 1009.5 1012.6 1017.9 1066.2 19367.3 21292.9 22361.4 22445.6 22572.5 957.3 1001.4 1129.5 1161.7 1170.1 11306.4 11421.3 11538.2 11709.9 12296.0 328513.0 334743.0 336053.0 336417.0 336833.0 46706.3 46820.8 46960.5 47092.5 47205.3 1477.8 1512.6 1514.5 1519.0 1537.6 21481.3 21925.2 22512.7 22668.7 23437.2 36380.0 36497.3 36500.0 36574.8 36612.5 238898.0 242302.0 242510.0 271017.0 271959.0 31901.0 32007.8 32077.0 32098.0 32223.8 40541.4 41256.0 41330.6 41561.6 41592.0 25829.6 28012.4 29030.3 30675.5 30828.2 24507.8 28794.3 29491.7 30013.7 30033.8 33981.0 35177.6 36080.6 36122.5 36299.6 26830.6 29143.4 29362.6 29794.6 31151.4 18221.5 22427.8 24244.6 24636.8 25147.0 17:13:27 INFO - PID 1652 | 17:13:27 INFO - PID 1652 | [#2] jquery.html Cycles:180 Average:39655.27 Median:2229.69 stddev:100812.67 (4521.4%) stddev-sans-first:101052.59 17:13:27 INFO - PID 1652 | Values: 490.6 494.4 496.3 511.8 512.7 21415.9 21750.5 22493.9 22516.0 22633.0 23789.4 23927.0 23949.9 24184.9 24264.9 6440.9 6553.1 6607.9 6608.6 6620.8 6568.2 6571.4 6588.8 6598.0 6611.0 6051.0 6201.2 6422.1 6459.3 6460.5 5485.9 6392.8 6439.8 6455.0 6463.6 5018.5 5019.9 5901.2 5906.1 5925.3 28345.4 29503.9 30282.7 30483.8 30534.7 2191.8 2203.0 2211.9 2230.7 2232.0 18487.4 18841.7 18955.1 18962.0 18986.5 2165.2 2204.2 2225.7 2228.6 2234.3 161047.0 166875.0 168325.0 168368.0 199102.0 13118.3 13622.6 14518.2 14788.0 14837.8 19729.6 19899.6 19914.4 20024.0 20025.0 8260.8 9625.0 9685.1 9715.0 9778.9 7584.9 8701.8 8750.6 8997.2 9091.8 8898.8 9004.6 9028.4 9047.4 9056.0 2167.6 2554.1 2562.6 2566.7 2675.2 978.0 981.8 983.0 1037.2 1037.8 20963.3 21104.0 21240.9 21326.4 22183.7 928.0 1019.9 1025.5 1045.9 1046.3 8933.8 11132.5 11189.3 11235.8 11394.8 570967.0 582567.0 589480.0 598662.0 604393.0 152487.0 153619.0 157804.0 158997.4 159142.0 1017.1 1076.3 1167.2 1209.5 1257.0 18061.5 22223.0 24308.8 24333.6 24979.6 65097.7 66344.0 66700.0 67956.9 68443.0 89676.0 91026.5 91174.0 91221.0 91510.0 11549.7 11593.7 11655.2 11728.8 11754.6 17887.0 18360.4 18617.3 18725.3 18916.6 12140.8 15163.2 15200.1 15291.8 15351.2 13162.0 13265.4 13314.8 13432.8 13807.4 11213.6 15284.6 15419.9 15591.6 15599.0 9493.9 10973.5 11341.9 11638.4 11641.6 14594.4 14652.0 14810.1 14829.2 14998.7 17:13:27 INFO - PID 1652 | 17:13:27 INFO - PID 1652 | [#3] mootools.html Cycles:180 Average:8047.38 Median:2756.03 stddev:5347.58 (194.0%) stddev-sans-first:5337.45 17:13:27 INFO - PID 1652 | Values: 1148.6 1330.0 1398.9 1423.8 1424.0 11216.2 12851.3 13248.8 13460.9 13466.9 12910.8 13255.9 13290.1 13301.9 13322.0 2253.0 2751.3 2754.8 2757.6 2759.8 2673.8 2731.1 2731.5 2731.7 2734.5 1871.5 2341.0 2669.2 2704.6 2728.8 2277.5 2311.7 2591.2 2696.4 2703.5 2752.2 2755.7 2756.0 2756.1 2758.8 13134.1 13217.2 13231.1 13234.9 13264.2 7219.0 7447.1 7496.9 7515.3 7518.2 4479.4 4657.9 4658.0 4752.7 4758.9 1411.6 1415.0 1420.9 1431.4 1445.4 19556.5 19650.9 19655.6 19784.9 19843.1 5276.7 6376.9 6492.3 6587.6 6598.1 13903.2 14086.2 14086.7 14110.5 14123.2 7104.5 7243.7 7244.9 7260.2 7264.5 6437.7 6665.9 6675.0 6686.7 6747.2 4841.2 4970.8 5010.3 5057.5 5069.1 1421.1 1440.2 1444.8 1460.9 1466.9 789.2 804.2 819.8 823.3 835.5 6914.7 7098.5 7132.2 7207.9 7227.4 634.0 668.3 697.4 701.7 702.9 3214.9 4317.6 5079.1 5211.8 5258.4 17512.8 17986.6 18154.7 18185.5 18239.2 14524.4 17950.0 17993.0 18107.5 18116.5 8803.8 9276.2 9321.7 9519.8 9531.3 7463.8 7569.4 7824.5 8116.0 8201.6 14750.7 15085.0 15817.3 16021.5 16184.9 15676.4 16091.4 16257.7 16420.7 16498.4 9898.9 10863.3 10866.8 10884.5 10900.2 11953.2 12160.8 12191.9 12196.0 12224.8 9634.0 9634.8 9777.0 9931.4 10030.3 9457.9 9652.4 9814.8 9844.2 9992.8 8909.8 8945.1 8996.7 9012.9 9016.5 6107.2 6416.6 6550.3 6594.6 6856.4 6483.8 6599.8 6875.9 6907.3 7047.3 17:13:27 INFO - PID 1652 | 17:13:27 INFO - PID 1652 | [#4] prototype.html Cycles:180 Average:5404.49 Median:5988.41 stddev:1656.90 (27.7%) stddev-sans-first:1618.83 17:13:27 INFO - PID 1652 | Values: 424.2 447.8 454.0 466.4 469.9 6296.2 6330.9 6333.8 6334.1 6337.2 5918.6 6119.7 6150.9 6164.4 6190.7 4685.2 4704.0 4705.9 4709.0 4719.9 4690.7 4698.9 4705.1 4740.4 4747.7 4038.2 4423.9 4680.3 4703.4 4709.8 3795.0 4485.9 4659.9 4669.3 4685.4 3711.5 3758.4 4088.9 4098.8 4110.5 5603.4 5939.0 6040.0 6042.4 6063.6 4792.4 5250.4 5551.1 5554.5 5642.7 6593.4 6659.5 6679.7 6698.6 6740.1 3871.4 3895.6 4243.7 4260.4 4701.0 6417.5 6458.3 6472.8 7070.4 7148.4 4344.0 4617.3 4739.0 4762.8 4772.9 4859.3 4865.2 4873.4 4880.2 4915.7 5548.3 5566.9 5570.6 5591.3 5603.0 5853.9 6384.3 6716.0 6728.0 6749.6 1455.8 1586.8 1637.8 1653.8 1667.0 1107.9 1118.2 1131.6 1136.2 1139.2 5077.6 5876.9 6213.4 6281.3 6328.6 6916.3 6916.7 6935.8 6944.0 6955.5 5529.9 6319.6 6352.7 6362.0 6393.3 3369.4 3455.5 3545.4 3571.2 3599.7 5855.0 6464.9 7061.4 7103.0 7138.4 6473.8 6621.4 6720.0 7106.0 7196.4 5430.7 6853.9 7090.9 7127.1 7145.3 5406.8 5490.5 6940.7 6954.6 7003.4 5456.4 6165.9 6423.8 6632.0 6801.0 7164.5 7166.8 7173.8 7177.1 7189.9 4701.6 4756.9 4764.8 4767.8 4777.0 5960.7 6793.9 6943.9 6983.4 7019.4 6016.1 6667.0 6693.1 6757.5 6784.7 6139.8 6617.0 6687.5 6725.8 6737.6 5481.1 5552.6 6513.0 6549.2 6594.0 5526.8 6149.3 6621.7 6676.9 6678.4 6128.7 6237.7 6280.7 6388.2 6524.8 17:13:27 INFO - PID 1652 | 17:13:27 INFO - PID 1652 | [#5] yui.html Cycles:180 Average:24341.65 Median:17297.07 stddev:36393.14 (210.4%) stddev-sans-first:36452.24 17:13:27 INFO - PID 1652 | Values: 783.4 820.7 824.9 852.5 853.7 16754.8 17051.0 17229.7 17256.6 17288.8 12648.6 13241.0 13265.5 15720.0 16568.4 948.9 955.4 963.4 969.3 981.9 961.6 973.2 974.4 977.5 979.8 1016.2 1024.7 1027.3 1028.6 1030.0 953.1 981.3 1041.9 1042.3 1046.2 774.6 778.7 780.8 781.7 783.6 14481.8 16687.3 17127.6 17137.1 17245.0 17302.2 17544.9 17605.2 17647.0 17723.4 13311.3 14426.7 14860.5 14925.3 15044.8 3648.8 3684.3 3684.7 3689.7 3699.1 112133.4 130994.0 131319.0 138899.0 146536.0 13587.5 13675.8 13754.7 13818.9 13935.9 25500.7 25724.0 25779.9 25809.4 26401.3 7490.6 7573.5 7659.7 7667.5 7827.5 3921.3 3964.3 3966.8 4000.8 4007.8 4570.3 4672.6 4674.6 4686.3 4693.9 976.9 1004.1 1014.5 1014.9 1015.4 568.2 620.8 636.8 649.5 661.5 11569.2 11596.5 11818.3 11872.3 12264.5 608.2 635.3 650.9 652.2 687.6 5294.0 5929.8 6171.8 6254.1 6714.7 124783.4 128412.0 133822.3 135477.0 138683.0 101343.5 103021.5 105825.0 110241.0 113373.4 96331.5 98980.0 99215.0 100748.5 102862.5 9769.6 10034.8 12006.1 12201.5 12785.4 34344.5 34663.7 37339.6 45686.1 46306.0 72852.0 78775.0 80418.6 81650.6 86144.1 16917.0 17097.1 17146.1 17154.8 17187.3 18463.5 18505.4 18505.8 18864.7 18968.7 13788.7 16482.3 16680.5 17291.9 17423.4 14848.0 15228.9 16680.2 17280.9 17567.2 14352.4 16235.4 16747.0 16990.5 17010.3 10648.3 11832.6 11869.7 12042.9 12114.8 12775.7 12874.5 12951.8 12956.1 13372.8 17:13:27 INFO - PID 1652 | -------- Summary: end -------- 17:13:27 INFO - PID 1652 | 17:13:28 INFO - TEST-INFO | 1652: exit 0 17:13:28 INFO - TEST-OK | dromaeo_css | took 1143724ms 17:13:28 INFO - TEST-START | kraken 17:13:28 INFO - operating with platform_type : mac_ 17:13:28 INFO - Initialising browser for kraken test... 17:13:28 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49221/getInfo.html -foreground -profile /var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/tmpnRBwPX/profile 17:13:28 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49221/getInfo.html -foreground) 17:14:02 INFO - TEST-INFO | 1662: killed by SIGTERM 17:14:02 INFO - Browser initialized. 17:14:02 INFO - Running cycle 1/1 for kraken test... 17:14:02 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/tmpnRBwPX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:14:04 INFO - PID 1666 | 2017-06-12 17:14:04.101 plugin-container[1667:10837] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.tsm.portname' 17:14:04 INFO - PID 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:14:04 INFO - PID 1666 | 2017-06-12 17:14:04.101 plugin-container[1667:10837] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464f, name = 'com.apple.CFPasteboardClient' 17:14:04 INFO - PID 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:14:04 INFO - PID 1666 | 2017-06-12 17:14:04.101 plugin-container[1667:10837] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:14:04 INFO - PID 1666 | 2017-06-12 17:14:04.980 plugin-container[1668:10886] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4843, name = 'com.apple.tsm.portname' 17:14:04 INFO - PID 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:14:04 INFO - PID 1666 | 2017-06-12 17:14:04.980 plugin-container[1668:10886] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 17:14:04 INFO - PID 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:14:04 INFO - PID 1666 | 2017-06-12 17:14:04.980 plugin-container[1668:10886] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:14:14 INFO - PID 1666 | RSS: Main: 252776448 17:14:14 INFO - PID 1666 | 17:14:17 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49221/tests/kraken/driver.html?testName=audio-beat-detection) 17:14:17 INFO - PID 1666 | RSS: Main: 265199616 17:14:17 INFO - PID 1666 | 17:14:20 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49221/tests/kraken/driver.html?testName=audio-dft) 17:14:21 INFO - PID 1666 | RSS: Main: 274665472 17:14:21 INFO - PID 1666 | 17:14:24 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49221/tests/kraken/driver.html?testName=audio-fft) 17:14:25 INFO - PID 1666 | RSS: Main: 277442560 17:14:25 INFO - PID 1666 | 17:14:27 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49221/tests/kraken/driver.html?testName=audio-oscillator) 17:14:27 INFO - PID 1666 | RSS: Main: 279367680 17:14:27 INFO - PID 1666 | 17:14:29 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49221/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:14:30 INFO - PID 1666 | RSS: Main: 280137728 17:14:30 INFO - PID 1666 | 17:14:33 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49221/tests/kraken/driver.html?testName=imaging-darkroom) 17:14:33 INFO - PID 1666 | RSS: Main: 284459008 17:14:33 INFO - PID 1666 | 17:14:36 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49221/tests/kraken/driver.html?testName=imaging-desaturate) 17:14:37 INFO - PID 1666 | RSS: Main: 286617600 17:14:37 INFO - PID 1666 | 17:14:40 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49221/tests/kraken/driver.html?testName=json-parse-financial) 17:14:41 INFO - PID 1666 | RSS: Main: 288997376 17:14:41 INFO - PID 1666 | 17:14:42 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49221/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:14:43 INFO - PID 1666 | RSS: Main: 288587776 17:14:43 INFO - PID 1666 | 17:14:45 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:14:45 INFO - PID 1666 | RSS: Main: 288305152 17:14:45 INFO - PID 1666 | 17:14:47 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:14:48 INFO - PID 1666 | RSS: Main: 288149504 17:14:48 INFO - PID 1666 | 17:14:51 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:14:51 INFO - PID 1666 | RSS: Main: 288165888 17:14:51 INFO - PID 1666 | 17:14:54 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:14:55 INFO - PID 1666 | RSS: Main: 288333824 17:14:55 INFO - PID 1666 | 17:14:57 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49221/tests/kraken/driver.html?testName=ai-astar) 17:14:57 INFO - PID 1666 | RSS: Main: 290050048 17:14:57 INFO - PID 1666 | 17:14:57 INFO - PID 1666 | __start_tp_report 17:14:57 INFO - PID 1666 | _x_x_mozilla_page_load 17:14:57 INFO - PID 1666 | _x_x_mozilla_page_load_details 17:14:57 INFO - PID 1666 | |i|pagename|runs| 17:14:57 INFO - PID 1666 | |0;ai-astar;105;116;117;125;122;120;114;117;116;120 17:14:57 INFO - PID 1666 | |1;audio-beat-detection;173;146;201;135;115;128;111;141;140;108 17:14:57 INFO - PID 1666 | |2;audio-dft;198;209;198;204;212;217;223;263;219;194 17:14:57 INFO - PID 1666 | |3;audio-fft;68;80;75;131;57;59;62;69;115;78 17:14:57 INFO - PID 1666 | |4;audio-oscillator;68;70;109;72;69;72;126;56;64;113 17:14:57 INFO - PID 1666 | |5;imaging-gaussian-blur;89;89;89;89;90;89;90;91;90;89 17:14:57 INFO - PID 1666 | |6;imaging-darkroom;97;97;97;121;98;96;97;94;96;98 17:14:57 INFO - PID 1666 | |7;imaging-desaturate;90;92;118;115;145;92;91;94;93;91 17:14:57 INFO - PID 1666 | |8;json-parse-financial;50;49;51;54;53;52;54;53;53;63 17:14:57 INFO - PID 1666 | |9;json-stringify-tinderbox;64;74;83;57;64;63;56;59;57;59 17:14:57 INFO - PID 1666 | |10;stanford-crypto-aes;80;91;90;81;82;81;79;83;80;81 17:14:57 INFO - PID 1666 | |11;stanford-crypto-ccm;147;137;136;134;136;141;138;137;136;164 17:14:57 INFO - PID 1666 | |12;stanford-crypto-pbkdf2;179;184;185;176;179;179;178;190;187;183 17:14:57 INFO - PID 1666 | |13;stanford-crypto-sha256-iterative;65;67;65;60;90;95;63;64;65;65 17:14:57 INFO - PID 1666 | __end_tp_report 17:14:57 INFO - PID 1666 | __start_cc_report 17:14:57 INFO - PID 1666 | _x_x_mozilla_cycle_collect,1174 17:14:57 INFO - PID 1666 | __end_cc_report 17:14:57 INFO - PID 1666 | __startTimestamp1497312897509__endTimestamp 17:14:57 INFO - PID 1666 | 17:14:57 INFO - PID 1666 | ------- Summary: start ------- 17:14:57 INFO - PID 1666 | Number of tests: 14 17:14:57 INFO - PID 1666 | 17:14:57 INFO - PID 1666 | [#0] ai-astar Cycles:10 Average:117.20 Median:118.50 stddev:5.39 (4.5%) stddev-sans-first:3.47 17:14:57 INFO - PID 1666 | Values: 105.0 116.0 117.0 125.0 122.0 120.0 114.0 117.0 116.0 120.0 17:14:57 INFO - PID 1666 | 17:14:57 INFO - PID 1666 | [#1] audio-beat-detection Cycles:10 Average:139.80 Median:140.50 stddev:28.88 (20.6%) stddev-sans-first:28.02 17:14:57 INFO - PID 1666 | Values: 173.0 146.0 201.0 135.0 115.0 128.0 111.0 141.0 140.0 108.0 17:14:57 INFO - PID 1666 | 17:14:57 INFO - PID 1666 | [#2] audio-dft Cycles:10 Average:213.70 Median:214.50 stddev:19.88 (9.3%) stddev-sans-first:20.26 17:14:57 INFO - PID 1666 | Values: 198.0 209.0 198.0 204.0 212.0 217.0 223.0 263.0 219.0 194.0 17:14:57 INFO - PID 1666 | 17:14:57 INFO - PID 1666 | [#3] audio-fft Cycles:10 Average:79.40 Median:68.50 stddev:24.52 (35.8%) stddev-sans-first:25.66 17:14:57 INFO - PID 1666 | Values: 68.0 80.0 75.0 131.0 57.0 59.0 62.0 69.0 115.0 78.0 17:14:57 INFO - PID 1666 | 17:14:57 INFO - PID 1666 | [#4] audio-oscillator Cycles:10 Average:81.90 Median:68.50 stddev:24.35 (35.5%) stddev-sans-first:25.30 17:14:57 INFO - PID 1666 | Values: 68.0 70.0 109.0 72.0 69.0 72.0 126.0 56.0 64.0 113.0 17:14:57 INFO - PID 1666 | 17:14:57 INFO - PID 1666 | [#5] imaging-gaussian-blur Cycles:10 Average:89.50 Median:89.50 stddev:0.71 (0.8%) stddev-sans-first:0.73 17:14:57 INFO - PID 1666 | Values: 89.0 89.0 89.0 89.0 90.0 89.0 90.0 91.0 90.0 89.0 17:14:57 INFO - PID 1666 | 17:14:57 INFO - PID 1666 | [#6] imaging-darkroom Cycles:10 Average:99.10 Median:97.00 stddev:7.78 (8.0%) stddev-sans-first:8.22 17:14:57 INFO - PID 1666 | Values: 97.0 97.0 97.0 121.0 98.0 96.0 97.0 94.0 96.0 98.0 17:14:57 INFO - PID 1666 | 17:14:57 INFO - PID 1666 | [#7] imaging-desaturate Cycles:10 Average:102.10 Median:91.50 stddev:18.27 (20.0%) stddev-sans-first:18.85 17:14:57 INFO - PID 1666 | Values: 90.0 92.0 118.0 115.0 145.0 92.0 91.0 94.0 93.0 91.0 17:14:57 INFO - PID 1666 | 17:14:57 INFO - PID 1666 | [#8] json-parse-financial Cycles:10 Average:53.20 Median:53.00 stddev:3.82 (7.2%) stddev-sans-first:3.88 17:14:57 INFO - PID 1666 | Values: 50.0 49.0 51.0 54.0 53.0 52.0 54.0 53.0 53.0 63.0 17:14:57 INFO - PID 1666 | 17:14:57 INFO - PID 1666 | [#9] json-stringify-tinderbox Cycles:10 Average:63.60 Median:63.50 stddev:8.64 (13.6%) stddev-sans-first:9.17 17:14:57 INFO - PID 1666 | Values: 64.0 74.0 83.0 57.0 64.0 63.0 56.0 59.0 57.0 59.0 17:14:57 INFO - PID 1666 | 17:14:57 INFO - PID 1666 | [#10] stanford-crypto-aes Cycles:10 Average:82.80 Median:81.50 stddev:4.21 (5.2%) stddev-sans-first:4.34 17:14:57 INFO - PID 1666 | Values: 80.0 91.0 90.0 81.0 82.0 81.0 79.0 83.0 80.0 81.0 17:14:57 INFO - PID 1666 | 17:14:57 INFO - PID 1666 | [#11] stanford-crypto-ccm Cycles:10 Average:140.60 Median:137.50 stddev:9.00 (6.5%) stddev-sans-first:9.24 17:14:57 INFO - PID 1666 | Values: 147.0 137.0 136.0 134.0 136.0 141.0 138.0 137.0 136.0 164.0 17:14:57 INFO - PID 1666 | 17:14:57 INFO - PID 1666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.00 Median:183.50 stddev:4.50 (2.5%) stddev-sans-first:4.64 17:14:57 INFO - PID 1666 | Values: 179.0 184.0 185.0 176.0 179.0 179.0 178.0 190.0 187.0 183.0 17:14:57 INFO - PID 1666 | 17:14:57 INFO - PID 1666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.90 Median:65.00 stddev:12.11 (18.6%) stddev-sans-first:12.71 17:14:57 INFO - PID 1666 | Values: 65.0 67.0 65.0 60.0 90.0 95.0 63.0 64.0 65.0 65.0 17:14:57 INFO - PID 1666 | -------- Summary: end -------- 17:14:57 INFO - PID 1666 | 17:14:58 INFO - TEST-INFO | 1666: exit 0 17:14:58 INFO - TEST-OK | kraken | took 89867ms 17:14:58 INFO - SUITE-END | took 1233s 17:14:58 INFO - Completed test suite (00:20:33) 17:14:58 INFO - javascript benchmark 17:14:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8156.403405317356, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3448.2758620689656, 3550.1479644885226, 3591.6767490925795, 3598.8740394086194, 3627.52842130523, 6141.631502109871, 6190.1643278157435, 6192.690365481731, 6205.255369355684, 6242.687865606718, 6209.658468784215, 6232.84417889553, 6299.401556852104, 6300.716467758951, 6303.873922521547, 4416.646668266547, 4971.776270067838, 5521.723913804314, 5527.834164975057, 5653.6607803531715, 5568.220449137124, 5623.128415095646, 5626.695199940284, 5651.8587035324035, 5658.236138121362, 5396.541293989976, 5439.265699130646, 5527.723613819293, 5561.110222364402, 5589.329280486348, 4403.8458653946955, 5436.429174936654, 5437.02133615949, 5455.918161227585, 5544.22380866679, 5423.620346575742, 5496.148097044965, 5565.860853478655, 5567.9977604031255, 5580.720963951786, 6151.200343955257, 6197.194364732601, 6214.844628884269, 6233.937660623381, 6262.96868515659, 6663.566868153554, 6695.799126026178, 6707.932920670828, 6708.865822683519, 6725.2602213756445, 5623.803166889139, 6515.609063456208, 6661.26726060133, 6795.286494918061, 6799.388055075067, 6245.344238854855, 6296.087067375205, 6298.842528936768, 6299.543178160521, 6307.747690092391, 6927.445804335742, 6983.930160698328, 7001.859962800715, 7008.159020917421, 7019.789406317818, 6410.134631824737, 6492.058651495507, 6519.5436319457185, 6593.769218077345, 6626.900596491057, 6617.106690596708, 6622.602643841385, 6706.83232919181, 6740.865182696319, 6791.72833086671, 6673.833154171088, 6695.598264104169, 6702.83242918931, 6708.161479815023, 6713.966430167818, 6696.06255124283, 6706.396424321834, 6733.46132309414, 6734.0572319875255, 6748.797536073926, 5237.764300606482, 5259.15853463444, 5297.894042119136, 5302.734863256822, 5307.363116425976, 4026.315526360526, 4725.4565724941385, 4748.19280722278, 4751.809927602926, 4776.617870570346, 5673.461021202979, 5892.045738253324, 6037.396260373927, 6255.280642726145, 6320.0, 6792.59244445325, 6809.285025072395, 6832.282610325944, 6834.0, 6857.794266172023, 6321.65230912295, 6360.427561519486, 6360.809175724643, 6372.171617689671, 6407.679616019181, 5775.88448231033, 5962.493188079062, 5978.790742323998, 5987.491063259585, 5997.910031349533, 6817.488688348394, 6882.380585747307, 6920.4809639277255, 6942.409895158968, 6979.895301570379, 5380.354357477127, 6805.3875151236625, 6836.589804611739, 6846.212685541027, 6852.623105729033, 6987.441004719712, 6991.405730512964, 6998.230194678479, 7001.964990175016, 7053.977173309944, 6643.867122657423, 6651.301613330724, 6687.431568316553, 6691.899621505584, 6704.128463299739, 6585.703643335965, 6645.568038077509, 6657.900131497934, 6675.499337549801, 6815.69329380189, 6412.23053233615, 6467.062275970052, 6542.149520562297, 6550.148480697479, 6582.572132811352, 6049.606775559574, 6259.3427690091885, 6657.167854018247, 6665.933340666531, 6688.866222675617, 5403.7838486460105, 6389.520785941166, 6491.707873145625, 6503.6097834130105, 6532.738690452328, 6421.0, 6459.61242325462, 6476.5466417350335, 6477.481801455967, 6609.801705948829, 6343.841403964938, 6347.5239357047485, 6365.204349456318, 6396.456301214449, 6410.326915673787], "value": 6112.940402914468, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [789.2186735132217, 817.4237162800226, 818.8689809630458, 839.3788596438635, 851.3657325292654, 36067.81966090169, 36915.0, 37072.814635926785, 37114.62885371149, 37615.05962350943, 36381.81809090951, 36810.07974800631, 36915.89252322428, 36959.630403695955, 37038.0, 7949.205079492048, 8073.95963020187, 8101.513909165439, 8103.3112185464015, 8103.594820258963, 7929.762107136767, 8017.398695097891, 8032.0, 8032.076311224197, 8039.115697273295, 8037.196280371945, 8055.751358539435, 8075.757727268162, 8076.990376202974, 8079.798005049862, 7974.7208847690345, 8025.879611805827, 8033.919660803404, 8054.0, 8064.395170362217, 7139.964300178466, 7145.749898753519, 7164.21193668696, 7196.64016799158, 7202.351788339075, 34655.6534434655, 34793.478097828556, 34797.826010870034, 34822.82588587041, 34959.0, 3553.9111522211892, 3571.821408929543, 3588.138846676805, 3590.5870824855215, 3592.4431713084514, 28495.145145645663, 28813.279668008257, 28864.0, 28872.27819304513, 28878.42243155125, 2439.902403903842, 3017.5926249956196, 3087.1201707513474, 3090.2428904918215, 3102.550130231104, 251772.0, 256998.99999999814, 257144.0, 315145.0, 317761.0, 36972.0, 44838.77580612141, 45029.874253143935, 45272.0, 45445.0, 39384.01539961467, 47363.05273894503, 47916.0, 48267.5173248263, 48368.5163148364, 13708.93145534266, 13815.792763108562, 13918.30408479572, 13933.442662293395, 13937.65155871091, 11122.44387780624, 11221.382823944597, 11350.3757293348, 11410.0, 11417.48621312043, 12137.69655758613, 12259.69350766238, 12950.0, 12982.35088245584, 13000.67498312531, 5835.56233282497, 5889.440503152194, 5912.640092778599, 5917.378675239124, 5941.792037278675, 967.6226271754022, 1009.4952523738131, 1012.6455740490917, 1017.9491025448698, 1066.2376400873413, 19367.3221437249, 21292.935353232275, 22361.44151066067, 22445.61169500693, 22572.548549029256, 957.2772556719632, 1001.409168590535, 1129.491728722062, 1161.6631176958588, 1170.1399471388522, 11306.434678266054, 11421.31472111676, 11538.192326537062, 11709.882901170879, 12296.016318694483, 328513.0, 334743.0, 336053.0, 336417.0, 336833.0, 46706.299405508944, 46820.7658961703, 46960.530394696296, 47092.5290747095, 47205.29192062122, 1477.793108964746, 1512.6142833577367, 1514.4547962733407, 1519.027822193797, 1537.5541093082882, 21481.25854183238, 21925.232616858335, 22512.66231006569, 22668.659970100132, 23437.179698710464, 36380.0, 36497.27005459823, 36500.0, 36574.817125915266, 36612.45081323729, 238898.0, 242302.0, 242510.0, 271017.0, 271959.0, 31901.042968710975, 32007.839960800047, 32077.0, 32098.0, 32223.838880805444, 40541.39187912125, 41256.0, 41330.586694132675, 41561.58438415577, 41592.0, 25829.609844985796, 28012.43975120527, 29030.27424314409, 30675.539866901567, 30828.22929426782, 24507.754922450546, 28794.28014299659, 29491.705082949753, 30013.69986300109, 30033.849830750707, 33981.0, 35177.64822351846, 36080.63919360773, 36122.458163128096, 36299.63700362963, 26830.59754103651, 29143.417131656824, 29362.559561606166, 29794.553081703358, 31151.37697245997, 18221.54446138857, 22427.775722242568, 24244.636330454705, 24636.753632463446, 25147.0], "value": 16597.350357429284, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [490.6197696784991, 494.44622023333847, 496.30021669446074, 511.7952818872451, 512.743628185907, 21415.9292035398, 21750.456238594023, 22493.887530562344, 22515.98678059487, 22633.0, 23789.405264868386, 23927.0, 23949.880250598722, 24184.879075604596, 24264.878675606597, 6440.903386449207, 6553.148090748196, 6607.867842643144, 6608.603483790988, 6620.808254514185, 6568.211814582257, 6571.408573228408, 6588.769393071243, 6598.0, 6611.008348747702, 6051.001584738513, 6201.162843016203, 6422.133012043384, 6459.257185423689, 6460.450861676763, 5485.890282194374, 6392.840178995539, 6439.774607888701, 6454.967725161344, 6463.57986730861, 5018.498150184989, 5019.899602007939, 5901.23283973085, 5906.143609176688, 5925.259342582177, 28345.433091338058, 29503.852480737674, 30282.69717302821, 30483.847580762176, 30534.694653053408, 2191.791779780918, 2202.9889850550803, 2211.8672879627275, 2230.7434645015705, 2232.0, 18487.352942646943, 18841.717374239397, 18955.052247387575, 18961.957092359913, 18986.52533686655, 2165.2096984600666, 2204.1954686539457, 2225.677276794872, 2228.6322756745117, 2234.3297010896827, 161047.0, 166875.0, 168325.0, 168368.0, 199102.0, 13118.344082795822, 13622.591322260349, 14518.20149891766, 14788.038777479645, 14837.777433338508, 19729.605407891762, 19899.60200795976, 19914.402567922985, 20023.99880006023, 20025.0, 8260.802183683332, 9625.0, 9685.079917407835, 9714.95142524297, 9778.9022109778, 7584.924150758422, 8701.782455438664, 8750.606222719993, 8997.235235004968, 9091.772705682279, 8898.777530561658, 9004.59479323432, 9028.388644542236, 9047.411918225425, 9056.0, 2167.5989941860807, 2554.1315952576215, 2562.5643640581147, 2566.742296274849, 2675.224184986332, 977.9804403911882, 981.8045468897989, 982.9508524573743, 1037.190991027, 1037.7872536130076, 20963.26628567993, 21104.0, 21240.893795530923, 21326.36020919375, 22183.66724499134, 927.9536023198813, 1019.90820826126, 1025.4616326428536, 1045.9320144190604, 1046.3355769086684, 8933.821323573493, 11132.49903754333, 11189.272697274651, 11235.83146252807, 11394.829077563843, 570967.0, 582567.0, 589480.0, 598662.0, 604393.0, 152487.0, 153619.0, 157804.0, 158997.41002589825, 159142.0, 1017.1354348803458, 1076.2896488317672, 1167.2237961755507, 1209.4859684633996, 1257.0320852943182, 18061.458156255332, 22223.0, 24308.75691243065, 24333.634995474727, 24979.625305620793, 65097.67451162714, 66344.0, 66700.0, 67956.86452922372, 68443.0, 89676.0, 91026.54486727524, 91174.0, 91221.0, 91510.0, 11549.711257218301, 11593.6521904343, 11655.242409243372, 11728.765424691288, 11754.647360579196, 17887.01621410783, 18360.449186524427, 18617.348392806187, 18725.344612938483, 18916.621667566298, 12140.817887731868, 15163.241837908281, 15200.087994720352, 15291.770623440434, 15351.232438377812, 13162.0, 13265.403056862657, 13314.800277996032, 13432.798508022192, 13807.447702091804, 11213.60752373663, 15284.617884552976, 15419.922900385427, 15591.610209744393, 15599.0, 9493.85759213626, 10973.518574992273, 11341.922517360837, 11638.35989020612, 11641.59254426091, 14594.416223350947, 14652.047616904867, 14810.111393316436, 14829.184394858386, 14998.7000259992], "value": 11609.799140215739, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1148.6266963236951, 1329.9626291492634, 1398.9370478328474, 1423.7826658207232, 1423.9715205695893, 11216.214864959436, 12851.29317887517, 13248.801267980987, 13460.932695336522, 13466.865331346684, 12910.806337904938, 13255.867441325583, 13290.069695121345, 13301.866981330184, 13322.000849936268, 2252.966205506919, 2751.312171957011, 2754.848483333416, 2757.5587905935054, 2759.8206116602455, 2673.7860971122263, 2731.112388473744, 2731.549294366437, 2731.6721993360825, 2734.5214587447217, 1871.5227616957657, 2340.958273568263, 2669.2392668089597, 2704.648395708555, 2728.7544121029105, 2277.464795772992, 2311.7225932888077, 2591.20968104728, 2696.3528753099313, 2703.4998525272886, 2752.2156185487243, 2755.6830964439146, 2755.9724402756115, 2756.0904901382496, 2758.8344699318104, 13134.080614356999, 13217.206967581977, 13231.073824832265, 13234.86765132346, 13264.204147751167, 7218.963905180441, 7447.069116360455, 7496.887546686804, 7515.286047825448, 7518.210587888302, 4479.350494178326, 4657.883552911204, 4657.95050177924, 4752.714837109785, 4758.881027974294, 1411.6118067531409, 1414.9812135262594, 1420.936057877398, 1431.391658545114, 1445.407382972976, 19556.511087222792, 19650.90174549118, 19655.606887862305, 19784.901075494676, 19843.107060182327, 5276.709780962007, 6376.872462550723, 6492.285848556655, 6587.571807832475, 6598.076269322299, 13903.235322057384, 14086.15483071019, 14086.71826563463, 14110.50613228532, 14123.223222722849, 7104.538205016657, 7243.674034668453, 7244.891326630105, 7260.2013778484325, 7264.491485595958, 6437.67811609427, 6665.900011499832, 6675.0, 6686.732530698718, 6747.156605424322, 4841.2496063110275, 4970.801167953314, 5010.2735103409805, 5057.494250574913, 5069.138246498104, 1421.0620990146454, 1440.1503113163074, 1444.8193975753031, 1460.8612181842707, 1466.8899832512604, 789.2383849585178, 804.2038382001776, 819.815541503157, 823.2755175445569, 835.4778263585259, 6914.723411063602, 7098.467614928901, 7132.215456299803, 7207.89188162178, 7227.421806255487, 633.9746410143542, 668.3216535216759, 697.3584302441766, 701.6596950479013, 702.880510313248, 3214.9196270093435, 4317.6114149726045, 5079.1365467870555, 5211.843644690665, 5258.399729778052, 17512.824871751374, 17986.5503362417, 18154.727679084823, 18185.45443636693, 18239.17923693437, 14524.419023238952, 17950.0, 17993.0, 18107.54731131732, 18116.547086322946, 8803.779905502286, 9276.211522020569, 9321.673741419017, 9519.762005949768, 9531.285153613368, 7463.813404664927, 7569.394448444001, 7824.491408058458, 8116.0, 8201.630926608197, 14750.704985900436, 15085.0, 15817.288222029807, 16021.519354419386, 16184.919075404548, 15676.372945082068, 16091.356345746039, 16257.67484650324, 16420.671586568442, 16498.42255521051, 9898.901010989797, 10863.293885897392, 10866.836997445203, 10884.510197040994, 10900.182486313717, 11953.163278570417, 12160.756784864076, 12191.939040304742, 12196.024318054357, 12224.755504889674, 9634.0, 9634.755710659765, 9777.0, 9931.354461959949, 10030.29787914839, 9457.905420945703, 9652.372595781251, 9814.803703926023, 9844.2124630028, 9992.750181245527, 8909.777255568662, 8945.105489451003, 8996.685116020908, 9012.909870901469, 9016.549172541478, 6107.206063211754, 6416.647084410402, 6550.312217217123, 6594.604323740591, 6856.41720453767, 6483.837904052248, 6599.769008084694, 6875.931240687529, 6907.309269073053, 7047.316127166782], "value": 5901.793943654576, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [424.21943623732324, 447.75597299471775, 453.981840726371, 466.384372628131, 469.9496625043028, 6296.1814964054665, 6330.905036424451, 6333.809985700431, 6334.113224148623, 6337.239531256244, 5918.556108291885, 6119.7246123924415, 6150.923588372039, 6164.445199932005, 6190.659513726743, 4685.2035154023815, 4703.952960470403, 4705.9058818823605, 4708.9764551177195, 4719.90560188796, 4690.742009189493, 4698.906021879578, 4705.129551033053, 4740.36005139307, 4747.667663263555, 4038.1519880825063, 4423.911521769563, 4680.25115981443, 4703.365045718835, 4709.835155769549, 3794.981025094871, 4485.932711009337, 4659.88350291242, 4669.2529195328925, 4685.4143232095985, 3711.46183803347, 3758.4174452959833, 4088.9386659200136, 4098.8360465581345, 4110.527289361732, 5603.3836278009385, 5938.970305148447, 6039.969800151015, 6042.365551617061, 6063.575549711522, 4792.400949881265, 5250.369955605352, 5551.1118221084835, 5554.50009499143, 5642.689652069134, 6593.3736295051895, 6659.467242620579, 6679.697458995498, 6698.5980841149685, 6740.1237839081095, 3871.380579107329, 3895.590962948878, 4243.66050715945, 4260.360945858115, 4700.976495117503, 6417.518686098515, 6458.257300410466, 6472.773452929125, 7070.434365250716, 7148.392386647141, 4343.978280108579, 4617.3304870793545, 4739.0, 4762.76186190696, 4772.904541909143, 4859.319695242669, 4865.172920603506, 4873.366462359942, 4880.170371036861, 4915.705057696549, 5548.278723765885, 5566.916496252559, 5570.554355651539, 5591.30108736408, 5602.97198514013, 5853.853653658607, 6384.265809431882, 6715.985204438746, 6728.0, 6749.628770417674, 1455.8398576156615, 1586.7653634060357, 1637.7625244339504, 1653.7519372094282, 1666.983330166707, 1107.85044019058, 1118.2395970739974, 1131.643532287327, 1136.170595465315, 1139.2310190621297, 5077.593792496591, 5876.853078672982, 6213.440790328802, 6281.277653069864, 6328.557001009977, 6916.273791251947, 6916.6541672916155, 6935.826604334831, 6944.02783610295, 6955.504566518223, 5529.944700553024, 6319.6208227506495, 6352.650604216812, 6362.0, 6393.296737358887, 3369.359821633882, 3455.5335029771163, 3545.3618348697482, 3571.2143328467696, 3599.7321687337335, 5855.0, 6464.896849512166, 7061.399781018671, 7102.964485177541, 7138.357547820721, 6473.805785826433, 6621.437177839841, 6719.966400167968, 7106.005159277705, 7196.352328290479, 5430.728463576885, 6853.931460685429, 7090.92909070913, 7127.144742630814, 7145.285471452917, 5406.83779486616, 5490.474381784424, 6940.722371105099, 6954.582725036514, 7003.439724822104, 5456.399796022513, 6165.859316026549, 6423.807285781434, 6631.970111681633, 6801.0, 7164.516112917818, 7166.820829479097, 7173.784786456415, 7177.102862142232, 7189.856202875809, 4701.623870090316, 4756.857294281177, 4764.809407623657, 4767.833125840579, 4777.0, 5960.701964901651, 6793.864122717617, 6943.930560694328, 6983.406410455168, 7019.3682568569075, 6016.127661488972, 6667.0, 6693.1298931138645, 6757.493188011017, 6784.660766961533, 6139.754409823557, 6617.0, 6687.465002799861, 6725.831854203684, 6737.562058466184, 5481.095619222783, 5552.583556233218, 6512.967435162794, 6549.1838509441395, 6593.967030164818, 5526.778928842801, 6149.323574406902, 6621.668916554249, 6676.899846502403, 6678.365555272241, 6128.693565321805, 6237.656928868956, 6280.717367718372, 6388.169537960035, 6524.771632992823], "value": 4907.07510159542, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [783.3693876429473, 820.6963423533293, 824.9010118785746, 852.475727427632, 853.6981103816679, 16754.83245167548, 17051.0, 17229.74155387667, 17256.56858578536, 17288.82711172888, 12648.557300494485, 13241.006924480678, 13265.535706250283, 15720.0, 16568.420105296318, 948.8908775490803, 955.3837774635356, 963.3738070254356, 969.3360048366856, 981.9410835349867, 961.6393852305386, 973.1922504321432, 974.352055882837, 977.4721650308825, 979.8383266760976, 1016.1768967136607, 1024.748936510556, 1027.291169093327, 1028.5731421460084, 1030.0472063341379, 953.132649289147, 981.2640519610293, 1041.874975002997, 1042.3485321673916, 1046.1787496814964, 774.6088225446169, 778.6885245901628, 780.8399278148023, 781.6560713286136, 783.6473586885924, 14481.78277325841, 16687.33250669984, 17127.57181070483, 17137.143142842808, 17245.0, 17302.22140003703, 17544.94730316185, 17605.207765650448, 17647.0, 17723.37968171121, 13311.334433278298, 14426.711465770732, 14860.479883204036, 14925.328360223708, 15044.849551504454, 3648.781073135594, 3684.2631473705364, 3684.7236457265813, 3689.723270754704, 3699.093722038091, 112133.43933280362, 130994.0, 131319.0, 138899.0, 146536.0, 13587.45650173982, 13675.794863077062, 13754.724905501835, 13818.861811381757, 13935.86064139366, 25500.744992549837, 25723.971041158144, 25779.871100644377, 25809.354766130997, 26401.339966500993, 7490.5505669658905, 7573.46985710995, 7659.655315510815, 7667.501612395176, 7827.491213071132, 3921.274564205631, 3964.3260645689725, 3966.801659916993, 4000.7999600019884, 4007.7595344279434, 4570.314452832035, 4672.602828759525, 4674.579287864109, 4686.250199968057, 4693.929591056137, 976.8827740671023, 1004.0712341084613, 1014.452195814252, 1014.8883622801486, 1015.421209910344, 568.2101878389043, 620.7858288890326, 636.8216899268213, 649.5420728386476, 661.5104822431463, 11569.247998880046, 11596.478158482889, 11818.349990750592, 11872.34702091376, 12264.509419623115, 608.2093278737624, 635.2757856044113, 650.8958566629411, 652.1749986267375, 687.5702685821361, 5294.0, 5929.792457264061, 6171.783987560414, 6254.093156492283, 6714.731410743516, 124783.37608312082, 128412.0, 133822.33088834689, 135477.0, 138683.0, 101343.49328253312, 103021.48489257655, 105825.0, 110241.0, 113373.43313283546, 96331.51834240784, 98980.0, 99215.0, 100748.49625751824, 102862.48568757258, 9769.609215631295, 10034.849477257701, 12006.09954253417, 12201.450934708137, 12785.36073196355, 34344.45449954708, 34663.653363466045, 37339.62660373362, 45686.086278273586, 46306.0, 72852.0, 78775.0, 80418.59790701009, 81650.59174704089, 86144.13855861362, 16917.0, 17097.145142743062, 17146.142692865058, 17154.828451715322, 17187.31250749956, 18463.53841153982, 18505.444836654922, 18505.81494185095, 18864.717029244846, 18968.715469267696, 13788.724225515634, 16482.25829837684, 16680.49958501247, 17291.91354043222, 17423.39018134359, 14848.0, 15228.923855380652, 16680.24938877778, 17280.913595432445, 17567.209475573887, 14352.354144063333, 16235.350585976428, 16747.0, 16990.49028529146, 17010.319587216374, 10648.264405339054, 11832.645020649394, 11869.703257418634, 12042.879571204527, 12114.75770484603, 12775.680607984874, 12874.485020599446, 12951.805722913976, 12956.093073485143, 13372.799408009083], "value": 8630.664985820145, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1514.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 116.0, 117.0, 125.0, 122.0, 120.0, 114.0, 117.0, 116.0, 120.0], "value": 117.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 146.0, 201.0, 135.0, 115.0, 128.0, 111.0, 141.0, 140.0, 108.0], "value": 139.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 209.0, 198.0, 204.0, 212.0, 217.0, 223.0, 263.0, 219.0, 194.0], "value": 213.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 80.0, 75.0, 131.0, 57.0, 59.0, 62.0, 69.0, 115.0, 78.0], "value": 79.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 109.0, 72.0, 69.0, 72.0, 126.0, 56.0, 64.0, 113.0], "value": 81.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 89.0, 89.0, 90.0, 89.0, 90.0, 91.0, 90.0, 89.0], "value": 89.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 97.0, 121.0, 98.0, 96.0, 97.0, 94.0, 96.0, 98.0], "value": 99.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 92.0, 118.0, 115.0, 145.0, 92.0, 91.0, 94.0, 93.0, 91.0], "value": 102.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 51.0, 54.0, 53.0, 52.0, 54.0, 53.0, 53.0, 63.0], "value": 53.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 74.0, 83.0, 57.0, 64.0, 63.0, 56.0, 59.0, 57.0, 59.0], "value": 63.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 91.0, 90.0, 81.0, 82.0, 81.0, 79.0, 83.0, 80.0, 81.0], "value": 82.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 137.0, 136.0, 134.0, 136.0, 141.0, 138.0, 137.0, 136.0, 164.0], "value": 140.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 184.0, 185.0, 176.0, 179.0, 179.0, 178.0, 190.0, 187.0, 183.0], "value": 182.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 65.0, 60.0, 90.0, 95.0, 63.0, 64.0, 65.0, 65.0], "value": 69.9, "unit": "score"}]}]} 17:14:58 INFO - Return code: 0 17:14:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:14:58 INFO - # TBPL SUCCESS # 17:14:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:14:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:14:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:14:58 INFO - 2017-06-12 17:14:58.894 screenresolution[1674:11268] starting screenresolution argv=screenresolution get 17:14:58 INFO - 2017-06-12 17:14:58.896 screenresolution[1674:11268] Display 0: 1600x1200x32@60 17:14:58 INFO - 2017-06-12 17:14:58.902 screenresolution[1675:11271] starting screenresolution argv=screenresolution list 17:14:58 INFO - Available Modes on Display 0 17:14:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:14:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:14:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:14:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:14:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:14:59 INFO - Intel Iris: 17:14:59 INFO - Chipset Model: Intel Iris 17:14:59 INFO - Type: GPU 17:14:59 INFO - Bus: Built-In 17:14:59 INFO - VRAM (Dynamic, Max): 1536 MB 17:14:59 INFO - Vendor: Intel (0x8086) 17:14:59 INFO - Device ID: 0x0a2e 17:14:59 INFO - Revision ID: 0x0009 17:14:59 INFO - Displays: 17:14:59 INFO - Display: 17:14:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:14:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:14:59 INFO - Display Serial Number: 1600x1200 60 17:14:59 INFO - Main Display: Yes 17:14:59 INFO - Mirror: Off 17:14:59 INFO - Online: Yes 17:14:59 INFO - Rotation: Supported 17:14:59 INFO - Return code: 0 17:14:59 INFO - Running post-action listener: _resource_record_post_action 17:14:59 INFO - [mozharness: 2017-06-13 00:14:59.136721Z] Finished run-tests step (success) 17:14:59 INFO - Running post-run listener: _resource_record_post_run 17:14:59 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 45022720; Write bytes: 576911872; Read time: 293; Write time: 1839 17:14:59 INFO - TinderboxPrint: CPU usage
53.3% 17:14:59 INFO - TinderboxPrint: I/O read bytes / time
45,022,720 / 293 17:14:59 INFO - TinderboxPrint: I/O write bytes / time
576,911,872 / 1,839 17:14:59 INFO - TinderboxPrint: CPU idle
2,345.1 (46.7%) 17:14:59 INFO - TinderboxPrint: CPU system
1,017.4 (20.2%) 17:14:59 INFO - TinderboxPrint: CPU user
1,663.3 (33.1%) 17:14:59 INFO - TinderboxPrint: Swap in / out
305,438,720 / 0 17:14:59 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 253772288; Write bytes: 257230336; Read time: 18044; Write time: 556 17:14:59 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 17:14:59 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 42110464; Write bytes: 318272512; Read time: 264; Write time: 1278 17:14:59 INFO - Running post-run listener: _upload_blobber_files 17:14:59 INFO - Blob upload gear active. 17:14:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:14:59 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 17:14:59 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', 'Cedar', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:14:59 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 Cedar -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:15:00 INFO - (blobuploader) - INFO - Open directory for files ... 17:15:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:15:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:15:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:15:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:15:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:15:01 INFO - (blobuploader) - INFO - Done attempting. 17:15:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:15:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:15:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:15:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:15:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:15:02 INFO - (blobuploader) - INFO - Done attempting. 17:15:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:15:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:15:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:15:03 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:15:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:15:03 INFO - (blobuploader) - INFO - Done attempting. 17:15:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:15:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:15:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:15:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:15:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:15:05 INFO - (blobuploader) - INFO - Done attempting. 17:15:05 INFO - (blobuploader) - INFO - Iteration through files over. 17:15:05 INFO - Return code: 0 17:15:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:15:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:15:05 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/3bfa6568d21e6a56f1bb9adea6a64f31cf6159d8b0c20b2b3afa4ddcb8204957ad0f0e95499d0253ba9be705f122678142106787b854e3ebda0e540e268295c1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/d79d2d046d2c6ca240ef8aee3e8505b63d47e178362f1f48a996954869fd5aa6d8892e3276b243eee89551899a17255daf4ead6957d54bc3614c8237ea02d299", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/eee5f9d53e77ab031615ed87cfec11146f52351dc7a1b2bfc8893d119446f3c39fc261b41f96176940f38dafc33e314a57426f979bd57cb5bae86f8fcc107a52", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/2d934c5ac07af82a0e6b1a601f1c9a84cd2c41446f9429cfd6347658daaa9f785c190869cfdc26507ab9470fb4b68749eba07435e10e829eb51c6dd77099a043"} 17:15:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:15:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:15:05 INFO - Contents: 17:15:05 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/3bfa6568d21e6a56f1bb9adea6a64f31cf6159d8b0c20b2b3afa4ddcb8204957ad0f0e95499d0253ba9be705f122678142106787b854e3ebda0e540e268295c1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/d79d2d046d2c6ca240ef8aee3e8505b63d47e178362f1f48a996954869fd5aa6d8892e3276b243eee89551899a17255daf4ead6957d54bc3614c8237ea02d299", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/eee5f9d53e77ab031615ed87cfec11146f52351dc7a1b2bfc8893d119446f3c39fc261b41f96176940f38dafc33e314a57426f979bd57cb5bae86f8fcc107a52", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/2d934c5ac07af82a0e6b1a601f1c9a84cd2c41446f9429cfd6347658daaa9f785c190869cfdc26507ab9470fb4b68749eba07435e10e829eb51c6dd77099a043"} 17:15:05 INFO - Running post-run listener: copy_logs_to_upload_dir 17:15:05 INFO - Copying logs to upload dir... 17:15:05 INFO - mkdir: /builds/slave/test/build/upload/logs 17:15:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1338.496889 ========= master_lag: -0.26 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-06-12 17:15:05.182810) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-12 17:15:05.186444) ========= 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.oDoijyvLlt/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.2wFZ7MDzgR/Listeners TMPDIR=/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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/Cedar/sha512/3bfa6568d21e6a56f1bb9adea6a64f31cf6159d8b0c20b2b3afa4ddcb8204957ad0f0e95499d0253ba9be705f122678142106787b854e3ebda0e540e268295c1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/d79d2d046d2c6ca240ef8aee3e8505b63d47e178362f1f48a996954869fd5aa6d8892e3276b243eee89551899a17255daf4ead6957d54bc3614c8237ea02d299", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/eee5f9d53e77ab031615ed87cfec11146f52351dc7a1b2bfc8893d119446f3c39fc261b41f96176940f38dafc33e314a57426f979bd57cb5bae86f8fcc107a52", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/2d934c5ac07af82a0e6b1a601f1c9a84cd2c41446f9429cfd6347658daaa9f785c190869cfdc26507ab9470fb4b68749eba07435e10e829eb51c6dd77099a043"} build_url:https://queue.taskcluster.net/v1/task/JEIcrPHUQY-a6gBFETciEw/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011048 build_url: 'https://queue.taskcluster.net/v1/task/JEIcrPHUQY-a6gBFETciEw/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/3bfa6568d21e6a56f1bb9adea6a64f31cf6159d8b0c20b2b3afa4ddcb8204957ad0f0e95499d0253ba9be705f122678142106787b854e3ebda0e540e268295c1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/d79d2d046d2c6ca240ef8aee3e8505b63d47e178362f1f48a996954869fd5aa6d8892e3276b243eee89551899a17255daf4ead6957d54bc3614c8237ea02d299", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/eee5f9d53e77ab031615ed87cfec11146f52351dc7a1b2bfc8893d119446f3c39fc261b41f96176940f38dafc33e314a57426f979bd57cb5bae86f8fcc107a52", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/2d934c5ac07af82a0e6b1a601f1c9a84cd2c41446f9429cfd6347658daaa9f785c190869cfdc26507ab9470fb4b68749eba07435e10e829eb51c6dd77099a043"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-12 17:15:05.224483) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 17:15:05.224833) ========= 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.oDoijyvLlt/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.2wFZ7MDzgR/Listeners TMPDIR=/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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.005153 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 17:15:05.274217) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-12 17:15:05.274528) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-12 17:15:05.274875) ========= ========= Total master_lag: -0.13 =========