builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0091 starttime: 1496840826.18 results: success (0) revision: f6320f91851c2cede08b0fb54a6d0525b870310e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-07 06:07:06.178457) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-07 06:07:06.179006) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-07 06:07:06.179309) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-07 06:07:06.204930) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-07 06:07:06.205239) ========= 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.O71XTs0EVW/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.07rO4RdVZg/Listeners TMPDIR=/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/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-07 06:07:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 13.9M=0.001s 2017-06-07 06:07:06 (13.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.122964 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-07 06:07:06.351215) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-07 06:07:06.351548) ========= 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.O71XTs0EVW/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.07rO4RdVZg/Listeners TMPDIR=/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/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.027553 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-07 06:07:06.399886) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-07 06:07:06.400247) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f6320f91851c2cede08b0fb54a6d0525b870310e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev f6320f91851c2cede08b0fb54a6d0525b870310e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.O71XTs0EVW/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.07rO4RdVZg/Listeners TMPDIR=/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/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-07 06:07:06,499 truncating revision to first 12 chars 2017-06-07 06:07:06,499 Setting DEBUG logging. 2017-06-07 06:07:06,499 attempt 1/10 2017-06-07 06:07:06,500 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f6320f91851c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-07 06:07:08,318 unpacking tar archive at: mozilla-central-f6320f91851c/testing/mozharness/ program finished with exit code 0 elapsedTime=2.097686 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-07 06:07:08.519968) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-07 06:07:08.520317) ========= script_repo_revision: f6320f91851c2cede08b0fb54a6d0525b870310e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-07 06:07:08.520729) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-07 06:07:08.521004) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-07 06:07:08.540109) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-06-07 06:07:08.540511) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.O71XTs0EVW/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.07rO4RdVZg/Listeners TMPDIR=/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/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 06:07:08 INFO - MultiFileLogger online at 20170607 06:07:08 in /builds/slave/test 06:07:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 06:07:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:07:08 INFO - {'append_to_log': False, 06:07:08 INFO - 'base_work_dir': '/builds/slave/test', 06:07:08 INFO - 'blob_upload_branch': 'Firefox', 06:07:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:07:08 INFO - 'branch': 'Firefox', 06:07:08 INFO - 'buildbot_json_path': 'buildprops.json', 06:07:08 INFO - 'config_files': ('talos/mac_config.py',), 06:07:08 INFO - 'default_actions': ('clobber', 06:07:08 INFO - 'read-buildbot-config', 06:07:08 INFO - 'download-and-extract', 06:07:08 INFO - 'populate-webroot', 06:07:08 INFO - 'create-virtualenv', 06:07:08 INFO - 'install', 06:07:08 INFO - 'setup-mitmproxy', 06:07:08 INFO - 'run-tests'), 06:07:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:07:08 INFO - 'download_minidump_stackwalk': True, 06:07:08 INFO - 'download_symbols': 'ondemand', 06:07:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:07:08 INFO - 'tooltool.py': '/tools/tooltool.py', 06:07:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:07:08 INFO - '/tools/misc-python/virtualenv.py')}, 06:07:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:07:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:07:08 INFO - 'gecko_profile': False, 06:07:08 INFO - 'gecko_profile_interval': 0, 06:07:08 INFO - 'installer_path': 'installer.exe', 06:07:08 INFO - 'log_level': 'info', 06:07:08 INFO - 'log_name': 'talos', 06:07:08 INFO - 'log_to_console': True, 06:07:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:07:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:07:08 INFO - 'opt_config_files': (), 06:07:08 INFO - 'pip_index': False, 06:07:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:07:08 INFO - 'cmd': ('bash', 06:07:08 INFO - '-c', 06:07:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:07:08 INFO - 'enabled': True, 06:07:08 INFO - 'halt_on_failure': False, 06:07:08 INFO - 'name': 'check_screen_resolution'},), 06:07:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:07:08 INFO - 'cmd': ('bash', 06:07:08 INFO - '-c', 06:07:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:07:08 INFO - 'enabled': True, 06:07:08 INFO - 'halt_on_failure': False, 06:07:08 INFO - 'name': 'check_screen_resolution'},), 06:07:08 INFO - 'run_cmd_checks_enabled': True, 06:07:08 INFO - 'suite': 'dromaeojs-e10s', 06:07:08 INFO - 'system_bits': '32', 06:07:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:07:08 INFO - 'title': 't-yosemite-r7-0091', 06:07:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:07:08 INFO - 'use_talos_json': True, 06:07:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:07:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:07:08 INFO - 'work_dir': 'build'} 06:07:08 INFO - [mozharness: 2017-06-07 13:07:08.747804Z] Running clobber step. 06:07:08 INFO - Running pre-action listener: _resource_record_pre_action 06:07:08 INFO - Running main action method: clobber 06:07:08 INFO - rmtree: /builds/slave/test/build 06:07:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:07:11 INFO - Running post-action listener: _resource_record_post_action 06:07:11 INFO - [mozharness: 2017-06-07 13:07:11.274688Z] Finished clobber step (success) 06:07:11 INFO - [mozharness: 2017-06-07 13:07:11.274816Z] Running read-buildbot-config step. 06:07:11 INFO - Running pre-action listener: _resource_record_pre_action 06:07:11 INFO - Running main action method: read_buildbot_config 06:07:11 INFO - Using buildbot properties: 06:07:11 INFO - { 06:07:11 INFO - "project": "", 06:07:11 INFO - "product": "firefox", 06:07:11 INFO - "who": "cbook@mozilla.com", 06:07:11 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 06:07:11 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 06:07:11 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 06:07:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 06:07:11 INFO - "stage_platform": "macosx64", 06:07:11 INFO - "basedir": "/builds/slave/test", 06:07:11 INFO - "buildnumber": 80, 06:07:11 INFO - "platform": "yosemite_r7", 06:07:11 INFO - "parent_task_id": "N01XEjMNRXuO4FYowsEHHA", 06:07:11 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 06:07:11 INFO - "slavebuilddir": "test", 06:07:11 INFO - "taskId": "ctoFCSLGSQqlo52NI9SqMg", 06:07:11 INFO - "branch": "mozilla-central", 06:07:11 INFO - "script_repo_revision": "production", 06:07:11 INFO - "revision": "f6320f91851c2cede08b0fb54a6d0525b870310e", 06:07:11 INFO - "slavename": "t-yosemite-r7-0091", 06:07:11 INFO - "repo_path": "mozilla-central" 06:07:11 INFO - } 06:07:11 INFO - Finding installer, test and symbols from parent task. 06:07:11 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ctoFCSLGSQqlo52NI9SqMg'}, attempt #1 06:07:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ctoFCSLGSQqlo52NI9SqMg'}, attempt #1 06:07:12 INFO - Task dependencies: L5oTW_P1QF-tCQHwA9orOQ N01XEjMNRXuO4FYowsEHHA 06:07:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N01XEjMNRXuO4FYowsEHHA'}, attempt #1 06:07:12 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/N01XEjMNRXuO4FYowsEHHA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 06:07:12 INFO - Set test_url: https://queue.taskcluster.net/v1/task/N01XEjMNRXuO4FYowsEHHA/artifacts/public/build/test_packages.json 06:07:12 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/N01XEjMNRXuO4FYowsEHHA/artifacts/public/build/target.crashreporter-symbols.zip 06:07:12 INFO - Running post-action listener: _resource_record_post_action 06:07:12 INFO - [mozharness: 2017-06-07 13:07:12.940620Z] Finished read-buildbot-config step (success) 06:07:12 INFO - [mozharness: 2017-06-07 13:07:12.940762Z] Running download-and-extract step. 06:07:12 INFO - Running pre-action listener: _resource_record_pre_action 06:07:12 INFO - Running main action method: download_and_extract 06:07:12 INFO - mkdir: /builds/slave/test/build/tests 06:07:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:12 INFO - trying https://queue.taskcluster.net/v1/task/N01XEjMNRXuO4FYowsEHHA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 06:07:12 INFO - Downloading https://queue.taskcluster.net/v1/task/N01XEjMNRXuO4FYowsEHHA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 06:07:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N01XEjMNRXuO4FYowsEHHA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 06:07:13 INFO - Downloaded 1328 bytes. 06:07:13 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 06:07:13 INFO - Using the following test package requirements: 06:07:13 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:07:13 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 06:07:13 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 06:07:13 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:07:13 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 06:07:13 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:07:13 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 06:07:13 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:07:13 INFO - u'jsshell-mac.zip'], 06:07:13 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:07:13 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 06:07:13 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 06:07:13 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:07:13 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 06:07:13 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:07:13 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 06:07:13 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:07:13 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 06:07:13 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:07:13 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 06:07:13 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:07:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/N01XEjMNRXuO4FYowsEHHA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 06:07:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N01XEjMNRXuO4FYowsEHHA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:07:13 INFO - Fetch https://queue.taskcluster.net/v1/task/N01XEjMNRXuO4FYowsEHHA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 06:07:15 INFO - Content-Length response header: 57639069 06:07:15 INFO - Bytes received: 57639069 06:07:33 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 06:07:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/N01XEjMNRXuO4FYowsEHHA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 06:07:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N01XEjMNRXuO4FYowsEHHA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:07:33 INFO - Fetch https://queue.taskcluster.net/v1/task/N01XEjMNRXuO4FYowsEHHA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 06:07:35 INFO - Content-Length response header: 57639069 06:07:35 INFO - Bytes received: 57639069 06:07:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/N01XEjMNRXuO4FYowsEHHA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 06:07:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N01XEjMNRXuO4FYowsEHHA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:07:53 INFO - Fetch https://queue.taskcluster.net/v1/task/N01XEjMNRXuO4FYowsEHHA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 06:07:54 INFO - Content-Length response header: 14447563 06:07:54 INFO - Bytes received: 14447563 06:07:54 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')]} 06:07:54 INFO - trying https://queue.taskcluster.net/v1/task/N01XEjMNRXuO4FYowsEHHA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 06:07:54 INFO - Downloading https://queue.taskcluster.net/v1/task/N01XEjMNRXuO4FYowsEHHA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 06:07:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N01XEjMNRXuO4FYowsEHHA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 06:07:56 INFO - Downloaded 68022277 bytes. 06:07:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/N01XEjMNRXuO4FYowsEHHA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 06:07:56 INFO - mkdir: /builds/slave/test/properties 06:07:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:07:56 INFO - Writing to file /builds/slave/test/properties/build_url 06:07:56 INFO - Contents: 06:07:56 INFO - build_url:https://queue.taskcluster.net/v1/task/N01XEjMNRXuO4FYowsEHHA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 06:07:56 INFO - Running post-action listener: _resource_record_post_action 06:07:56 INFO - Running post-action listener: set_extra_try_arguments 06:07:56 INFO - [mozharness: 2017-06-07 13:07:56.791522Z] Finished download-and-extract step (success) 06:07:56 INFO - [mozharness: 2017-06-07 13:07:56.791683Z] Running populate-webroot step. 06:07:56 INFO - Running pre-action listener: _resource_record_pre_action 06:07:56 INFO - Running main action method: populate_webroot 06:07:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:07:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:07:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:07:56 INFO - '--tppagecycles', 06:07:56 INFO - '7'], 06:07:56 INFO - 'tests': ['tsvgm']}}, 06:07:56 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:07:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:07:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:07:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:07:56 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:07:56 INFO - 'talos_options': ['--disable-e10s'], 06:07:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:07:56 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:07:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:07:56 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:07:56 INFO - 'talos_options': ['--disable-e10s'], 06:07:56 INFO - 'tests': ['damp', 'tps']}, 06:07:56 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:07:56 INFO - 'tests': ['damp', 'tps']}, 06:07:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:07:56 INFO - 'tests': ['dromaeo_dom']}, 06:07:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:07:56 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:07:56 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 06:07:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 06:07:56 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 06:07:56 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 06:07:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:07:56 INFO - 'tests': ['a11yr', 06:07:56 INFO - 'ts_paint', 06:07:56 INFO - 'tpaint', 06:07:56 INFO - 'sessionrestore', 06:07:56 INFO - 'sessionrestore_no_auto_restore', 06:07:56 INFO - 'tabpaint']}, 06:07:56 INFO - 'other-e10s': {'tests': ['a11yr', 06:07:56 INFO - 'ts_paint', 06:07:56 INFO - 'tpaint', 06:07:56 INFO - 'sessionrestore', 06:07:56 INFO - 'sessionrestore_no_auto_restore', 06:07:56 INFO - 'tabpaint']}, 06:07:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:07:56 INFO - 'ts_paint', 06:07:56 INFO - 'tpaint', 06:07:56 INFO - 'sessionrestore', 06:07:56 INFO - 'sessionrestore_no_auto_restore', 06:07:56 INFO - 'tabpaint']}, 06:07:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:07:56 INFO - 'ts_paint', 06:07:56 INFO - 'tpaint', 06:07:56 INFO - 'sessionrestore', 06:07:56 INFO - 'sessionrestore_no_auto_restore', 06:07:56 INFO - 'tabpaint']}, 06:07:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:07:56 INFO - 'tests': ['a11yr', 06:07:56 INFO - 'ts_paint', 06:07:56 INFO - 'tpaint', 06:07:56 INFO - 'sessionrestore', 06:07:56 INFO - 'sessionrestore_no_auto_restore', 06:07:56 INFO - 'tabpaint']}, 06:07:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:07:56 INFO - 'tests': ['a11yr', 06:07:56 INFO - 'ts_paint', 06:07:56 INFO - 'tpaint', 06:07:56 INFO - 'sessionrestore', 06:07:56 INFO - 'sessionrestore_no_auto_restore', 06:07:56 INFO - 'tabpaint']}, 06:07:56 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 06:07:56 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 06:07:56 INFO - 'bloom_basic_ref']}, 06:07:56 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 06:07:56 INFO - 'talos_options': ['--mitmproxy', 06:07:56 INFO - 'mitmproxy-recording-1.mp', 06:07:56 INFO - '--firstNonBlankPaint'], 06:07:56 INFO - 'tests': ['Quantum_1']}, 06:07:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:07:56 INFO - 'tests': ['tsvgx', 06:07:56 INFO - 'tsvgr_opacity', 06:07:56 INFO - 'tart', 06:07:56 INFO - 'tscrollx', 06:07:56 INFO - 'cart', 06:07:56 INFO - 'tsvg_static']}, 06:07:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:07:56 INFO - 'tsvgr_opacity', 06:07:56 INFO - 'tart', 06:07:56 INFO - 'tscrollx', 06:07:56 INFO - 'cart', 06:07:56 INFO - 'tsvg_static']}, 06:07:56 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:07:56 INFO - 'talos_options': ['--disable-e10s'], 06:07:56 INFO - 'tests': ['tp5o']}, 06:07:56 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:07:56 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:07:56 INFO - 'talos_options': ['--disable-e10s', 06:07:56 INFO - '--xperf_path', 06:07:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:07:56 INFO - 'tests': ['tp5n']}, 06:07:56 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:07:56 INFO - 'talos_options': ['--xperf_path', 06:07:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:07:56 INFO - 'tests': ['tp5n']}}, 06:07:56 INFO - 'talos.zip': {'path': '', 06:07:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:07:56 INFO - Running post-action listener: _resource_record_post_action 06:07:56 INFO - [mozharness: 2017-06-07 13:07:56.814092Z] Finished populate-webroot step (success) 06:07:56 INFO - [mozharness: 2017-06-07 13:07:56.814235Z] Running create-virtualenv step. 06:07:56 INFO - Running pre-action listener: _resource_record_pre_action 06:07:56 INFO - Running main action method: create_virtualenv 06:07:56 INFO - Creating virtualenv /builds/slave/test/build/venv 06:07:56 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 06:07:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:07:56 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 06:07:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:07:56 INFO - Using real prefix '/tools/python27' 06:07:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:07:57 INFO - Installing distribute.............................................................................................................................................................................................done. 06:08:00 INFO - Installing pip.................done. 06:08:00 INFO - Return code: 0 06:08:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:08:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:08:00 INFO - Reading from file tmpfile_stdout 06:08:00 INFO - Output received: 06:08:00 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) 06:08:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:08:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:08:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:08:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:08:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:08:00 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 0x1079abc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079af768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb312491680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107701d30>, '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 0x1076cbcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076f2be8>, '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.O71XTs0EVW/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.07rO4RdVZg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/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 06:08:00 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 06:08:00 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 06:08:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O71XTs0EVW/Render', 06:08:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:08:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:08:00 INFO - 'HOME': '/Users/cltbld', 06:08:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:08:00 INFO - 'LOGNAME': 'cltbld', 06:08:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:08:00 INFO - 'MOZ_NO_REMOTE': '1', 06:08:00 INFO - 'NO_EM_RESTART': '1', 06:08:00 INFO - 'PAGER': '/bin/cat', 06:08:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:08:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:08:00 INFO - 'PWD': '/builds/slave/test', 06:08:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:08:00 INFO - 'SHELL': '/bin/bash', 06:08:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.07rO4RdVZg/Listeners', 06:08:00 INFO - 'TMPDIR': '/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/', 06:08:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:08:00 INFO - 'USER': 'cltbld', 06:08:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:08:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:08:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:08:00 INFO - 'XPC_FLAGS': '0x0', 06:08:00 INFO - 'XPC_SERVICE_NAME': '0', 06:08:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:08:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:08:01 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 06:08:01 INFO - Cleaning up... 06:08:01 INFO - Return code: 0 06:08:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:08: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')]} 06:08:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:08: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')]} 06:08:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:08: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', '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 0x1079abc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079af768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb312491680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107701d30>, '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 0x1076cbcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076f2be8>, '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.O71XTs0EVW/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.07rO4RdVZg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/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 06:08: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', 'psutil>=3.1.1'] in /builds/slave/test/build 06:08: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 psutil>=3.1.1 06:08:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O71XTs0EVW/Render', 06:08:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:08:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:08:01 INFO - 'HOME': '/Users/cltbld', 06:08:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:08:01 INFO - 'LOGNAME': 'cltbld', 06:08:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:08:01 INFO - 'MOZ_NO_REMOTE': '1', 06:08:01 INFO - 'NO_EM_RESTART': '1', 06:08:01 INFO - 'PAGER': '/bin/cat', 06:08:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:08:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:08:01 INFO - 'PWD': '/builds/slave/test', 06:08:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:08:01 INFO - 'SHELL': '/bin/bash', 06:08:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.07rO4RdVZg/Listeners', 06:08:01 INFO - 'TMPDIR': '/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/', 06:08:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:08:01 INFO - 'USER': 'cltbld', 06:08:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:08:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:08:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:08:01 INFO - 'XPC_FLAGS': '0x0', 06:08:01 INFO - 'XPC_SERVICE_NAME': '0', 06:08:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:08:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:08:01 INFO - Downloading/unpacking psutil>=3.1.1 06:08:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:08:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:08:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:08:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:08:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:08:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:08:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:08:03 INFO - Installing collected packages: psutil 06:08:03 INFO - Running setup.py install for psutil 06:08:03 INFO - building 'psutil._psutil_osx' extension 06:08:03 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 06:08:03 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 06:08:04 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 06:08:04 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 06:08:04 INFO - building 'psutil._psutil_posix' extension 06:08:04 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 06:08:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:08:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:08:04 INFO - ^ 06:08:04 INFO - 1 warning generated. 06:08:04 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 06:08:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:08:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:08:04 INFO - Successfully installed psutil 06:08:04 INFO - Cleaning up... 06:08:04 INFO - Return code: 0 06:08:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:08:04 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')]} 06:08:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:08:04 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')]} 06:08:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:08:05 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 0x1079abc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079af768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb312491680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107701d30>, '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 0x1076cbcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076f2be8>, '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.O71XTs0EVW/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.07rO4RdVZg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/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 06:08:05 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 06:08:05 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 06:08:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O71XTs0EVW/Render', 06:08:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:08:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:08:05 INFO - 'HOME': '/Users/cltbld', 06:08:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:08:05 INFO - 'LOGNAME': 'cltbld', 06:08:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:08:05 INFO - 'MOZ_NO_REMOTE': '1', 06:08:05 INFO - 'NO_EM_RESTART': '1', 06:08:05 INFO - 'PAGER': '/bin/cat', 06:08:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:08:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:08:05 INFO - 'PWD': '/builds/slave/test', 06:08:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:08:05 INFO - 'SHELL': '/bin/bash', 06:08:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.07rO4RdVZg/Listeners', 06:08:05 INFO - 'TMPDIR': '/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/', 06:08:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:08:05 INFO - 'USER': 'cltbld', 06:08:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:08:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:08:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:08:05 INFO - 'XPC_FLAGS': '0x0', 06:08:05 INFO - 'XPC_SERVICE_NAME': '0', 06:08:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:08:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:08:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:08:05 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 06:08:05 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 06:08:05 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 06:08:05 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 06:08:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:08:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:08:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:08:07 INFO - Installing collected packages: mozsystemmonitor 06:08:07 INFO - Running setup.py install for mozsystemmonitor 06:08:07 INFO - Successfully installed mozsystemmonitor 06:08:07 INFO - Cleaning up... 06:08:07 INFO - Return code: 0 06:08:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:08:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:08:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:08:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:08:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:08:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1079abc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079af768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb312491680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107701d30>, '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 0x1076cbcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076f2be8>, '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.O71XTs0EVW/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.07rO4RdVZg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/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 06:08:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 06:08:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 06:08:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O71XTs0EVW/Render', 06:08:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:08:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:08:07 INFO - 'HOME': '/Users/cltbld', 06:08:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:08:07 INFO - 'LOGNAME': 'cltbld', 06:08:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:08:07 INFO - 'MOZ_NO_REMOTE': '1', 06:08:07 INFO - 'NO_EM_RESTART': '1', 06:08:07 INFO - 'PAGER': '/bin/cat', 06:08:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:08:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:08:07 INFO - 'PWD': '/builds/slave/test', 06:08:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:08:07 INFO - 'SHELL': '/bin/bash', 06:08:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.07rO4RdVZg/Listeners', 06:08:07 INFO - 'TMPDIR': '/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/', 06:08:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:08:07 INFO - 'USER': 'cltbld', 06:08:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:08:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:08:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:08:07 INFO - 'XPC_FLAGS': '0x0', 06:08:07 INFO - 'XPC_SERVICE_NAME': '0', 06:08:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:08:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:08:07 INFO - Downloading/unpacking jsonschema==2.5.1 06:08:07 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 06:08:07 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 06:08:07 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 06:08:07 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 06:08:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:08:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:08:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:08:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:08:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:08:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:08:09 INFO - Downloading functools32-3.2.3-2.tar.gz 06:08:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:08:09 INFO - warning: no files found matching '*.txt' 06:08:09 INFO - no previously-included directories found matching 'build' 06:08:09 INFO - no previously-included directories found matching 'dist' 06:08:09 INFO - no previously-included directories found matching '.git*' 06:08:09 INFO - Installing collected packages: jsonschema, functools32 06:08:09 INFO - Running setup.py install for functools32 06:08:09 INFO - warning: no files found matching '*.txt' 06:08:09 INFO - no previously-included directories found matching 'build' 06:08:09 INFO - no previously-included directories found matching 'dist' 06:08:09 INFO - no previously-included directories found matching '.git*' 06:08:09 INFO - Successfully installed jsonschema functools32 06:08:09 INFO - Cleaning up... 06:08:09 INFO - Return code: 0 06:08:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:08:09 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')]} 06:08:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:08:09 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')]} 06:08:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:08:09 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 0x1079abc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079af768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb312491680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107701d30>, '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 0x1076cbcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076f2be8>, '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.O71XTs0EVW/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.07rO4RdVZg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/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 06:08:09 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 06:08:09 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 06:08:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O71XTs0EVW/Render', 06:08:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:08:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:08:09 INFO - 'HOME': '/Users/cltbld', 06:08:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:08:09 INFO - 'LOGNAME': 'cltbld', 06:08:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:08:09 INFO - 'MOZ_NO_REMOTE': '1', 06:08:09 INFO - 'NO_EM_RESTART': '1', 06:08:09 INFO - 'PAGER': '/bin/cat', 06:08:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:08:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:08:09 INFO - 'PWD': '/builds/slave/test', 06:08:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:08:09 INFO - 'SHELL': '/bin/bash', 06:08:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.07rO4RdVZg/Listeners', 06:08:09 INFO - 'TMPDIR': '/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/', 06:08:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:08:09 INFO - 'USER': 'cltbld', 06:08:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:08:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:08:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:08:09 INFO - 'XPC_FLAGS': '0x0', 06:08:09 INFO - 'XPC_SERVICE_NAME': '0', 06:08:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:08:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:08:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:08:09 INFO - Cleaning up... 06:08:09 INFO - Return code: 0 06:08:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:08:09 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')]} 06:08:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:08:09 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')]} 06:08:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:08:09 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 0x1079abc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079af768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb312491680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107701d30>, '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 0x1076cbcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076f2be8>, '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.O71XTs0EVW/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.07rO4RdVZg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/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 06:08:09 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 06:08:09 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 06:08:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O71XTs0EVW/Render', 06:08:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:08:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:08:09 INFO - 'HOME': '/Users/cltbld', 06:08:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:08:09 INFO - 'LOGNAME': 'cltbld', 06:08:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:08:09 INFO - 'MOZ_NO_REMOTE': '1', 06:08:09 INFO - 'NO_EM_RESTART': '1', 06:08:09 INFO - 'PAGER': '/bin/cat', 06:08:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:08:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:08:09 INFO - 'PWD': '/builds/slave/test', 06:08:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:08:09 INFO - 'SHELL': '/bin/bash', 06:08:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.07rO4RdVZg/Listeners', 06:08:09 INFO - 'TMPDIR': '/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/', 06:08:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:08:09 INFO - 'USER': 'cltbld', 06:08:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:08:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:08:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:08:09 INFO - 'XPC_FLAGS': '0x0', 06:08:09 INFO - 'XPC_SERVICE_NAME': '0', 06:08:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:08:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:08:10 INFO - Downloading/unpacking blobuploader==1.2.4 06:08:10 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 06:08:10 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 06:08:10 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 06:08:10 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 06:08:11 INFO - Downloading blobuploader-1.2.4.tar.gz 06:08:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:08:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:08:11 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 06:08:11 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 06:08:11 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 06:08:11 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 06:08:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:08:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:08:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:08:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:08:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:08:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:08:12 INFO - Downloading docopt-0.6.1.tar.gz 06:08:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:08:12 INFO - Installing collected packages: blobuploader, requests, docopt 06:08:12 INFO - Running setup.py install for blobuploader 06:08:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:08:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:08:12 INFO - Running setup.py install for requests 06:08:13 INFO - Running setup.py install for docopt 06:08:13 INFO - Successfully installed blobuploader requests docopt 06:08:13 INFO - Cleaning up... 06:08:13 INFO - Return code: 0 06:08:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:08:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:08:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:08:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:08:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:08:13 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 0x1079abc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079af768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb312491680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107701d30>, '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 0x1076cbcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076f2be8>, '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.O71XTs0EVW/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.07rO4RdVZg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/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 06:08:13 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 06:08:13 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 06:08:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O71XTs0EVW/Render', 06:08:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:08:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:08:13 INFO - 'HOME': '/Users/cltbld', 06:08:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:08:13 INFO - 'LOGNAME': 'cltbld', 06:08:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:08:13 INFO - 'MOZ_NO_REMOTE': '1', 06:08:13 INFO - 'NO_EM_RESTART': '1', 06:08:13 INFO - 'PAGER': '/bin/cat', 06:08:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:08:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:08:13 INFO - 'PWD': '/builds/slave/test', 06:08:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:08:13 INFO - 'SHELL': '/bin/bash', 06:08:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.07rO4RdVZg/Listeners', 06:08:13 INFO - 'TMPDIR': '/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/', 06:08:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:08:13 INFO - 'USER': 'cltbld', 06:08:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:08:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:08:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:08:13 INFO - 'XPC_FLAGS': '0x0', 06:08:13 INFO - 'XPC_SERVICE_NAME': '0', 06:08:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:08:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:08:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:08:13 INFO - Running setup.py (path:/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/pip-FxohU6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:08:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:08:13 INFO - Running setup.py (path:/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/pip-FLFQQW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:08:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:08:13 INFO - Running setup.py (path:/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/pip-uGDrXS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:08:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:08:13 INFO - Running setup.py (path:/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/pip-XvjdfJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:08:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:08:14 INFO - Running setup.py (path:/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/pip-b2DsrN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:08:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:08:14 INFO - Running setup.py (path:/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/pip-c5v2Fm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:08:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:08:14 INFO - Running setup.py (path:/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/pip-xifzyI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:08:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:08:14 INFO - Running setup.py (path:/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/pip-q8en_A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:08:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:08:14 INFO - Running setup.py (path:/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/pip-j7oBJq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:08:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:08:14 INFO - Running setup.py (path:/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/pip-lQPRAq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:08:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:08:14 INFO - Running setup.py (path:/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/pip-ykvHGS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:08:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:08:14 INFO - Running setup.py (path:/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/pip-pR9mhL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:08:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:08:14 INFO - Running setup.py (path:/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/pip-JFjYuC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:08:15 INFO - Running setup.py (path:/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/pip-bkWUqR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:08:15 INFO - Running setup.py (path:/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/pip-M9BVa0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:08:15 INFO - Running setup.py (path:/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/pip-1LfSba-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:08:15 INFO - Running setup.py (path:/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/pip-tqxS1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:08:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:08:15 INFO - Running setup.py install for manifestparser 06:08:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:08:15 INFO - Running setup.py install for mozcrash 06:08:15 INFO - Running setup.py install for mozdebug 06:08:15 INFO - Running setup.py install for mozdevice 06:08:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:08:16 INFO - Running setup.py install for mozfile 06:08:16 INFO - Running setup.py install for mozhttpd 06:08:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:08:16 INFO - Running setup.py install for mozinfo 06:08:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:08:16 INFO - Running setup.py install for mozInstall 06:08:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:08:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:08:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:08:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:08:16 INFO - Running setup.py install for mozleak 06:08:16 INFO - Running setup.py install for mozlog 06:08:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:08:16 INFO - Running setup.py install for moznetwork 06:08:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:08:17 INFO - Running setup.py install for mozprocess 06:08:17 INFO - Running setup.py install for mozprofile 06:08:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:08:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:08:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:08:17 INFO - Running setup.py install for mozrunner 06:08:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:08:17 INFO - Running setup.py install for mozscreenshot 06:08:17 INFO - Running setup.py install for moztest 06:08:17 INFO - Running setup.py install for mozversion 06:08:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:08:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:08:17 INFO - Cleaning up... 06:08:17 INFO - Return code: 0 06:08:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:08:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:08:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:08:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:08:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:08:18 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 0x1079abc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079af768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb312491680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107701d30>, '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 0x1076cbcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076f2be8>, '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.O71XTs0EVW/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.07rO4RdVZg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/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 06:08:18 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 06:08:18 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 06:08:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O71XTs0EVW/Render', 06:08:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:08:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:08:18 INFO - 'HOME': '/Users/cltbld', 06:08:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:08:18 INFO - 'LOGNAME': 'cltbld', 06:08:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:08:18 INFO - 'MOZ_NO_REMOTE': '1', 06:08:18 INFO - 'NO_EM_RESTART': '1', 06:08:18 INFO - 'PAGER': '/bin/cat', 06:08:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:08:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:08:18 INFO - 'PWD': '/builds/slave/test', 06:08:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:08:18 INFO - 'SHELL': '/bin/bash', 06:08:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.07rO4RdVZg/Listeners', 06:08:18 INFO - 'TMPDIR': '/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/', 06:08:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:08:18 INFO - 'USER': 'cltbld', 06:08:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:08:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:08:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:08:18 INFO - 'XPC_FLAGS': '0x0', 06:08:18 INFO - 'XPC_SERVICE_NAME': '0', 06:08:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:08:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:08:18 INFO - Running setup.py (path:/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/pip-QJRQBu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:08:18 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)) 06:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:08:18 INFO - Running setup.py (path:/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/pip-5kGw0k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:08:18 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)) 06:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:08:18 INFO - Running setup.py (path:/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/pip-WGlcL4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:08:18 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)) 06:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:08:18 INFO - Running setup.py (path:/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/pip-sTDHcg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:08:18 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)) 06:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:08:18 INFO - Running setup.py (path:/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/pip-6IQsry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:08:18 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)) 06:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:08:18 INFO - Running setup.py (path:/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/pip-F3q05n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:08:18 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)) 06:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:08:18 INFO - Running setup.py (path:/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/pip-U96WF5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:08:18 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)) 06:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:08:18 INFO - Running setup.py (path:/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/pip-V_CF0f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:08:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 06:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:08:19 INFO - Running setup.py (path:/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/pip-J5kBYY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:08:19 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)) 06:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:08:19 INFO - Running setup.py (path:/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/pip-yma1Xv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:08:19 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)) 06:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:08:19 INFO - Running setup.py (path:/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/pip-xg7zWs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:08:19 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)) 06:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:08:19 INFO - Running setup.py (path:/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/pip-0eWkuO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:08:19 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)) 06:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:08:19 INFO - Running setup.py (path:/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/pip-ucouxu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:08:19 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)) 06:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:08:19 INFO - Running setup.py (path:/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/pip-9ObXqv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:08:19 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)) 06:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:08:19 INFO - Running setup.py (path:/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/pip-ljv5jT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:08:19 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)) 06:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:08:19 INFO - Running setup.py (path:/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/pip-6xxGCM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:08:19 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)) 06:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:08:19 INFO - Running setup.py (path:/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/pip-O1atHN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:08:20 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)) 06:08:20 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)) 06:08:20 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)) 06:08:20 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)) 06:08:20 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)) 06:08:20 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)) 06:08:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:08:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:08:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:08:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:08:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:08:21 INFO - Downloading blessings-1.6.tar.gz 06:08:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:08:21 INFO - Installing collected packages: blessings 06:08:21 INFO - Running setup.py install for blessings 06:08:21 INFO - Successfully installed blessings 06:08:21 INFO - Cleaning up... 06:08:21 INFO - Return code: 0 06:08:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:08:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:08:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:08:22 INFO - Reading from file tmpfile_stdout 06:08:22 INFO - Current package versions: 06:08:22 INFO - blessings == 1.6 06:08:22 INFO - blobuploader == 1.2.4 06:08:22 INFO - docopt == 0.6.1 06:08:22 INFO - functools32 == 3.2.3-2 06:08:22 INFO - jsonschema == 2.5.1 06:08:22 INFO - manifestparser == 1.1 06:08:22 INFO - mozInstall == 1.12 06:08:22 INFO - mozcrash == 1.0 06:08:22 INFO - mozdebug == 0.1 06:08:22 INFO - mozdevice == 0.51 06:08:22 INFO - mozfile == 1.2 06:08:22 INFO - mozhttpd == 0.7 06:08:22 INFO - mozinfo == 0.10 06:08:22 INFO - mozleak == 0.1 06:08:22 INFO - mozlog == 3.4 06:08:22 INFO - moznetwork == 0.27 06:08:22 INFO - mozprocess == 0.25 06:08:22 INFO - mozprofile == 0.28 06:08:22 INFO - mozrunner == 6.13 06:08:22 INFO - mozscreenshot == 0.1 06:08:22 INFO - mozsystemmonitor == 0.3 06:08:22 INFO - moztest == 0.8 06:08:22 INFO - mozversion == 1.4 06:08:22 INFO - psutil == 3.1.1 06:08:22 INFO - requests == 1.2.3 06:08:22 INFO - wsgiref == 0.1.2 06:08:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:08:22 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')]} 06:08:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:08:22 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')]} 06:08:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:08:22 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 0x1079abc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079af768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb312491680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107701d30>, '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 0x1076cbcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076f2be8>, '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.O71XTs0EVW/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.07rO4RdVZg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/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 06:08:22 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 06:08:22 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 06:08:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O71XTs0EVW/Render', 06:08:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:08:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:08:22 INFO - 'HOME': '/Users/cltbld', 06:08:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:08:22 INFO - 'LOGNAME': 'cltbld', 06:08:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:08:22 INFO - 'MOZ_NO_REMOTE': '1', 06:08:22 INFO - 'NO_EM_RESTART': '1', 06:08:22 INFO - 'PAGER': '/bin/cat', 06:08:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:08:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:08:22 INFO - 'PWD': '/builds/slave/test', 06:08:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:08:22 INFO - 'SHELL': '/bin/bash', 06:08:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.07rO4RdVZg/Listeners', 06:08:22 INFO - 'TMPDIR': '/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/', 06:08:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:08:22 INFO - 'USER': 'cltbld', 06:08:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:08:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:08:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:08:22 INFO - 'XPC_FLAGS': '0x0', 06:08:22 INFO - 'XPC_SERVICE_NAME': '0', 06:08:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:08:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:08:22 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)) 06:08:22 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)) 06:08:22 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)) 06:08:22 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)) 06:08:22 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)) 06:08:22 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)) 06:08:22 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)) 06:08:22 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)) 06:08:22 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)) 06:08:22 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 06:08:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:08:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:08:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:08:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:08:23 INFO - Downloading marionette_driver-1.4.0.tar.gz 06:08:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 06:08:23 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)) 06:08:23 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)) 06:08:23 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)) 06:08:23 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)) 06:08:23 INFO - Installing collected packages: marionette-driver 06:08:23 INFO - Running setup.py install for marionette-driver 06:08:23 INFO - Successfully installed marionette-driver 06:08:23 INFO - Cleaning up... 06:08:24 INFO - Return code: 0 06:08:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:08: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')]} 06:08:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:08: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')]} 06:08:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:08:24 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 0x1079abc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079af768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb312491680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107701d30>, '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 0x1076cbcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076f2be8>, '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.O71XTs0EVW/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.07rO4RdVZg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/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 06:08:24 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 06:08:24 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 06:08:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O71XTs0EVW/Render', 06:08:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:08:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:08:24 INFO - 'HOME': '/Users/cltbld', 06:08:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:08:24 INFO - 'LOGNAME': 'cltbld', 06:08:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:08:24 INFO - 'MOZ_NO_REMOTE': '1', 06:08:24 INFO - 'NO_EM_RESTART': '1', 06:08:24 INFO - 'PAGER': '/bin/cat', 06:08:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:08:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:08:24 INFO - 'PWD': '/builds/slave/test', 06:08:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:08:24 INFO - 'SHELL': '/bin/bash', 06:08:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.07rO4RdVZg/Listeners', 06:08:24 INFO - 'TMPDIR': '/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/', 06:08:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:08:24 INFO - 'USER': 'cltbld', 06:08:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:08:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:08:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:08:24 INFO - 'XPC_FLAGS': '0x0', 06:08:24 INFO - 'XPC_SERVICE_NAME': '0', 06:08:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:08:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:08:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:08:24 INFO - Cleaning up... 06:08:24 INFO - Return code: 0 06:08:24 INFO - Running post-action listener: _resource_record_post_action 06:08:24 INFO - Running post-action listener: _start_resource_monitoring 06:08:24 INFO - Starting resource monitoring. 06:08:24 INFO - [mozharness: 2017-06-07 13:08:24.330231Z] Finished create-virtualenv step (success) 06:08:24 INFO - [mozharness: 2017-06-07 13:08:24.331212Z] Running install step. 06:08:24 INFO - Running pre-action listener: _resource_record_pre_action 06:08:24 INFO - Running main action method: install 06:08:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:08:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:08:24 INFO - Reading from file tmpfile_stdout 06:08:24 INFO - Detecting whether we're running mozinstall >=1.0... 06:08:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:08:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:08:24 INFO - Reading from file tmpfile_stdout 06:08:24 INFO - Output received: 06:08:24 INFO - Usage: mozinstall [options] installer 06:08:24 INFO - Options: 06:08:24 INFO - -h, --help show this help message and exit 06:08:24 INFO - -d DEST, --destination=DEST 06:08:24 INFO - Directory to install application into. [default: 06:08:24 INFO - "/builds/slave/test"] 06:08:24 INFO - --app=APP Application being installed. [default: firefox] 06:08:24 INFO - mkdir: /builds/slave/test/build/application 06:08:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:08:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 06:08:46 INFO - Reading from file tmpfile_stdout 06:08:46 INFO - Output received: 06:08:46 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:08:46 INFO - Running post-action listener: _resource_record_post_action 06:08:46 INFO - [mozharness: 2017-06-07 13:08:46.223539Z] Finished install step (success) 06:08:46 INFO - [mozharness: 2017-06-07 13:08:46.223660Z] Running setup-mitmproxy step. 06:08:46 INFO - Running pre-action listener: _resource_record_pre_action 06:08:46 INFO - Running main action method: setup_mitmproxy 06:08:46 INFO - Skipping: mitmproxy is not required 06:08:46 INFO - Running post-action listener: _resource_record_post_action 06:08:46 INFO - [mozharness: 2017-06-07 13:08:46.224353Z] Finished setup-mitmproxy step (success) 06:08:46 INFO - [mozharness: 2017-06-07 13:08:46.224491Z] Running run-tests step. 06:08:46 INFO - Running pre-action listener: _resource_record_pre_action 06:08:46 INFO - Running main action method: run_tests 06:08:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:08:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:08:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:08:46 INFO - 2017-06-07 06:08:46.239 screenresolution[1629:7434] starting screenresolution argv=screenresolution get 06:08:46 INFO - 2017-06-07 06:08:46.241 screenresolution[1629:7434] Display 0: 1600x1200x32@60 06:08:46 INFO - 2017-06-07 06:08:46.247 screenresolution[1630:7437] starting screenresolution argv=screenresolution list 06:08:46 INFO - Available Modes on Display 0 06:08:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:08:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:08:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:08:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:08:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:08:46 INFO - Intel Iris: 06:08:46 INFO - Chipset Model: Intel Iris 06:08:46 INFO - Type: GPU 06:08:46 INFO - Bus: Built-In 06:08:46 INFO - VRAM (Dynamic, Max): 1536 MB 06:08:46 INFO - Vendor: Intel (0x8086) 06:08:46 INFO - Device ID: 0x0a2e 06:08:46 INFO - Revision ID: 0x0009 06:08:46 INFO - Displays: 06:08:46 INFO - Display: 06:08:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:08:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:08:46 INFO - Display Serial Number: 1600x1200 60 06:08:46 INFO - Main Display: Yes 06:08:46 INFO - Mirror: Off 06:08:46 INFO - Online: Yes 06:08:46 INFO - Rotation: Supported 06:08:46 INFO - Return code: 0 06:08:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:08:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:08:46 INFO - Python 2.7.3 06:08:46 INFO - Return code: 0 06:08:46 INFO - grabbing minidump binary from tooltool 06:08:46 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')]} 06:08:46 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 0x107701d30>, '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 0x1076cbcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076f2be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:08:46 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 06:08:46 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 06:08:46 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 06:08:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:08:46 INFO - Return code: 0 06:08:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:08:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:08:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:08:46 INFO - ENV: RUST_BACKTRACE is now 1 06:08:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:08:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:08:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/N01XEjMNRXuO4FYowsEHHA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0091', '--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 06:08:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/N01XEjMNRXuO4FYowsEHHA/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0091 --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 06:08:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O71XTs0EVW/Render', 06:08:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:08:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:08:46 INFO - 'HOME': '/Users/cltbld', 06:08:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:08:46 INFO - 'LOGNAME': 'cltbld', 06:08:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:08:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:08:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:08:46 INFO - 'MOZ_NO_REMOTE': '1', 06:08:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:08:46 INFO - 'NO_EM_RESTART': '1', 06:08:46 INFO - 'PAGER': '/bin/cat', 06:08:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:08:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:08:46 INFO - 'PWD': '/builds/slave/test', 06:08:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:08:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:08:46 INFO - 'RUST_BACKTRACE': '1', 06:08:46 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 06:08:46 INFO - 'SHELL': '/bin/bash', 06:08:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.07rO4RdVZg/Listeners', 06:08:46 INFO - 'TMPDIR': '/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/', 06:08:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:08:46 INFO - 'USER': 'cltbld', 06:08:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:08:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:08:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:08:46 INFO - 'XPC_FLAGS': '0x0', 06:08:46 INFO - 'XPC_SERVICE_NAME': '0', 06:08:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:08:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/N01XEjMNRXuO4FYowsEHHA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0091', '--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 06:08:46 INFO - mozversion application_buildid: 20170607043658 06:08:46 INFO - mozversion application_changeset: f6320f91851c2cede08b0fb54a6d0525b870310e 06:08:46 INFO - mozversion application_display_name: Nightly 06:08:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:08:46 INFO - mozversion application_name: Firefox 06:08:46 INFO - mozversion application_remotingname: firefox 06:08:46 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:08:46 INFO - mozversion application_vendor: Mozilla 06:08:46 INFO - mozversion application_version: 55.0a1 06:08:46 INFO - mozversion platform_buildid: 20170607043658 06:08:46 INFO - mozversion platform_changeset: f6320f91851c2cede08b0fb54a6d0525b870310e 06:08:46 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:08:46 INFO - mozversion platform_version: 55.0a1 06:08:46 INFO - using testdate: 1496840926 06:08:46 INFO - actual date: 1496840926 06:08:46 INFO - starting webserver on 'localhost:49227' 06:08:46 INFO - SUITE-START | Running 2 tests 06:08:46 INFO - TEST-START | dromaeo_css 06:08:46 INFO - operating with platform_type : mac_ 06:08:46 INFO - Initialising browser for dromaeo_css test... 06:08:46 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground -profile /var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/tmphHz5lS/profile 06:08:46 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground) 06:09:21 INFO - TEST-INFO | 1640: killed by SIGTERM 06:09:21 INFO - Browser initialized. 06:09:21 INFO - Running cycle 1/1 for dromaeo_css test... 06:09:21 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/tmphHz5lS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:09:22 INFO - PID 1651 | 2017-06-07 06:09:22.779 plugin-container[1652:8020] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 06:09:22 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:09:22 INFO - PID 1651 | 2017-06-07 06:09:22.780 plugin-container[1652:8020] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa00b, name = 'com.apple.CFPasteboardClient' 06:09:22 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:09:22 INFO - PID 1651 | 2017-06-07 06:09:22.780 plugin-container[1652:8020] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:09:23 INFO - PID 1651 | 2017-06-07 06:09:23.607 plugin-container[1653:8066] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 06:09:23 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:09:23 INFO - PID 1651 | 2017-06-07 06:09:23.607 plugin-container[1653:8066] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4713, name = 'com.apple.CFPasteboardClient' 06:09:23 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:09:23 INFO - PID 1651 | 2017-06-07 06:09:23.607 plugin-container[1653:8066] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:09:32 INFO - PID 1651 | RSS: Main: 252755968 06:09:32 INFO - PID 1651 | 06:12:34 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49227/tests/dromaeo/cssquery-ext.html) 06:12:35 INFO - PID 1651 | RSS: Main: 262537216 06:12:35 INFO - PID 1651 | 06:15:38 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-ext.html (next: http://localhost:49227/tests/dromaeo/cssquery-jquery.html) 06:15:38 INFO - PID 1651 | RSS: Main: 275787776 06:15:38 INFO - PID 1651 | 06:18:40 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49227/tests/dromaeo/cssquery-mootools.html) 06:18:41 INFO - PID 1651 | RSS: Main: 276418560 06:18:41 INFO - PID 1651 | 06:21:42 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49227/tests/dromaeo/cssquery-prototype.html) 06:21:43 INFO - PID 1651 | RSS: Main: 279937024 06:21:43 INFO - PID 1651 | 06:24:46 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49227/tests/dromaeo/cssquery-yui.html) 06:24:47 INFO - PID 1651 | RSS: Main: 278999040 06:24:47 INFO - PID 1651 | 06:27:49 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-yui.html (next: http://localhost:49227/tests/dromaeo/cssquery-dojo.html) 06:27:49 INFO - PID 1651 | RSS: Main: 279810048 06:27:49 INFO - PID 1651 | 06:27:49 INFO - PID 1651 | __start_tp_report 06:27:49 INFO - PID 1651 | _x_x_mozilla_page_load 06:27:49 INFO - PID 1651 | _x_x_mozilla_page_load_details 06:27:49 INFO - PID 1651 | |i|pagename|runs| 06:27:49 INFO - PID 1651 | |0;dojo.html;2854.200823769345;3506.4915587239852;3548.8757893473735;3595.1012246938267;3595.478655594937;6083.78706745264;6100;6171.475424588915;6172.62964222147;6186;6119.076065312626;6127.601705889114;6139.355367686385;6163.537734669906;6188.535859810521;4980.626453016038;5210.592711153663;5329.040772660919;5352.57179425645;5372.516473517383;5470.671759694411;5500.779968801243;5503.147012213113;5503.174523821439;5546.500814926656;5323.547498462635;5327.866803329948;5371.811986580451;5406.40529541752;5425.267588875491;5404.621676482648;5428.4028756836715;5435.456454354572;5446.645968012106;5449.3188351456065;5376.462353764631;5405.918911216335;5409.404965453797;5416.729163541847;5420.756065977041;5280.52475277223;6118.847028824315;6195.31851496335;6231.781887633956;6260.78087266948;5475.890482190373;6371.808845734636;6423.261324947644;6505.577137486096;6508.25155107164;5843.737031833578;6357.950938095255;6641.86716265672;6667.533272670915;6673.39939405451;5409;6196.411340922605;6343.714532846033;6352.523560732963;6371.553991220617;6462.838429039311;6633.4693224541925;6709.396154346132;6776.491763117786;6779.017042528806;5668.858278543069;6325.841853953688;6357.586756860789;6361.4592759615025;6420.871582568416;6508.869822603521;6625.271220165778;6627.204735431779;6630.502712296597;6648;6309.369063093745;6531.0856480092825;6540.084388185658;6540.182477190351;6560.278369379364;6419.711112999927;6456.418922297016;6463.644499552475;6495.610263384212;6529.902051469232;5291.312129423227;5292.179712144623;5296.682199068068;5298.867528311823;5303.946960530422;4677.532246775295;4679.110968915895;4702.788374523155;4708.434987801417;4727.810887564528;6170.475509581646;6230.408111229426;6230.968845155745;6302.275238347557;6352.809415717536;6668.699908504129;6681.265060843303;6685;6690.2306234782645;6695.832604184934;6325.683715814191;6340.968295158495;6347.650879201688;6357.554971152067;6372.107904893319;5634.943650563442;5696.686682232434;5728.427157284322;5746.454086861741;5817.650940943557;6748.156480439945;6763.053172555846;6790.7623233186605;6795.7961261162245;6805.149356330458;6664.666766661744;6673.366030227121;6674.666266686743;6688.933110668831;6697.062411262525;6538.346165383424;6643.335666433318;6710.496712746466;6728.7308507659145;6784.93215067843;6487.837804054936;6502.837429064311;6571.868562628816;6576.0464732612545;6605.702743376463;6649.833754156185;6664.733410663519;6665.0668906352175;6666.033425153422;6678.933210667831;6315.368463153648;6348.396902294275;6383.648899310582;6391.360863913757;6400.135981642418;6426.839329016812;6477.4170324671;6479.935200647933;6482.384173503509;6484.254310754323;6264.749410023548;6350.269718982191;6361.332060133619;6362.936370636419;6404.103425520338;6250.4374606285655;6307.653079080695;6344.524160688059;6427.517936154901;6456.063870738811;6285.465735412358;6334.144890439732;6338.334474880256;6347.206599175103;6354.17395738551 06:27:49 INFO - PID 1651 | |1;ext.html;746.9103707555095;780.2197802197802;841.0538144587341;1258.4274155259352;1325.7878739401697;39093;40377.788666339984;40400.39399409011;40422.797886010565;40433.59566404335;40848.59151408485;41130.38304425428;41169.58830411695;41236.79381603095;41246.79376603113;7825.491343062697;7989.360851131895;8231.17688231178;8443.28232100269;8449.23956843884;8247.670093196264;8330.000399952014;8335.374846886478;8355;8375.120612335679;7424.703011879546;8294.751157465256;8299.792505187359;8368.037675667316;8386.245237928586;8279.917200828035;8318.292945099674;8348.123447038035;8349.03986041601;8382.49705017701;8184.345252379795;8235.629396677105;8283.95858020706;8285.710000149966;8309.086000539935;38303.42544861829;38523.80738096291;38599.42100868489;38728.61271387278;38745.61254387476;3578.051816268691;3582.42681171014;3607.9458808117897;3621.9094522637147;3623.239119784822;30740.2314942126;30800.537991930138;31124;31131.844340778378;31154.376912461852;2611.347163209198;2935.6183696119583;2963.748081413061;2976.5088760354733;3011.864466099031;245461.54538454567;246985.7650711753;248645;301504;302970;49634.255486167734;49753;49880.501194987584;49968.000639986996;49983;52514;52601;52612;52682.94634107373;52734.945301093765;13953;14009.369578368998;14238;14271.286435678174;14378.209198494184;11281.928216819553;11300.434978251054;11419.14356423255;11421.714457138472;11446;14120.011599187957;14138.717225655635;14155.50455734065;14155.50455734065;14320.57038288853;5630.23991761107;5793.681347525842;6172.4136207060255;6357.173567436296;6359.904601430982;912.5528491039342;949.7625593601599;981.7300242433388;1025.415513157508;1044.6709286574705;23734.881325593262;25069.122580709587;25155.62266566003;25160.119395821424;25169.748302517106;1088.9782204355868;1109.1958330210532;1132.6998345438465;1136.6249137784512;1144.187626784992;12193.817092743617;12782.296973666538;12828.615141545768;12871.163374380636;12902.548410805577;311246;313295;313506.4324678362;314237;314325;55695;55813;55985;56003.439965600635;56198.719006405525;1444.4583281269524;1456.6795305032877;1498.1760031982149;1504.8720858727131;1516.8558986896228;22156.88921555382;22553.548929021;22714.318570442912;24068.037278509364;24073;37570.87287381383;38290.80854595821;39529.80235098806;39682;39687.40468892911;196902;231158;234010;237233;239850;26538.7346126544;27839.721602784524;34162.829185854906;35152.29695406129;35479.467807982386;43096;44602.77698611486;44678.776606116764;44694.88262793456;44769.88075298144;23995.880020599787;28839;30155.849220753757;30805.537916930814;32150.19624509406;29002.71603243481;29851.709506677413;31584.6037495518;31771.046868593978;32003.879864204642;30601.387972239987;35542.111447214025;37958.810205948794;38490.845274641804;38778.4183237246;29187;30247.395052099277;30629.693703063575;31480.685193148693;31568.842155789072;19674.606507869477;20215.39353819388;25035.123770668666;25398.746012540378;25414.74585254198 06:27:49 INFO - PID 1651 | |2;jquery.html;418.54169684195807;428.0796287980839;434.7282948157402;435.24948099362865;438.9604935555797;18743.718844217314;21414.57170856584;22044.448888777788;22235.443518953678;24549.75450245497;23509;23559.4110147246;23955.640665390034;23959.760402395972;24023.039078436843;6246.750129994794;6343.175387199658;6357.872842543146;6389.1694079769795;6399.2640846302575;6266.154069200645;6274.529410294223;6314.7158377872875;6331.113644089831;6341.270753863296;5882.4705776480105;6125.908111378333;6137.202163718733;6197;6198.039303907902;6186.381361863822;6199.876002479948;6258.655773932432;6267.279262884758;6271.843203919939;5586.86032849178;5595.664260144364;5596.832095037114;5614;5618.719064046782;28743.42513149746;29603.70396296031;29771;29893.25266868324;30087.398252034836;2212.402651284144;2229.5206530595838;2244.6969659095976;2247.1123906057014;2247.494313779403;18172;18624.534386640305;18706.62586748271;18710.81289187118;18742.437726868215;2175.9564808703735;2218.389942765736;2229.108356657334;2241.8206543476645;2246.5394594108334;139567.60432395648;166546.16726916409;178653;192399.1140132913;214696;10694;12516.874831251753;13948.651283717785;13974.301284935713;14011.929940350234;17274.913625431793;17508.299668013136;17576.38482653101;17579.20893559793;17587.648247034987;9060.592273347584;9106.726798196065;9113.908860911439;9286.535673216447;9554.474503902213;8929.687460938965;8973.057828927998;8982.955085224532;9014.729558113268;9053.004169541346;7075.690997165438;7153.105861767279;7453.031105956191;8264.669413223404;8382.87425688615;2242.9663555046686;2260.208926875613;2263.728352597666;2270.002397889846;2415.6014257647644;935.3686261773275;959.7504648791364;963.5431889479956;995.5271246158017;1001.2540657210425;20391.796082039287;21384;21777.455563610718;22220.888955552156;22507.662385064235;978.505854543451;1004.5178314409125;1004.9849252261222;1024.144839059379;1024.6925922223304;8005.159458256916;11283.210175287815;11524.078073754079;11560.884391155982;11562.248453850472;435136;544193.5580644143;546786;554250;557008;144405;145316;146142.269288655;146813.26593366964;147060;979.0552117206787;1035.6892932120334;1045.947702614866;1109.7059279290995;1111.127764704719;22388;23233.88383058074;23977;24142.637860431758;24286.39284017843;66192.66903665451;66309.66845165743;66583.66708166429;66876;67846.9822952666;87993.1200687985;88454.55772721095;88499.55750221208;89140.21719565702;89543;10491.790164196826;10552.366857988545;10595.788084238118;10650.360978341325;10695;18406.987615681264;18524;18711.9708416033;18780.24879004879;18801.247950081844;15077.623059423602;15100.622484437978;15247.16140612277;15364.231788410758;15379;12956.935215323863;13331.66670833237;13383.933080334536;13923.386391463973;14094.436222550983;15134.848651513345;15578.610534736268;15599.29803158838;15753.842461575237;15755.975861569419;10432.68701938943;10519.474026298807;10555.736106597396;10574.15406767472;10628.309159904582;14712.190829504052;14918.552443426715;14993.2503374833;15071.623209419413;15212.391504340145 06:27:49 INFO - PID 1651 | |3;mootools.html;1304.3347892574786;1438.0293302021132;1445.8554144585544;1446.4214314274288;1464.6045567696722;11673.708157296072;11822.7635447291;12084.2145260558;12258.81611775823;12268.57060002899;11716.531338746461;11826.645200643972;11929.329893814873;11943.701407464818;11994.16040877139;2286.5655525450193;2320.7563205863394;2442.8412153210074;2595.948081038378;2603.4793041391704;2513.6355228491857;2625.4617803350266;2631.1317265302405;2634;2638.0766731643866;1886.783024948914;2049.333966460899;2224.8442609017375;2243.091547923094;2274.2608652188023;2278.4190031541934;2552.85121695236;2564.9102281420155;2574.6009368547907;2574.691037075563;2431.902723891042;2448.6327050942323;2498.7876030537427;2546.987265063681;2549.1205534090896;11761;11843.940780296129;11913.04695624348;11965.222260553122;11981.700457488545;6436.871262574722;6450.402965112562;6649.202095748493;6706.5640733352175;6710.42961348286;4172.332426811696;4195.349720793282;4277.957220427787;4280.400743895857;4303.827846886152;1219.5792451604182;1220.8962238209763;1223.2354778263584;1238.777020136375;1242.43469221504;15157.696846063018;15511;16599;16727.916360418243;16780.2448889799;5550.47270509301;5553.055980483327;5711.828645140652;5812.041013232769;5815.79644712433;12227.266364018187;12307.384630768425;12316.630501084803;12322.876771232173;12327.075469339656;6482.12491313676;6538.313477084837;6594.20869495654;6605.933940660628;6620.139381880421;5329.920051199235;5897.52819774417;6084.361142080106;6194.752209911643;6222.906656400158;3898.8245528951265;4436.121432524318;4532.0256144928335;4569.1547063793305;4597.954020459752;1270.764908491932;1294.6116165150418;1295.559509766684;1307.6338625184962;1318.162966516269;820.3724151024471;822.1696086952221;853.2107800284712;853.9402241843011;881.0528681667233;5663.292088488939;6422.165118534654;6518.250401203827;6614.371634694697;6791.981202819517;592.5141384065112;593.3769541980903;606.9241344831896;611.9388061193845;611.9474503853365;4189.6438802701505;4674.742889141062;4721.6694831362165;4756.904861902743;4940.160172770566;13658.180509169482;14325.92837035829;14832.777508337384;15153.242337883063;15200.935934484702;14614.342354593857;15139.318730657147;15147.54557363281;15154.16652084146;15351.616209594626;8274.793130171673;8429.157084291523;8441.282490988335;8456.2812160967;8456.492610443393;6233.812985610439;6256.718447669957;7231.783046508614;7757.844843103219;7818.765437036898;10374.844377334195;10831.458427078456;12652.114351995271;12731.172473789175;12846.743065138831;10922.726931826768;13212.603621891358;13251.602451926457;13263.66840828987;13424.261665608095;9615.471149086867;9799.069088436587;9808.460534670376;9813.705588832347;9823.754406139904;10515.158787296845;10749.193810464403;10838.512266948164;10902.78194436091;11041.227114101936;9073.13805188506;9287.48918809472;9315.813683726152;9359.06409359086;9401.34190606651;9148;9162.816743665222;9166;9218.26253899673;9233.538323083952;8233.135520770233;8262.42163048581;8342.207490288412;8357.791055223668;8381.455205411628;5996.940030599812;6136.23297087864;6264;6369.585976911486;6416.00551914454;6258.968705156445;6387.5209359297305;6487.481001519962;6559.672016399257;6596.439302659328 06:27:49 INFO - PID 1651 | |4;prototype.html;361.35317781171705;364.28599944109544;391.2761391425861;402.93351596986497;408.9509258888934;5049.798008079672;6091.908621370678;6310.337414571472;6486.805395838132;6497.6101433914;6284.465820405259;6397.784420960014;6403.295637479873;6414.679266036703;6425.582337148082;4501.437320334958;4895.167821470341;4897.681650692712;4908.87727806805;4914.877128071809;4914.803407863681;4931.605471562284;4946.332245146895;4949.356583644121;4964.255361695738;4897.216445368742;4915.778789954461;4918.5573298403315;4929.926051109218;4954.157793175169;4643.955110100201;4914.9262761058435;4915.631327650405;4917.016596680678;4931.432885218192;3854.3062248795204;4274.422952901349;4390.27560452525;4409.691321607489;4438.60052595268;5570.685442161907;6378.1503364024375;6387.680615969183;6393.474577541891;6430.453411460032;5224.555912747421;5308.52223299901;5871.765129394819;5884.645362431813;5899.174115623816;6433.678316084224;6554;6922.203946546161;6949.270326615733;6974.476914231403;4222.9020454682;4421.624161946238;4441.9111617767785;4872.293517439952;5061.785171558836;4855.3734498942895;6214.378562143795;7001.369876711069;7074.186468556079;7166.996620473138;4676.859694209179;4854.902901941941;4996.925660982906;5112.463191364853;5132.127538318495;3954;5221.242919776611;5268.552173065255;5274.498922602346;5280.260763493114;4955.4548999610015;5801.564882633735;5821.155932389865;5841.88316233673;5848.093545500455;5694.345150307685;6870;6879.89680154798;6893.20728116273;6910.2744211858035;1582.4698725926714;1792.4981005318532;1800.513861257453;1801.1354549816162;1804.3594523944073;1204.6687161030786;1241.9254844709346;1247.8502579690314;1252.8120781882608;1275.470679667946;4708.670393072452;5473.014857325719;5649.915251271234;6375.234971803413;6376.617402955838;7058;7073.575585464786;7076.646167691595;7094.964525177445;7117.252688467636;6284.497240220863;6285.308616052231;6326.5255105867245;6330.873382532323;6344.555881088279;3264.689854463822;3667.0832291927018;3670.6696397324367;3684.6683798458266;3703.2408356286896;7224.74713385029;7258.76600977835;7261.5280006799385;7267.30960558746;7286.599237041907;7252.642891060426;7274.345308922222;7280.6723697433745;7288.161861386008;7308.415326773846;6353.36466353361;6503.577267477599;7167.139943206745;7204.9279507204255;7206.531575447579;6211.2857021443115;6558.049082883051;7016.087908571853;7091.751788687578;7115.861462166029;5821.359650438364;6850;6964.8159812832955;6985.755498557526;7008;5647.774089036392;5773.769049237983;6420.780051790145;6470.773522926675;7230.530015548972;4537.931931020971;4914.262860570943;5102;5126.564242039469;5142.717150556755;6453;6563.737450501926;6994.76167626375;7055.294470553109;7080.079589653312;6773.035819612257;6791;6799.898001529882;6807.489438292049;6824.590524568542;6769;6770.93229067703;6786.251568114922;6790.388865002174;6793.354631310018;6628.734850606114;6631;6643.900341494785;6652.800415987528;6716.652763138787;5660.660360378391;5730.137705245895;6464.482841372773;6703.262641109572;6769.763058292936;6308.653024083719;6568.507361947969;6601.174853143357;6615.834604134935;6650.268470468342 06:27:49 INFO - PID 1651 | |5;yui.html;818.4393690322133;818.4884447220487;830.0495932157679;847.5803029924878;860.066827492171;16705.331786728533;16797.244124014418;16891;16987.490375288744;17023.40418085367;14745.115293082423;15324.080555166698;16073.83926160738;16209.108499032549;16308.429204977829;972.5234635028838;992.4888682328607;1001.8597396364515;1005.1104772276551;1007.1841808135433;1007.0785231513157;1007.531497853498;1008.2035192198191;1008.6923488336055;1009.0313299232745;1000.2847963705985;1046.5918291866178;1052.300220353464;1053.010170439788;1054.1830081686678;1048.444324508012;1049.1134990932649;1050.7845891592206;1051.532068229638;1051.9474026298692;767.386091127096;772.1660606544974;772.7372693284254;773.9535627862347;774.6978678315461;16578.419755308627;16670.249838757285;16693.248803803857;16703.91648041752;16772.07753573553;16927;17056.65886682272;17070.48788536346;17110.058946757923;17463.912680436642;14493.492727754583;14564.92717536416;14687;14721.852781472155;14729;3509.877154299613;3531.7880927144197;3543.4153364694794;3554.5556805399324;3560.661737134968;125457.37271313676;126627.20710272147;128007.99999999907;128065.7193428063;128219.71780282172;13001.089923705438;13339.399727012307;13379.53171638988;13413.463461461432;13580.320983950764;22569.548609028057;25454.618180727302;25579.104731334315;25871.353216169748;26709.599356009676;6628.5691430056895;7515.661795219228;7516.6993320267675;7518.436117291225;7519.699212031567;3534.452159915217;3570.1788588624763;3585.7489975701974;3592.425211966125;3612.7290453216115;4300.1399720056115;4489.416375871116;4517.819287228474;4524.5927866492175;4528.230200865795;790.253210715868;954.5322791831952;958.5925981457852;964.1997142371814;964.7973925475588;640.6258575355929;648.7405037984844;661.751843058853;668.6489592963636;671.3253180553512;11182.273152245078;12241.326727030098;12603.684907877376;12761.61715148547;13628.863711362957;631.9652419116901;635.199648442956;648.7567162314132;666.6433458099893;669.6450881033061;4436.3123715824095;4894.4616092230535;5099.617528685363;5208.921866172011;5488.121900495901;114248;139251;140087;141784.29107854396;143187.5681243195;117392.8260717399;119263;119829.40085299691;121417.39291303663;123608;100805;102761.63213817835;104999;108526;109574;10599.364038157735;11563.942180289045;11564.826527601925;12531;12752.4261408235;40496.58261960817;41190.9702257446;42003.57996419997;42269.78865105778;42312.576874230865;78773.21226787659;80037.5998120029;81958.59020704859;84718.15281847103;86239;16886.40897568579;17515.124243788014;17518.299268029645;17827.28690852403;17848.821511784718;18325.266989320276;18871.339503117324;18901.527461813566;19069;19078.904605476884;17166.570835729206;17439.651206976043;18416.81583184151;18466.538336541693;18545.35091271853;17227.483175504756;17570.912145439703;18068.457946261635;18201.27194912189;18276.45170644883;17068.914655426644;17374.652506949536;17499.38752143669;17499.650006999535;17593.472195834147;11487.425628718698;11764.88235117672;11960.282383056696;12112.273263604213;12491.812622810847;11359.75042745281;12437;12458.065645076475;13328.2669453181;13361 06:27:49 INFO - PID 1651 | __end_tp_report 06:27:49 INFO - PID 1651 | __start_cc_report 06:27:49 INFO - PID 1651 | _x_x_mozilla_cycle_collect,456 06:27:49 INFO - PID 1651 | __end_cc_report 06:27:49 INFO - PID 1651 | __startTimestamp1496842069356__endTimestamp 06:27:49 INFO - PID 1651 | 06:27:49 INFO - PID 1651 | ------- Summary: start ------- 06:27:49 INFO - PID 1651 | Number of tests: 6 06:27:49 INFO - PID 1651 | 06:27:49 INFO - PID 1651 | [#0] dojo.html Cycles:165 Average:6067.83 Median:6347.21 stddev:714.16 (11.3%) stddev-sans-first:670.38 06:27:49 INFO - PID 1651 | Values: 2854.2 3506.5 3548.9 3595.1 3595.5 6083.8 6100.0 6171.5 6172.6 6186.0 6119.1 6127.6 6139.4 6163.5 6188.5 4980.6 5210.6 5329.0 5352.6 5372.5 5470.7 5500.8 5503.1 5503.2 5546.5 5323.5 5327.9 5371.8 5406.4 5425.3 5404.6 5428.4 5435.5 5446.6 5449.3 5376.5 5405.9 5409.4 5416.7 5420.8 5280.5 6118.8 6195.3 6231.8 6260.8 5475.9 6371.8 6423.3 6505.6 6508.3 5843.7 6358.0 6641.9 6667.5 6673.4 5409.0 6196.4 6343.7 6352.5 6371.6 6462.8 6633.5 6709.4 6776.5 6779.0 5668.9 6325.8 6357.6 6361.5 6420.9 6508.9 6625.3 6627.2 6630.5 6648.0 6309.4 6531.1 6540.1 6540.2 6560.3 6419.7 6456.4 6463.6 6495.6 6529.9 5291.3 5292.2 5296.7 5298.9 5303.9 4677.5 4679.1 4702.8 4708.4 4727.8 6170.5 6230.4 6231.0 6302.3 6352.8 6668.7 6681.3 6685.0 6690.2 6695.8 6325.7 6341.0 6347.7 6357.6 6372.1 5634.9 5696.7 5728.4 5746.5 5817.7 6748.2 6763.1 6790.8 6795.8 6805.1 6664.7 6673.4 6674.7 6688.9 6697.1 6538.3 6643.3 6710.5 6728.7 6784.9 6487.8 6502.8 6571.9 6576.0 6605.7 6649.8 6664.7 6665.1 6666.0 6678.9 6315.4 6348.4 6383.6 6391.4 6400.1 6426.8 6477.4 6479.9 6482.4 6484.3 6264.7 6350.3 6361.3 6362.9 6404.1 6250.4 6307.7 6344.5 6427.5 6456.1 6285.5 6334.1 6338.3 6347.2 6354.2 06:27:49 INFO - PID 1651 | 06:27:49 INFO - PID 1651 | [#1] ext.html Cycles:180 Average:42877.00 Median:32077.04 stddev:71460.76 (222.8%) stddev-sans-first:71591.21 06:27:49 INFO - PID 1651 | Values: 746.9 780.2 841.1 1258.4 1325.8 39093.0 40377.8 40400.4 40422.8 40433.6 40848.6 41130.4 41169.6 41236.8 41246.8 7825.5 7989.4 8231.2 8443.3 8449.2 8247.7 8330.0 8335.4 8355.0 8375.1 7424.7 8294.8 8299.8 8368.0 8386.2 8279.9 8318.3 8348.1 8349.0 8382.5 8184.3 8235.6 8284.0 8285.7 8309.1 38303.4 38523.8 38599.4 38728.6 38745.6 3578.1 3582.4 3607.9 3621.9 3623.2 30740.2 30800.5 31124.0 31131.8 31154.4 2611.3 2935.6 2963.7 2976.5 3011.9 245461.5 246985.8 248645.0 301504.0 302970.0 49634.3 49753.0 49880.5 49968.0 49983.0 52514.0 52601.0 52612.0 52682.9 52734.9 13953.0 14009.4 14238.0 14271.3 14378.2 11281.9 11300.4 11419.1 11421.7 11446.0 14120.0 14138.7 14155.5 14155.5 14320.6 5630.2 5793.7 6172.4 6357.2 6359.9 912.6 949.8 981.7 1025.4 1044.7 23734.9 25069.1 25155.6 25160.1 25169.7 1089.0 1109.2 1132.7 1136.6 1144.2 12193.8 12782.3 12828.6 12871.2 12902.5 311246.0 313295.0 313506.4 314237.0 314325.0 55695.0 55813.0 55985.0 56003.4 56198.7 1444.5 1456.7 1498.2 1504.9 1516.9 22156.9 22553.5 22714.3 24068.0 24073.0 37570.9 38290.8 39529.8 39682.0 39687.4 196902.0 231158.0 234010.0 237233.0 239850.0 26538.7 27839.7 34162.8 35152.3 35479.5 43096.0 44602.8 44678.8 44694.9 44769.9 23995.9 28839.0 30155.8 30805.5 32150.2 29002.7 29851.7 31584.6 31771.0 32003.9 30601.4 35542.1 37958.8 38490.8 38778.4 29187.0 30247.4 30629.7 31480.7 31568.8 19674.6 20215.4 25035.1 25398.7 25414.7 06:27:49 INFO - PID 1651 | 06:27:49 INFO - PID 1651 | [#2] jquery.html Cycles:180 Average:37596.50 Median:2246.83 stddev:91748.15 (4083.5%) stddev-sans-first:91963.06 06:27:49 INFO - PID 1651 | Values: 418.5 428.1 434.7 435.2 439.0 18743.7 21414.6 22044.4 22235.4 24549.8 23509.0 23559.4 23955.6 23959.8 24023.0 6246.8 6343.2 6357.9 6389.2 6399.3 6266.2 6274.5 6314.7 6331.1 6341.3 5882.5 6125.9 6137.2 6197.0 6198.0 6186.4 6199.9 6258.7 6267.3 6271.8 5586.9 5595.7 5596.8 5614.0 5618.7 28743.4 29603.7 29771.0 29893.3 30087.4 2212.4 2229.5 2244.7 2247.1 2247.5 18172.0 18624.5 18706.6 18710.8 18742.4 2176.0 2218.4 2229.1 2241.8 2246.5 139567.6 166546.2 178653.0 192399.1 214696.0 10694.0 12516.9 13948.7 13974.3 14011.9 17274.9 17508.3 17576.4 17579.2 17587.6 9060.6 9106.7 9113.9 9286.5 9554.5 8929.7 8973.1 8983.0 9014.7 9053.0 7075.7 7153.1 7453.0 8264.7 8382.9 2243.0 2260.2 2263.7 2270.0 2415.6 935.4 959.8 963.5 995.5 1001.3 20391.8 21384.0 21777.5 22220.9 22507.7 978.5 1004.5 1005.0 1024.1 1024.7 8005.2 11283.2 11524.1 11560.9 11562.2 435136.0 544193.6 546786.0 554250.0 557008.0 144405.0 145316.0 146142.3 146813.3 147060.0 979.1 1035.7 1045.9 1109.7 1111.1 22388.0 23233.9 23977.0 24142.6 24286.4 66192.7 66309.7 66583.7 66876.0 67847.0 87993.1 88454.6 88499.6 89140.2 89543.0 10491.8 10552.4 10595.8 10650.4 10695.0 18407.0 18524.0 18712.0 18780.2 18801.2 15077.6 15100.6 15247.2 15364.2 15379.0 12956.9 13331.7 13383.9 13923.4 14094.4 15134.8 15578.6 15599.3 15753.8 15756.0 10432.7 10519.5 10555.7 10574.2 10628.3 14712.2 14918.6 14993.3 15071.6 15212.4 06:27:49 INFO - PID 1651 | 06:27:49 INFO - PID 1651 | [#3] mootools.html Cycles:180 Average:7119.57 Median:4035.58 stddev:4452.48 (110.3%) stddev-sans-first:4443.52 06:27:49 INFO - PID 1651 | Values: 1304.3 1438.0 1445.9 1446.4 1464.6 11673.7 11822.8 12084.2 12258.8 12268.6 11716.5 11826.6 11929.3 11943.7 11994.2 2286.6 2320.8 2442.8 2595.9 2603.5 2513.6 2625.5 2631.1 2634.0 2638.1 1886.8 2049.3 2224.8 2243.1 2274.3 2278.4 2552.9 2564.9 2574.6 2574.7 2431.9 2448.6 2498.8 2547.0 2549.1 11761.0 11843.9 11913.0 11965.2 11981.7 6436.9 6450.4 6649.2 6706.6 6710.4 4172.3 4195.3 4278.0 4280.4 4303.8 1219.6 1220.9 1223.2 1238.8 1242.4 15157.7 15511.0 16599.0 16727.9 16780.2 5550.5 5553.1 5711.8 5812.0 5815.8 12227.3 12307.4 12316.6 12322.9 12327.1 6482.1 6538.3 6594.2 6605.9 6620.1 5329.9 5897.5 6084.4 6194.8 6222.9 3898.8 4436.1 4532.0 4569.2 4598.0 1270.8 1294.6 1295.6 1307.6 1318.2 820.4 822.2 853.2 853.9 881.1 5663.3 6422.2 6518.3 6614.4 6792.0 592.5 593.4 606.9 611.9 611.9 4189.6 4674.7 4721.7 4756.9 4940.2 13658.2 14325.9 14832.8 15153.2 15200.9 14614.3 15139.3 15147.5 15154.2 15351.6 8274.8 8429.2 8441.3 8456.3 8456.5 6233.8 6256.7 7231.8 7757.8 7818.8 10374.8 10831.5 12652.1 12731.2 12846.7 10922.7 13212.6 13251.6 13263.7 13424.3 9615.5 9799.1 9808.5 9813.7 9823.8 10515.2 10749.2 10838.5 10902.8 11041.2 9073.1 9287.5 9315.8 9359.1 9401.3 9148.0 9162.8 9166.0 9218.3 9233.5 8233.1 8262.4 8342.2 8357.8 8381.5 5996.9 6136.2 6264.0 6369.6 6416.0 6259.0 6387.5 6487.5 6559.7 6596.4 06:27:49 INFO - PID 1651 | 06:27:49 INFO - PID 1651 | [#4] prototype.html Cycles:180 Average:5568.45 Median:6284.48 stddev:1663.35 (26.5%) stddev-sans-first:1621.45 06:27:49 INFO - PID 1651 | Values: 361.4 364.3 391.3 402.9 409.0 5049.8 6091.9 6310.3 6486.8 6497.6 6284.5 6397.8 6403.3 6414.7 6425.6 4501.4 4895.2 4897.7 4908.9 4914.9 4914.8 4931.6 4946.3 4949.4 4964.3 4897.2 4915.8 4918.6 4929.9 4954.2 4644.0 4914.9 4915.6 4917.0 4931.4 3854.3 4274.4 4390.3 4409.7 4438.6 5570.7 6378.2 6387.7 6393.5 6430.5 5224.6 5308.5 5871.8 5884.6 5899.2 6433.7 6554.0 6922.2 6949.3 6974.5 4222.9 4421.6 4441.9 4872.3 5061.8 4855.4 6214.4 7001.4 7074.2 7167.0 4676.9 4854.9 4996.9 5112.5 5132.1 3954.0 5221.2 5268.6 5274.5 5280.3 4955.5 5801.6 5821.2 5841.9 5848.1 5694.3 6870.0 6879.9 6893.2 6910.3 1582.5 1792.5 1800.5 1801.1 1804.4 1204.7 1241.9 1247.9 1252.8 1275.5 4708.7 5473.0 5649.9 6375.2 6376.6 7058.0 7073.6 7076.6 7095.0 7117.3 6284.5 6285.3 6326.5 6330.9 6344.6 3264.7 3667.1 3670.7 3684.7 3703.2 7224.7 7258.8 7261.5 7267.3 7286.6 7252.6 7274.3 7280.7 7288.2 7308.4 6353.4 6503.6 7167.1 7204.9 7206.5 6211.3 6558.0 7016.1 7091.8 7115.9 5821.4 6850.0 6964.8 6985.8 7008.0 5647.8 5773.8 6420.8 6470.8 7230.5 4537.9 4914.3 5102.0 5126.6 5142.7 6453.0 6563.7 6994.8 7055.3 7080.1 6773.0 6791.0 6799.9 6807.5 6824.6 6769.0 6770.9 6786.3 6790.4 6793.4 6628.7 6631.0 6643.9 6652.8 6716.7 5660.7 5730.1 6464.5 6703.3 6769.8 6308.7 6568.5 6601.2 6615.8 6650.3 06:27:49 INFO - PID 1651 | 06:27:49 INFO - PID 1651 | [#5] yui.html Cycles:180 Average:25073.54 Median:17301.07 stddev:37627.05 (217.5%) stddev-sans-first:37688.53 06:27:49 INFO - PID 1651 | Values: 818.4 818.5 830.0 847.6 860.1 16705.3 16797.2 16891.0 16987.5 17023.4 14745.1 15324.1 16073.8 16209.1 16308.4 972.5 992.5 1001.9 1005.1 1007.2 1007.1 1007.5 1008.2 1008.7 1009.0 1000.3 1046.6 1052.3 1053.0 1054.2 1048.4 1049.1 1050.8 1051.5 1051.9 767.4 772.2 772.7 774.0 774.7 16578.4 16670.2 16693.2 16703.9 16772.1 16927.0 17056.7 17070.5 17110.1 17463.9 14493.5 14564.9 14687.0 14721.9 14729.0 3509.9 3531.8 3543.4 3554.6 3560.7 125457.4 126627.2 128008.0 128065.7 128219.7 13001.1 13339.4 13379.5 13413.5 13580.3 22569.5 25454.6 25579.1 25871.4 26709.6 6628.6 7515.7 7516.7 7518.4 7519.7 3534.5 3570.2 3585.7 3592.4 3612.7 4300.1 4489.4 4517.8 4524.6 4528.2 790.3 954.5 958.6 964.2 964.8 640.6 648.7 661.8 668.6 671.3 11182.3 12241.3 12603.7 12761.6 13628.9 632.0 635.2 648.8 666.6 669.6 4436.3 4894.5 5099.6 5208.9 5488.1 114248.0 139251.0 140087.0 141784.3 143187.6 117392.8 119263.0 119829.4 121417.4 123608.0 100805.0 102761.6 104999.0 108526.0 109574.0 10599.4 11563.9 11564.8 12531.0 12752.4 40496.6 41191.0 42003.6 42269.8 42312.6 78773.2 80037.6 81958.6 84718.2 86239.0 16886.4 17515.1 17518.3 17827.3 17848.8 18325.3 18871.3 18901.5 19069.0 19078.9 17166.6 17439.7 18416.8 18466.5 18545.4 17227.5 17570.9 18068.5 18201.3 18276.5 17068.9 17374.7 17499.4 17499.7 17593.5 11487.4 11764.9 11960.3 12112.3 12491.8 11359.8 12437.0 12458.1 13328.3 13361.0 06:27:49 INFO - PID 1651 | -------- Summary: end -------- 06:27:49 INFO - PID 1651 | 06:27:49 INFO - TEST-INFO | 1651: exit 0 06:27:49 INFO - TEST-OK | dromaeo_css | took 1143113ms 06:27:49 INFO - TEST-START | kraken 06:27:49 INFO - operating with platform_type : mac_ 06:27:49 INFO - Initialising browser for kraken test... 06:27:50 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground -profile /var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/tmp8QsXOV/profile 06:27:50 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground) 06:28:23 INFO - TEST-INFO | 1660: killed by SIGTERM 06:28:23 INFO - Browser initialized. 06:28:23 INFO - Running cycle 1/1 for kraken test... 06:28:23 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/tmp8QsXOV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:28:26 INFO - PID 1664 | 2017-06-07 06:28:26.182 plugin-container[1665:10623] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x983f, name = 'com.apple.tsm.portname' 06:28:26 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:28:26 INFO - PID 1664 | 2017-06-07 06:28:26.182 plugin-container[1665:10623] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 06:28:26 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:28:26 INFO - PID 1664 | 2017-06-07 06:28:26.182 plugin-container[1665:10623] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:28:26 INFO - PID 1664 | 2017-06-07 06:28:26.670 plugin-container[1666:10669] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.tsm.portname' 06:28:26 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:28:26 INFO - PID 1664 | 2017-06-07 06:28:26.670 plugin-container[1666:10669] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4917, name = 'com.apple.CFPasteboardClient' 06:28:26 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:28:26 INFO - PID 1664 | 2017-06-07 06:28:26.670 plugin-container[1666:10669] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:28:36 INFO - PID 1664 | RSS: Main: 252809216 06:28:36 INFO - PID 1664 | 06:28:38 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection) 06:28:39 INFO - PID 1664 | RSS: Main: 265904128 06:28:39 INFO - PID 1664 | 06:28:42 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-dft) 06:28:42 INFO - PID 1664 | RSS: Main: 274571264 06:28:42 INFO - PID 1664 | 06:28:46 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-fft) 06:28:46 INFO - PID 1664 | RSS: Main: 277065728 06:28:46 INFO - PID 1664 | 06:28:48 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator) 06:28:49 INFO - PID 1664 | RSS: Main: 282742784 06:28:49 INFO - PID 1664 | 06:28:51 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:28:51 INFO - PID 1664 | RSS: Main: 284774400 06:28:51 INFO - PID 1664 | 06:28:54 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom) 06:28:55 INFO - PID 1664 | RSS: Main: 289222656 06:28:55 INFO - PID 1664 | 06:28:58 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate) 06:28:59 INFO - PID 1664 | RSS: Main: 290967552 06:28:59 INFO - PID 1664 | 06:29:01 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial) 06:29:02 INFO - PID 1664 | RSS: Main: 290562048 06:29:02 INFO - PID 1664 | 06:29:04 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:29:04 INFO - PID 1664 | RSS: Main: 289918976 06:29:04 INFO - PID 1664 | 06:29:06 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:29:07 INFO - PID 1664 | RSS: Main: 289845248 06:29:07 INFO - PID 1664 | 06:29:09 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:29:10 INFO - PID 1664 | RSS: Main: 289849344 06:29:10 INFO - PID 1664 | 06:29:13 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:29:13 INFO - PID 1664 | RSS: Main: 289845248 06:29:13 INFO - PID 1664 | 06:29:16 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:29:17 INFO - PID 1664 | RSS: Main: 289853440 06:29:17 INFO - PID 1664 | 06:29:19 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49227/tests/kraken/driver.html?testName=ai-astar) 06:29:19 INFO - PID 1664 | RSS: Main: 291500032 06:29:19 INFO - PID 1664 | 06:29:19 INFO - PID 1664 | __start_tp_report 06:29:19 INFO - PID 1664 | _x_x_mozilla_page_load 06:29:19 INFO - PID 1664 | _x_x_mozilla_page_load_details 06:29:19 INFO - PID 1664 | |i|pagename|runs| 06:29:19 INFO - PID 1664 | |0;ai-astar;105;114;116;114;115;115;115;115;117;112 06:29:19 INFO - PID 1664 | |1;audio-beat-detection;139;109;118;147;114;114;113;148;132;111 06:29:19 INFO - PID 1664 | |2;audio-dft;200;198;203;205;213;218;215;205;203;198 06:29:19 INFO - PID 1664 | |3;audio-fft;76;58;56;81;57;55;58;61;82;79 06:29:19 INFO - PID 1664 | |4;audio-oscillator;89;68;81;74;105;102;93;71;73;90 06:29:19 INFO - PID 1664 | |5;imaging-gaussian-blur;90;92;90;88;90;93;90;94;134;94 06:29:19 INFO - PID 1664 | |6;imaging-darkroom;97;97;97;96;97;97;106;120;96;98 06:29:19 INFO - PID 1664 | |7;imaging-desaturate;96;90;93;92;93;95;99;108;93;92 06:29:19 INFO - PID 1664 | |8;json-parse-financial;51;51;49;52;52;52;53;51;53;52 06:29:19 INFO - PID 1664 | |9;json-stringify-tinderbox;56;58;60;57;81;85;79;54;55;56 06:29:19 INFO - PID 1664 | |10;stanford-crypto-aes;91;107;110;104;83;86;90;85;111;90 06:29:19 INFO - PID 1664 | |11;stanford-crypto-ccm;142;136;143;162;158;138;138;141;136;138 06:29:19 INFO - PID 1664 | |12;stanford-crypto-pbkdf2;197;179;188;183;181;183;181;176;184;179 06:29:19 INFO - PID 1664 | |13;stanford-crypto-sha256-iterative;67;64;67;64;65;65;64;69;67;70 06:29:19 INFO - PID 1664 | __end_tp_report 06:29:19 INFO - PID 1664 | __start_cc_report 06:29:19 INFO - PID 1664 | _x_x_mozilla_cycle_collect,1198 06:29:19 INFO - PID 1664 | __end_cc_report 06:29:19 INFO - PID 1664 | __startTimestamp1496842159324__endTimestamp 06:29:19 INFO - PID 1664 | 06:29:19 INFO - PID 1664 | ------- Summary: start ------- 06:29:19 INFO - PID 1664 | Number of tests: 14 06:29:19 INFO - PID 1664 | 06:29:19 INFO - PID 1664 | [#0] ai-astar Cycles:10 Average:113.80 Median:115.00 stddev:3.36 (2.9%) stddev-sans-first:1.39 06:29:19 INFO - PID 1664 | Values: 105.0 114.0 116.0 114.0 115.0 115.0 115.0 115.0 117.0 112.0 06:29:19 INFO - PID 1664 | 06:29:19 INFO - PID 1664 | [#1] audio-beat-detection Cycles:10 Average:124.50 Median:125.00 stddev:15.43 (12.3%) stddev-sans-first:15.45 06:29:19 INFO - PID 1664 | Values: 139.0 109.0 118.0 147.0 114.0 114.0 113.0 148.0 132.0 111.0 06:29:19 INFO - PID 1664 | 06:29:19 INFO - PID 1664 | [#2] audio-dft Cycles:10 Average:205.80 Median:205.00 stddev:7.13 (3.5%) stddev-sans-first:7.25 06:29:19 INFO - PID 1664 | Values: 200.0 198.0 203.0 205.0 213.0 218.0 215.0 205.0 203.0 198.0 06:29:19 INFO - PID 1664 | 06:29:19 INFO - PID 1664 | [#3] audio-fft Cycles:10 Average:66.30 Median:68.50 stddev:11.57 (16.9%) stddev-sans-first:11.72 06:29:19 INFO - PID 1664 | Values: 76.0 58.0 56.0 81.0 57.0 55.0 58.0 61.0 82.0 79.0 06:29:19 INFO - PID 1664 | 06:29:19 INFO - PID 1664 | [#4] audio-oscillator Cycles:10 Average:84.60 Median:77.50 stddev:13.16 (17.0%) stddev-sans-first:13.86 06:29:19 INFO - PID 1664 | Values: 89.0 68.0 81.0 74.0 105.0 102.0 93.0 71.0 73.0 90.0 06:29:19 INFO - PID 1664 | 06:29:19 INFO - PID 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:95.50 Median:91.00 stddev:13.67 (15.0%) stddev-sans-first:14.36 06:29:19 INFO - PID 1664 | Values: 90.0 92.0 90.0 88.0 90.0 93.0 90.0 94.0 134.0 94.0 06:29:19 INFO - PID 1664 | 06:29:19 INFO - PID 1664 | [#6] imaging-darkroom Cycles:10 Average:100.10 Median:97.00 stddev:7.58 (7.8%) stddev-sans-first:7.95 06:29:19 INFO - PID 1664 | Values: 97.0 97.0 97.0 96.0 97.0 97.0 106.0 120.0 96.0 98.0 06:29:19 INFO - PID 1664 | 06:29:19 INFO - PID 1664 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:93.00 stddev:5.17 (5.6%) stddev-sans-first:5.48 06:29:19 INFO - PID 1664 | Values: 96.0 90.0 93.0 92.0 93.0 95.0 99.0 108.0 93.0 92.0 06:29:19 INFO - PID 1664 | 06:29:19 INFO - PID 1664 | [#8] json-parse-financial Cycles:10 Average:51.60 Median:52.00 stddev:1.17 (2.3%) stddev-sans-first:1.22 06:29:19 INFO - PID 1664 | Values: 51.0 51.0 49.0 52.0 52.0 52.0 53.0 51.0 53.0 52.0 06:29:19 INFO - PID 1664 | 06:29:19 INFO - PID 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:64.10 Median:59.00 stddev:12.31 (20.9%) stddev-sans-first:12.71 06:29:19 INFO - PID 1664 | Values: 56.0 58.0 60.0 57.0 81.0 85.0 79.0 54.0 55.0 56.0 06:29:19 INFO - PID 1664 | 06:29:19 INFO - PID 1664 | [#10] stanford-crypto-aes Cycles:10 Average:95.70 Median:85.50 stddev:11.02 (12.9%) stddev-sans-first:11.55 06:29:19 INFO - PID 1664 | Values: 91.0 107.0 110.0 104.0 83.0 86.0 90.0 85.0 111.0 90.0 06:29:19 INFO - PID 1664 | 06:29:19 INFO - PID 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:143.20 Median:141.50 stddev:9.21 (6.5%) stddev-sans-first:9.76 06:29:19 INFO - PID 1664 | Values: 142.0 136.0 143.0 162.0 158.0 138.0 138.0 141.0 136.0 138.0 06:29:19 INFO - PID 1664 | 06:29:19 INFO - PID 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.10 Median:183.00 stddev:5.88 (3.2%) stddev-sans-first:3.47 06:29:19 INFO - PID 1664 | Values: 197.0 179.0 188.0 183.0 181.0 183.0 181.0 176.0 184.0 179.0 06:29:19 INFO - PID 1664 | 06:29:19 INFO - PID 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:67.00 stddev:2.15 (3.2%) stddev-sans-first:2.26 06:29:19 INFO - PID 1664 | Values: 67.0 64.0 67.0 64.0 65.0 65.0 64.0 69.0 67.0 70.0 06:29:19 INFO - PID 1664 | -------- Summary: end -------- 06:29:19 INFO - PID 1664 | 06:29:20 INFO - TEST-INFO | 1664: exit 0 06:29:20 INFO - TEST-OK | kraken | took 90213ms 06:29:20 INFO - SUITE-END | took 1233s 06:29:20 INFO - Completed test suite (00:20:33) 06:29:20 INFO - javascript benchmark 06:29:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8082.703389542925, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2854.200823769345, 3506.4915587239852, 3548.8757893473735, 3595.1012246938267, 3595.478655594937, 6083.78706745264, 6100.0, 6171.475424588915, 6172.62964222147, 6186.0, 6119.076065312626, 6127.601705889114, 6139.355367686385, 6163.537734669906, 6188.535859810521, 4980.626453016038, 5210.592711153663, 5329.040772660919, 5352.57179425645, 5372.516473517383, 5470.671759694411, 5500.779968801243, 5503.147012213113, 5503.174523821439, 5546.500814926656, 5323.547498462635, 5327.866803329948, 5371.811986580451, 5406.40529541752, 5425.267588875491, 5404.621676482648, 5428.4028756836715, 5435.456454354572, 5446.645968012106, 5449.3188351456065, 5376.462353764631, 5405.918911216335, 5409.404965453797, 5416.729163541847, 5420.756065977041, 5280.52475277223, 6118.847028824315, 6195.31851496335, 6231.781887633956, 6260.78087266948, 5475.890482190373, 6371.808845734636, 6423.261324947644, 6505.577137486096, 6508.25155107164, 5843.737031833578, 6357.950938095255, 6641.86716265672, 6667.533272670915, 6673.39939405451, 5409.0, 6196.411340922605, 6343.714532846033, 6352.523560732963, 6371.553991220617, 6462.838429039311, 6633.4693224541925, 6709.396154346132, 6776.491763117786, 6779.017042528806, 5668.858278543069, 6325.841853953688, 6357.586756860789, 6361.4592759615025, 6420.871582568416, 6508.869822603521, 6625.271220165778, 6627.204735431779, 6630.502712296597, 6648.0, 6309.369063093745, 6531.0856480092825, 6540.084388185658, 6540.182477190351, 6560.278369379364, 6419.711112999927, 6456.418922297016, 6463.644499552475, 6495.610263384212, 6529.902051469232, 5291.312129423227, 5292.179712144623, 5296.682199068068, 5298.867528311823, 5303.946960530422, 4677.532246775295, 4679.110968915895, 4702.788374523155, 4708.434987801417, 4727.810887564528, 6170.475509581646, 6230.408111229426, 6230.968845155745, 6302.275238347557, 6352.809415717536, 6668.699908504129, 6681.265060843303, 6685.0, 6690.2306234782645, 6695.832604184934, 6325.683715814191, 6340.968295158495, 6347.650879201688, 6357.554971152067, 6372.107904893319, 5634.943650563442, 5696.686682232434, 5728.427157284322, 5746.454086861741, 5817.650940943557, 6748.156480439945, 6763.053172555846, 6790.7623233186605, 6795.7961261162245, 6805.149356330458, 6664.666766661744, 6673.366030227121, 6674.666266686743, 6688.933110668831, 6697.062411262525, 6538.346165383424, 6643.335666433318, 6710.496712746466, 6728.7308507659145, 6784.93215067843, 6487.837804054936, 6502.837429064311, 6571.868562628816, 6576.0464732612545, 6605.702743376463, 6649.833754156185, 6664.733410663519, 6665.0668906352175, 6666.033425153422, 6678.933210667831, 6315.368463153648, 6348.396902294275, 6383.648899310582, 6391.360863913757, 6400.135981642418, 6426.839329016812, 6477.4170324671, 6479.935200647933, 6482.384173503509, 6484.254310754323, 6264.749410023548, 6350.269718982191, 6361.332060133619, 6362.936370636419, 6404.103425520338, 6250.4374606285655, 6307.653079080695, 6344.524160688059, 6427.517936154901, 6456.063870738811, 6285.465735412358, 6334.144890439732, 6338.334474880256, 6347.206599175103, 6354.17395738551], "value": 6019.571792244795, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [746.9103707555095, 780.2197802197802, 841.0538144587341, 1258.4274155259352, 1325.7878739401697, 39093.0, 40377.788666339984, 40400.39399409011, 40422.797886010565, 40433.59566404335, 40848.59151408485, 41130.38304425428, 41169.58830411695, 41236.79381603095, 41246.79376603113, 7825.491343062697, 7989.360851131895, 8231.17688231178, 8443.28232100269, 8449.23956843884, 8247.670093196264, 8330.000399952014, 8335.374846886478, 8355.0, 8375.120612335679, 7424.703011879546, 8294.751157465256, 8299.792505187359, 8368.037675667316, 8386.245237928586, 8279.917200828035, 8318.292945099674, 8348.123447038035, 8349.03986041601, 8382.49705017701, 8184.345252379795, 8235.629396677105, 8283.95858020706, 8285.710000149966, 8309.086000539935, 38303.42544861829, 38523.80738096291, 38599.42100868489, 38728.61271387278, 38745.61254387476, 3578.051816268691, 3582.42681171014, 3607.9458808117897, 3621.9094522637147, 3623.239119784822, 30740.2314942126, 30800.537991930138, 31124.0, 31131.844340778378, 31154.376912461852, 2611.347163209198, 2935.6183696119583, 2963.748081413061, 2976.5088760354733, 3011.864466099031, 245461.54538454567, 246985.7650711753, 248645.0, 301504.0, 302970.0, 49634.255486167734, 49753.0, 49880.501194987584, 49968.000639986996, 49983.0, 52514.0, 52601.0, 52612.0, 52682.94634107373, 52734.945301093765, 13953.0, 14009.369578368998, 14238.0, 14271.286435678174, 14378.209198494184, 11281.928216819553, 11300.434978251054, 11419.14356423255, 11421.714457138472, 11446.0, 14120.011599187957, 14138.717225655635, 14155.50455734065, 14155.50455734065, 14320.57038288853, 5630.23991761107, 5793.681347525842, 6172.4136207060255, 6357.173567436296, 6359.904601430982, 912.5528491039342, 949.7625593601599, 981.7300242433388, 1025.415513157508, 1044.6709286574705, 23734.881325593262, 25069.122580709587, 25155.62266566003, 25160.119395821424, 25169.748302517106, 1088.9782204355868, 1109.1958330210532, 1132.6998345438465, 1136.6249137784512, 1144.187626784992, 12193.817092743617, 12782.296973666538, 12828.615141545768, 12871.163374380636, 12902.548410805577, 311246.0, 313295.0, 313506.4324678362, 314237.0, 314325.0, 55695.0, 55813.0, 55985.0, 56003.439965600635, 56198.719006405525, 1444.4583281269524, 1456.6795305032877, 1498.1760031982149, 1504.8720858727131, 1516.8558986896228, 22156.88921555382, 22553.548929021, 22714.318570442912, 24068.037278509364, 24073.0, 37570.87287381383, 38290.80854595821, 39529.80235098806, 39682.0, 39687.40468892911, 196902.0, 231158.0, 234010.0, 237233.0, 239850.0, 26538.7346126544, 27839.721602784524, 34162.829185854906, 35152.29695406129, 35479.467807982386, 43096.0, 44602.77698611486, 44678.776606116764, 44694.88262793456, 44769.88075298144, 23995.880020599787, 28839.0, 30155.849220753757, 30805.537916930814, 32150.19624509406, 29002.71603243481, 29851.709506677413, 31584.6037495518, 31771.046868593978, 32003.879864204642, 30601.387972239987, 35542.111447214025, 37958.810205948794, 38490.845274641804, 38778.4183237246, 29187.0, 30247.395052099277, 30629.693703063575, 31480.685193148693, 31568.842155789072, 19674.606507869477, 20215.39353819388, 25035.123770668666, 25398.746012540378, 25414.74585254198], "value": 17410.605691954, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [418.54169684195807, 428.0796287980839, 434.7282948157402, 435.24948099362865, 438.9604935555797, 18743.718844217314, 21414.57170856584, 22044.448888777788, 22235.443518953678, 24549.75450245497, 23509.0, 23559.4110147246, 23955.640665390034, 23959.760402395972, 24023.039078436843, 6246.750129994794, 6343.175387199658, 6357.872842543146, 6389.1694079769795, 6399.2640846302575, 6266.154069200645, 6274.529410294223, 6314.7158377872875, 6331.113644089831, 6341.270753863296, 5882.4705776480105, 6125.908111378333, 6137.202163718733, 6197.0, 6198.039303907902, 6186.381361863822, 6199.876002479948, 6258.655773932432, 6267.279262884758, 6271.843203919939, 5586.86032849178, 5595.664260144364, 5596.832095037114, 5614.0, 5618.719064046782, 28743.42513149746, 29603.70396296031, 29771.0, 29893.25266868324, 30087.398252034836, 2212.402651284144, 2229.5206530595838, 2244.6969659095976, 2247.1123906057014, 2247.494313779403, 18172.0, 18624.534386640305, 18706.62586748271, 18710.81289187118, 18742.437726868215, 2175.9564808703735, 2218.389942765736, 2229.108356657334, 2241.8206543476645, 2246.5394594108334, 139567.60432395648, 166546.16726916409, 178653.0, 192399.1140132913, 214696.0, 10694.0, 12516.874831251753, 13948.651283717785, 13974.301284935713, 14011.929940350234, 17274.913625431793, 17508.299668013136, 17576.38482653101, 17579.20893559793, 17587.648247034987, 9060.592273347584, 9106.726798196065, 9113.908860911439, 9286.535673216447, 9554.474503902213, 8929.687460938965, 8973.057828927998, 8982.955085224532, 9014.729558113268, 9053.004169541346, 7075.690997165438, 7153.105861767279, 7453.031105956191, 8264.669413223404, 8382.87425688615, 2242.9663555046686, 2260.208926875613, 2263.728352597666, 2270.002397889846, 2415.6014257647644, 935.3686261773275, 959.7504648791364, 963.5431889479956, 995.5271246158017, 1001.2540657210425, 20391.796082039287, 21384.0, 21777.455563610718, 22220.888955552156, 22507.662385064235, 978.505854543451, 1004.5178314409125, 1004.9849252261222, 1024.144839059379, 1024.6925922223304, 8005.159458256916, 11283.210175287815, 11524.078073754079, 11560.884391155982, 11562.248453850472, 435136.0, 544193.5580644143, 546786.0, 554250.0, 557008.0, 144405.0, 145316.0, 146142.269288655, 146813.26593366964, 147060.0, 979.0552117206787, 1035.6892932120334, 1045.947702614866, 1109.7059279290995, 1111.127764704719, 22388.0, 23233.88383058074, 23977.0, 24142.637860431758, 24286.39284017843, 66192.66903665451, 66309.66845165743, 66583.66708166429, 66876.0, 67846.9822952666, 87993.1200687985, 88454.55772721095, 88499.55750221208, 89140.21719565702, 89543.0, 10491.790164196826, 10552.366857988545, 10595.788084238118, 10650.360978341325, 10695.0, 18406.987615681264, 18524.0, 18711.9708416033, 18780.24879004879, 18801.247950081844, 15077.623059423602, 15100.622484437978, 15247.16140612277, 15364.231788410758, 15379.0, 12956.935215323863, 13331.66670833237, 13383.933080334536, 13923.386391463973, 14094.436222550983, 15134.848651513345, 15578.610534736268, 15599.29803158838, 15753.842461575237, 15755.975861569419, 10432.68701938943, 10519.474026298807, 10555.736106597396, 10574.15406767472, 10628.309159904582, 14712.190829504052, 14918.552443426715, 14993.2503374833, 15071.623209419413, 15212.391504340145], "value": 11298.622460664754, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1304.3347892574786, 1438.0293302021132, 1445.8554144585544, 1446.4214314274288, 1464.6045567696722, 11673.708157296072, 11822.7635447291, 12084.2145260558, 12258.81611775823, 12268.57060002899, 11716.531338746461, 11826.645200643972, 11929.329893814873, 11943.701407464818, 11994.16040877139, 2286.5655525450193, 2320.7563205863394, 2442.8412153210074, 2595.948081038378, 2603.4793041391704, 2513.6355228491857, 2625.4617803350266, 2631.1317265302405, 2634.0, 2638.0766731643866, 1886.783024948914, 2049.333966460899, 2224.8442609017375, 2243.091547923094, 2274.2608652188023, 2278.4190031541934, 2552.85121695236, 2564.9102281420155, 2574.6009368547907, 2574.691037075563, 2431.902723891042, 2448.6327050942323, 2498.7876030537427, 2546.987265063681, 2549.1205534090896, 11761.0, 11843.940780296129, 11913.04695624348, 11965.222260553122, 11981.700457488545, 6436.871262574722, 6450.402965112562, 6649.202095748493, 6706.5640733352175, 6710.42961348286, 4172.332426811696, 4195.349720793282, 4277.957220427787, 4280.400743895857, 4303.827846886152, 1219.5792451604182, 1220.8962238209763, 1223.2354778263584, 1238.777020136375, 1242.43469221504, 15157.696846063018, 15511.0, 16599.0, 16727.916360418243, 16780.2448889799, 5550.47270509301, 5553.055980483327, 5711.828645140652, 5812.041013232769, 5815.79644712433, 12227.266364018187, 12307.384630768425, 12316.630501084803, 12322.876771232173, 12327.075469339656, 6482.12491313676, 6538.313477084837, 6594.20869495654, 6605.933940660628, 6620.139381880421, 5329.920051199235, 5897.52819774417, 6084.361142080106, 6194.752209911643, 6222.906656400158, 3898.8245528951265, 4436.121432524318, 4532.0256144928335, 4569.1547063793305, 4597.954020459752, 1270.764908491932, 1294.6116165150418, 1295.559509766684, 1307.6338625184962, 1318.162966516269, 820.3724151024471, 822.1696086952221, 853.2107800284712, 853.9402241843011, 881.0528681667233, 5663.292088488939, 6422.165118534654, 6518.250401203827, 6614.371634694697, 6791.981202819517, 592.5141384065112, 593.3769541980903, 606.9241344831896, 611.9388061193845, 611.9474503853365, 4189.6438802701505, 4674.742889141062, 4721.6694831362165, 4756.904861902743, 4940.160172770566, 13658.180509169482, 14325.92837035829, 14832.777508337384, 15153.242337883063, 15200.935934484702, 14614.342354593857, 15139.318730657147, 15147.54557363281, 15154.16652084146, 15351.616209594626, 8274.793130171673, 8429.157084291523, 8441.282490988335, 8456.2812160967, 8456.492610443393, 6233.812985610439, 6256.718447669957, 7231.783046508614, 7757.844843103219, 7818.765437036898, 10374.844377334195, 10831.458427078456, 12652.114351995271, 12731.172473789175, 12846.743065138831, 10922.726931826768, 13212.603621891358, 13251.602451926457, 13263.66840828987, 13424.261665608095, 9615.471149086867, 9799.069088436587, 9808.460534670376, 9813.705588832347, 9823.754406139904, 10515.158787296845, 10749.193810464403, 10838.512266948164, 10902.78194436091, 11041.227114101936, 9073.13805188506, 9287.48918809472, 9315.813683726152, 9359.06409359086, 9401.34190606651, 9148.0, 9162.816743665222, 9166.0, 9218.26253899673, 9233.538323083952, 8233.135520770233, 8262.42163048581, 8342.207490288412, 8357.791055223668, 8381.455205411628, 5996.940030599812, 6136.23297087864, 6264.0, 6369.585976911486, 6416.00551914454, 6258.968705156445, 6387.5209359297305, 6487.481001519962, 6559.672016399257, 6596.439302659328], "value": 5348.587913333726, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.35317781171705, 364.28599944109544, 391.2761391425861, 402.93351596986497, 408.9509258888934, 5049.798008079672, 6091.908621370678, 6310.337414571472, 6486.805395838132, 6497.6101433914, 6284.465820405259, 6397.784420960014, 6403.295637479873, 6414.679266036703, 6425.582337148082, 4501.437320334958, 4895.167821470341, 4897.681650692712, 4908.87727806805, 4914.877128071809, 4914.803407863681, 4931.605471562284, 4946.332245146895, 4949.356583644121, 4964.255361695738, 4897.216445368742, 4915.778789954461, 4918.5573298403315, 4929.926051109218, 4954.157793175169, 4643.955110100201, 4914.9262761058435, 4915.631327650405, 4917.016596680678, 4931.432885218192, 3854.3062248795204, 4274.422952901349, 4390.27560452525, 4409.691321607489, 4438.60052595268, 5570.685442161907, 6378.1503364024375, 6387.680615969183, 6393.474577541891, 6430.453411460032, 5224.555912747421, 5308.52223299901, 5871.765129394819, 5884.645362431813, 5899.174115623816, 6433.678316084224, 6554.0, 6922.203946546161, 6949.270326615733, 6974.476914231403, 4222.9020454682, 4421.624161946238, 4441.9111617767785, 4872.293517439952, 5061.785171558836, 4855.3734498942895, 6214.378562143795, 7001.369876711069, 7074.186468556079, 7166.996620473138, 4676.859694209179, 4854.902901941941, 4996.925660982906, 5112.463191364853, 5132.127538318495, 3954.0, 5221.242919776611, 5268.552173065255, 5274.498922602346, 5280.260763493114, 4955.4548999610015, 5801.564882633735, 5821.155932389865, 5841.88316233673, 5848.093545500455, 5694.345150307685, 6870.0, 6879.89680154798, 6893.20728116273, 6910.2744211858035, 1582.4698725926714, 1792.4981005318532, 1800.513861257453, 1801.1354549816162, 1804.3594523944073, 1204.6687161030786, 1241.9254844709346, 1247.8502579690314, 1252.8120781882608, 1275.470679667946, 4708.670393072452, 5473.014857325719, 5649.915251271234, 6375.234971803413, 6376.617402955838, 7058.0, 7073.575585464786, 7076.646167691595, 7094.964525177445, 7117.252688467636, 6284.497240220863, 6285.308616052231, 6326.5255105867245, 6330.873382532323, 6344.555881088279, 3264.689854463822, 3667.0832291927018, 3670.6696397324367, 3684.6683798458266, 3703.2408356286896, 7224.74713385029, 7258.76600977835, 7261.5280006799385, 7267.30960558746, 7286.599237041907, 7252.642891060426, 7274.345308922222, 7280.6723697433745, 7288.161861386008, 7308.415326773846, 6353.36466353361, 6503.577267477599, 7167.139943206745, 7204.9279507204255, 7206.531575447579, 6211.2857021443115, 6558.049082883051, 7016.087908571853, 7091.751788687578, 7115.861462166029, 5821.359650438364, 6850.0, 6964.8159812832955, 6985.755498557526, 7008.0, 5647.774089036392, 5773.769049237983, 6420.780051790145, 6470.773522926675, 7230.530015548972, 4537.931931020971, 4914.262860570943, 5102.0, 5126.564242039469, 5142.717150556755, 6453.0, 6563.737450501926, 6994.76167626375, 7055.294470553109, 7080.079589653312, 6773.035819612257, 6791.0, 6799.898001529882, 6807.489438292049, 6824.590524568542, 6769.0, 6770.93229067703, 6786.251568114922, 6790.388865002174, 6793.354631310018, 6628.734850606114, 6631.0, 6643.900341494785, 6652.800415987528, 6716.652763138787, 5660.660360378391, 5730.137705245895, 6464.482841372773, 6703.262641109572, 6769.763058292936, 6308.653024083719, 6568.507361947969, 6601.174853143357, 6615.834604134935, 6650.268470468342], "value": 5056.320595137707, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [818.4393690322133, 818.4884447220487, 830.0495932157679, 847.5803029924878, 860.066827492171, 16705.331786728533, 16797.244124014418, 16891.0, 16987.490375288744, 17023.40418085367, 14745.115293082423, 15324.080555166698, 16073.83926160738, 16209.108499032549, 16308.429204977829, 972.5234635028838, 992.4888682328607, 1001.8597396364515, 1005.1104772276551, 1007.1841808135433, 1007.0785231513157, 1007.531497853498, 1008.2035192198191, 1008.6923488336055, 1009.0313299232745, 1000.2847963705985, 1046.5918291866178, 1052.300220353464, 1053.010170439788, 1054.1830081686678, 1048.444324508012, 1049.1134990932649, 1050.7845891592206, 1051.532068229638, 1051.9474026298692, 767.386091127096, 772.1660606544974, 772.7372693284254, 773.9535627862347, 774.6978678315461, 16578.419755308627, 16670.249838757285, 16693.248803803857, 16703.91648041752, 16772.07753573553, 16927.0, 17056.65886682272, 17070.48788536346, 17110.058946757923, 17463.912680436642, 14493.492727754583, 14564.92717536416, 14687.0, 14721.852781472155, 14729.0, 3509.877154299613, 3531.7880927144197, 3543.4153364694794, 3554.5556805399324, 3560.661737134968, 125457.37271313676, 126627.20710272147, 128007.99999999907, 128065.7193428063, 128219.71780282172, 13001.089923705438, 13339.399727012307, 13379.53171638988, 13413.463461461432, 13580.320983950764, 22569.548609028057, 25454.618180727302, 25579.104731334315, 25871.353216169748, 26709.599356009676, 6628.5691430056895, 7515.661795219228, 7516.6993320267675, 7518.436117291225, 7519.699212031567, 3534.452159915217, 3570.1788588624763, 3585.7489975701974, 3592.425211966125, 3612.7290453216115, 4300.1399720056115, 4489.416375871116, 4517.819287228474, 4524.5927866492175, 4528.230200865795, 790.253210715868, 954.5322791831952, 958.5925981457852, 964.1997142371814, 964.7973925475588, 640.6258575355929, 648.7405037984844, 661.751843058853, 668.6489592963636, 671.3253180553512, 11182.273152245078, 12241.326727030098, 12603.684907877376, 12761.61715148547, 13628.863711362957, 631.9652419116901, 635.199648442956, 648.7567162314132, 666.6433458099893, 669.6450881033061, 4436.3123715824095, 4894.4616092230535, 5099.617528685363, 5208.921866172011, 5488.121900495901, 114248.0, 139251.0, 140087.0, 141784.29107854396, 143187.5681243195, 117392.8260717399, 119263.0, 119829.40085299691, 121417.39291303663, 123608.0, 100805.0, 102761.63213817835, 104999.0, 108526.0, 109574.0, 10599.364038157735, 11563.942180289045, 11564.826527601925, 12531.0, 12752.4261408235, 40496.58261960817, 41190.9702257446, 42003.57996419997, 42269.78865105778, 42312.576874230865, 78773.21226787659, 80037.5998120029, 81958.59020704859, 84718.15281847103, 86239.0, 16886.40897568579, 17515.124243788014, 17518.299268029645, 17827.28690852403, 17848.821511784718, 18325.266989320276, 18871.339503117324, 18901.527461813566, 19069.0, 19078.904605476884, 17166.570835729206, 17439.651206976043, 18416.81583184151, 18466.538336541693, 18545.35091271853, 17227.483175504756, 17570.912145439703, 18068.457946261635, 18201.27194912189, 18276.45170644883, 17068.914655426644, 17374.652506949536, 17499.38752143669, 17499.650006999535, 17593.472195834147, 11487.425628718698, 11764.88235117672, 11960.282383056696, 12112.273263604213, 12491.812622810847, 11359.75042745281, 12437.0, 12458.065645076475, 13328.2669453181, 13361.0], "value": 8706.247883830238, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1489.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 114.0, 116.0, 114.0, 115.0, 115.0, 115.0, 115.0, 117.0, 112.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 109.0, 118.0, 147.0, 114.0, 114.0, 113.0, 148.0, 132.0, 111.0], "value": 124.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 198.0, 203.0, 205.0, 213.0, 218.0, 215.0, 205.0, 203.0, 198.0], "value": 205.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 58.0, 56.0, 81.0, 57.0, 55.0, 58.0, 61.0, 82.0, 79.0], "value": 66.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 68.0, 81.0, 74.0, 105.0, 102.0, 93.0, 71.0, 73.0, 90.0], "value": 84.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 92.0, 90.0, 88.0, 90.0, 93.0, 90.0, 94.0, 134.0, 94.0], "value": 95.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 97.0, 96.0, 97.0, 97.0, 106.0, 120.0, 96.0, 98.0], "value": 100.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 90.0, 93.0, 92.0, 93.0, 95.0, 99.0, 108.0, 93.0, 92.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 49.0, 52.0, 52.0, 52.0, 53.0, 51.0, 53.0, 52.0], "value": 51.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 60.0, 57.0, 81.0, 85.0, 79.0, 54.0, 55.0, 56.0], "value": 64.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 107.0, 110.0, 104.0, 83.0, 86.0, 90.0, 85.0, 111.0, 90.0], "value": 95.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 136.0, 143.0, 162.0, 158.0, 138.0, 138.0, 141.0, 136.0, 138.0], "value": 143.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 179.0, 188.0, 183.0, 181.0, 183.0, 181.0, 176.0, 184.0, 179.0], "value": 183.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 64.0, 67.0, 64.0, 65.0, 65.0, 64.0, 69.0, 67.0, 70.0], "value": 66.2, "unit": "score"}]}]} 06:29:20 INFO - Return code: 0 06:29:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:29:20 INFO - # TBPL SUCCESS # 06:29:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:29:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:29:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:29:20 INFO - 2017-06-07 06:29:20.705 screenresolution[1672:11053] starting screenresolution argv=screenresolution get 06:29:20 INFO - 2017-06-07 06:29:20.708 screenresolution[1672:11053] Display 0: 1600x1200x32@60 06:29:20 INFO - 2017-06-07 06:29:20.713 screenresolution[1673:11056] starting screenresolution argv=screenresolution list 06:29:20 INFO - Available Modes on Display 0 06:29:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:29:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:29:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:29:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:29:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:29:20 INFO - Intel Iris: 06:29:20 INFO - Chipset Model: Intel Iris 06:29:20 INFO - Type: GPU 06:29:20 INFO - Bus: Built-In 06:29:20 INFO - VRAM (Dynamic, Max): 1536 MB 06:29:20 INFO - Vendor: Intel (0x8086) 06:29:20 INFO - Device ID: 0x0a2e 06:29:20 INFO - Revision ID: 0x0009 06:29:20 INFO - Displays: 06:29:20 INFO - Display: 06:29:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:29:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:29:20 INFO - Display Serial Number: 1600x1200 60 06:29:20 INFO - Main Display: Yes 06:29:20 INFO - Mirror: Off 06:29:20 INFO - Online: Yes 06:29:20 INFO - Rotation: Supported 06:29:20 INFO - Return code: 0 06:29:20 INFO - Running post-action listener: _resource_record_post_action 06:29:20 INFO - [mozharness: 2017-06-07 13:29:20.950215Z] Finished run-tests step (success) 06:29:20 INFO - Running post-run listener: _resource_record_post_run 06:29:21 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 39136256; Write bytes: 569662976; Read time: 403; Write time: 1792 06:29:21 INFO - TinderboxPrint: CPU usage
53.1% 06:29:21 INFO - TinderboxPrint: I/O read bytes / time
39,136,256 / 403 06:29:21 INFO - TinderboxPrint: I/O write bytes / time
569,662,976 / 1,792 06:29:21 INFO - TinderboxPrint: CPU idle
2,354.8 (46.9%) 06:29:21 INFO - TinderboxPrint: CPU system
1,011.3 (20.1%) 06:29:21 INFO - TinderboxPrint: CPU user
1,659.5 (33.0%) 06:29:21 INFO - TinderboxPrint: Swap in / out
300,253,184 / 0 06:29:21 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 229190144; Write bytes: 220689920; Read time: 16749; Write time: 507 06:29:21 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 06:29:21 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 38173696; Write bytes: 320661504; Read time: 387; Write time: 1218 06:29:21 INFO - Running post-run listener: _upload_blobber_files 06:29:21 INFO - Blob upload gear active. 06:29:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:29:21 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 06:29:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:29:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:29:21 INFO - (blobuploader) - INFO - Open directory for files ... 06:29:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:29:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:29:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:29:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:29:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:29:22 INFO - (blobuploader) - INFO - Done attempting. 06:29:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:29:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:29:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:29:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:29:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:29:23 INFO - (blobuploader) - INFO - Done attempting. 06:29:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:29:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:29:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:29:24 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:29:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:29:24 INFO - (blobuploader) - INFO - Done attempting. 06:29:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:29:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:29:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:29:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:29:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:29:26 INFO - (blobuploader) - INFO - Done attempting. 06:29:26 INFO - (blobuploader) - INFO - Iteration through files over. 06:29:26 INFO - Return code: 0 06:29:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:29:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:29:26 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca462123cd4a915207c896b80669060f45a06392de940e3447b9e67a006d4246d3cb5cbf4fc78f2b223f39d5a8a34a4dafa898306c5a8dca2062581a24b9e7ce", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b32b45277cc1e7378e1f6811c2094934e6eef95a74846f57ada659025dd0d8ec35fa7c8496500d498fca44c4a340a509553a4d3e58ba42ae72bc09ee7a9534bd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c8285211323204693a03017b2967183319408961ecc39e36b484d26b34d44be01111de3dddadc9d498f0765a4bc1abc2563c227ede00c54116c57bcb52d451d8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/28755537e5300278c3c98517bb47296e7f569337dec5faeaa8398cc79e5db76fec61190d4e7d3b9574af729cf95cfc3475bb1ffa7f91f89720384a0c1c4d3bd4"} 06:29:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:29:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:29:26 INFO - Contents: 06:29:26 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca462123cd4a915207c896b80669060f45a06392de940e3447b9e67a006d4246d3cb5cbf4fc78f2b223f39d5a8a34a4dafa898306c5a8dca2062581a24b9e7ce", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b32b45277cc1e7378e1f6811c2094934e6eef95a74846f57ada659025dd0d8ec35fa7c8496500d498fca44c4a340a509553a4d3e58ba42ae72bc09ee7a9534bd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c8285211323204693a03017b2967183319408961ecc39e36b484d26b34d44be01111de3dddadc9d498f0765a4bc1abc2563c227ede00c54116c57bcb52d451d8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/28755537e5300278c3c98517bb47296e7f569337dec5faeaa8398cc79e5db76fec61190d4e7d3b9574af729cf95cfc3475bb1ffa7f91f89720384a0c1c4d3bd4"} 06:29:26 INFO - Running post-run listener: copy_logs_to_upload_dir 06:29:26 INFO - Copying logs to upload dir... 06:29:26 INFO - mkdir: /builds/slave/test/build/upload/logs 06:29:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1337.877070 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-06-07 06:29:26.457689) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-07 06:29:26.460971) ========= 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.O71XTs0EVW/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.07rO4RdVZg/Listeners TMPDIR=/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca462123cd4a915207c896b80669060f45a06392de940e3447b9e67a006d4246d3cb5cbf4fc78f2b223f39d5a8a34a4dafa898306c5a8dca2062581a24b9e7ce", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b32b45277cc1e7378e1f6811c2094934e6eef95a74846f57ada659025dd0d8ec35fa7c8496500d498fca44c4a340a509553a4d3e58ba42ae72bc09ee7a9534bd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c8285211323204693a03017b2967183319408961ecc39e36b484d26b34d44be01111de3dddadc9d498f0765a4bc1abc2563c227ede00c54116c57bcb52d451d8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/28755537e5300278c3c98517bb47296e7f569337dec5faeaa8398cc79e5db76fec61190d4e7d3b9574af729cf95cfc3475bb1ffa7f91f89720384a0c1c4d3bd4"} build_url:https://queue.taskcluster.net/v1/task/N01XEjMNRXuO4FYowsEHHA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011114 build_url: 'https://queue.taskcluster.net/v1/task/N01XEjMNRXuO4FYowsEHHA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca462123cd4a915207c896b80669060f45a06392de940e3447b9e67a006d4246d3cb5cbf4fc78f2b223f39d5a8a34a4dafa898306c5a8dca2062581a24b9e7ce", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b32b45277cc1e7378e1f6811c2094934e6eef95a74846f57ada659025dd0d8ec35fa7c8496500d498fca44c4a340a509553a4d3e58ba42ae72bc09ee7a9534bd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c8285211323204693a03017b2967183319408961ecc39e36b484d26b34d44be01111de3dddadc9d498f0765a4bc1abc2563c227ede00c54116c57bcb52d451d8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/28755537e5300278c3c98517bb47296e7f569337dec5faeaa8398cc79e5db76fec61190d4e7d3b9574af729cf95cfc3475bb1ffa7f91f89720384a0c1c4d3bd4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-07 06:29:26.493976) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-07 06:29:26.494274) ========= 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.O71XTs0EVW/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.07rO4RdVZg/Listeners TMPDIR=/var/folders/sx/77r6qnn55xj86zkv7mbvc7k800000w/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.004749 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-07 06:29:26.542128) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-07 06:29:26.542401) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-07 06:29:26.542698) ========= ========= Total master_lag: 0.17 =========