builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0276 starttime: 1497316266.56 results: success (0) revision: 2d555a2ddb67aae0b5d4e52769ab4b2d54c4a35b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-12 18:11:06.557785) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-12 18:11:06.558208) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-12 18:11:06.558479) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-12 18:11:06.581802) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 18:11:06.582186) ========= 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.0calcjdzJU/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.xNfJFIU9OJ/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-12 18:11: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% 21.5M=0.001s 2017-06-12 18:11:06 (21.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.131168 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 18:11:06.735436) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 18:11:06.735763) ========= 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.0calcjdzJU/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.xNfJFIU9OJ/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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.022339 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 18:11:06.784548) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-12 18:11:06.784912) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 2d555a2ddb67aae0b5d4e52769ab4b2d54c4a35b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 2d555a2ddb67aae0b5d4e52769ab4b2d54c4a35b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0calcjdzJU/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.xNfJFIU9OJ/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-12 18:11:06,906 truncating revision to first 12 chars 2017-06-12 18:11:06,906 Setting DEBUG logging. 2017-06-12 18:11:06,906 attempt 1/10 2017-06-12 18:11:06,906 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/2d555a2ddb67?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-12 18:11:08,247 unpacking tar archive at: mozilla-beta-2d555a2ddb67/testing/mozharness/ program finished with exit code 0 elapsedTime=1.620727 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-12 18:11:08.427047) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-12 18:11:08.427367) ========= script_repo_revision: 2d555a2ddb67aae0b5d4e52769ab4b2d54c4a35b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-12 18:11:08.427745) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-12 18:11:08.428010) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-12 18:11:08.446411) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 12 secs) (at 2017-06-12 18:11:08.446779) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0calcjdzJU/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.xNfJFIU9OJ/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 18:11:08 INFO - MultiFileLogger online at 20170612 18:11:08 in /builds/slave/test 18:11:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 18:11:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:11:08 INFO - {'append_to_log': False, 18:11:08 INFO - 'base_work_dir': '/builds/slave/test', 18:11:08 INFO - 'blob_upload_branch': 'Mozilla-Beta', 18:11:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:11:08 INFO - 'branch': 'Mozilla-Beta', 18:11:08 INFO - 'buildbot_json_path': 'buildprops.json', 18:11:08 INFO - 'config_files': ('talos/mac_config.py',), 18:11:08 INFO - 'default_actions': ('clobber', 18:11:08 INFO - 'read-buildbot-config', 18:11:08 INFO - 'download-and-extract', 18:11:08 INFO - 'populate-webroot', 18:11:08 INFO - 'create-virtualenv', 18:11:08 INFO - 'install', 18:11:08 INFO - 'setup-mitmproxy', 18:11:08 INFO - 'run-tests'), 18:11:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:11:08 INFO - 'download_minidump_stackwalk': True, 18:11:08 INFO - 'download_symbols': 'ondemand', 18:11:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:11:08 INFO - 'tooltool.py': '/tools/tooltool.py', 18:11:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:11:08 INFO - '/tools/misc-python/virtualenv.py')}, 18:11:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:11:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:11:08 INFO - 'gecko_profile': False, 18:11:08 INFO - 'gecko_profile_interval': 0, 18:11:08 INFO - 'installer_path': 'installer.exe', 18:11:08 INFO - 'log_level': 'info', 18:11:08 INFO - 'log_name': 'talos', 18:11:08 INFO - 'log_to_console': True, 18:11:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:11:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:11:08 INFO - 'opt_config_files': (), 18:11:08 INFO - 'pip_index': False, 18:11:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:11:08 INFO - 'cmd': ('bash', 18:11:08 INFO - '-c', 18:11:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:11:08 INFO - 'enabled': True, 18:11:08 INFO - 'halt_on_failure': False, 18:11:08 INFO - 'name': 'check_screen_resolution'},), 18:11:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:11:08 INFO - 'cmd': ('bash', 18:11:08 INFO - '-c', 18:11:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:11:08 INFO - 'enabled': True, 18:11:08 INFO - 'halt_on_failure': False, 18:11:08 INFO - 'name': 'check_screen_resolution'},), 18:11:08 INFO - 'run_cmd_checks_enabled': True, 18:11:08 INFO - 'suite': 'dromaeojs-e10s', 18:11:08 INFO - 'system_bits': '32', 18:11:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:11:08 INFO - 'title': 't-yosemite-r7-0276', 18:11:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:11:08 INFO - 'use_talos_json': True, 18:11:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:11:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:11:08 INFO - 'work_dir': 'build'} 18:11:08 INFO - [mozharness: 2017-06-13 01:11:08.695339Z] Running clobber step. 18:11:08 INFO - Running pre-action listener: _resource_record_pre_action 18:11:08 INFO - Running main action method: clobber 18:11:08 INFO - rmtree: /builds/slave/test/build 18:11:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:11:10 INFO - Running post-action listener: _resource_record_post_action 18:11:10 INFO - [mozharness: 2017-06-13 01:11:10.614081Z] Finished clobber step (success) 18:11:10 INFO - [mozharness: 2017-06-13 01:11:10.614221Z] Running read-buildbot-config step. 18:11:10 INFO - Running pre-action listener: _resource_record_pre_action 18:11:10 INFO - Running main action method: read_buildbot_config 18:11:10 INFO - Using buildbot properties: 18:11:10 INFO - { 18:11:10 INFO - "project": "", 18:11:10 INFO - "product": "firefox", 18:11:10 INFO - "who": "asasaki@mozilla.com", 18:11:10 INFO - "installer_path": "public/build/firefox-55.0.en-US.mac.dmg", 18:11:10 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 18:11:10 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 18:11:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 18:11:10 INFO - "stage_platform": "macosx64", 18:11:10 INFO - "basedir": "/builds/slave/test", 18:11:10 INFO - "buildnumber": 0, 18:11:10 INFO - "platform": "yosemite_r7", 18:11:10 INFO - "parent_task_id": "FWuyTHrhQdO-81icKoQdxQ", 18:11:10 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 18:11:10 INFO - "slavebuilddir": "test", 18:11:10 INFO - "taskId": "JHUsGeQ3T36PsvZRy_el4A", 18:11:10 INFO - "branch": "mozilla-beta", 18:11:10 INFO - "script_repo_revision": "production", 18:11:10 INFO - "revision": "2d555a2ddb67aae0b5d4e52769ab4b2d54c4a35b", 18:11:10 INFO - "slavename": "t-yosemite-r7-0276", 18:11:10 INFO - "repo_path": "releases/mozilla-beta" 18:11:10 INFO - } 18:11:10 INFO - Finding installer, test and symbols from parent task. 18:11:10 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JHUsGeQ3T36PsvZRy_el4A'}, attempt #1 18:11:11 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JHUsGeQ3T36PsvZRy_el4A'}, attempt #1 18:11:11 INFO - Task dependencies: T62v1gGfSnO8opEvj-mOuA FWuyTHrhQdO-81icKoQdxQ 18:11:11 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FWuyTHrhQdO-81icKoQdxQ'}, attempt #1 18:11:12 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/FWuyTHrhQdO-81icKoQdxQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg 18:11:12 INFO - Set test_url: https://queue.taskcluster.net/v1/task/FWuyTHrhQdO-81icKoQdxQ/artifacts/public/build/test_packages.json 18:11:12 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/FWuyTHrhQdO-81icKoQdxQ/artifacts/public/build/target.crashreporter-symbols.zip 18:11:12 INFO - Running post-action listener: _resource_record_post_action 18:11:12 INFO - [mozharness: 2017-06-13 01:11:12.336040Z] Finished read-buildbot-config step (success) 18:11:12 INFO - [mozharness: 2017-06-13 01:11:12.336160Z] Running download-and-extract step. 18:11:12 INFO - Running pre-action listener: _resource_record_pre_action 18:11:12 INFO - Running main action method: download_and_extract 18:11:12 INFO - mkdir: /builds/slave/test/build/tests 18:11:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:11:12 INFO - trying https://queue.taskcluster.net/v1/task/FWuyTHrhQdO-81icKoQdxQ/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json 18:11:12 INFO - Downloading https://queue.taskcluster.net/v1/task/FWuyTHrhQdO-81icKoQdxQ/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 18:11:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FWuyTHrhQdO-81icKoQdxQ/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json'}, attempt #1 18:11:13 INFO - Downloaded 1290 bytes. 18:11:13 INFO - Reading from file /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 18:11:13 INFO - Using the following test package requirements: 18:11:13 INFO - {u'awsy': [u'firefox-55.0.en-US.mac.common.tests.zip', 18:11:13 INFO - u'firefox-55.0.en-US.mac.awsy.tests.zip'], 18:11:13 INFO - u'common': [u'firefox-55.0.en-US.mac.common.tests.zip'], 18:11:13 INFO - u'cppunittest': [u'firefox-55.0.en-US.mac.common.tests.zip', 18:11:13 INFO - u'firefox-55.0.en-US.mac.cppunittest.tests.zip'], 18:11:13 INFO - u'gtest': [u'firefox-55.0.en-US.mac.common.tests.zip', 18:11:13 INFO - u'firefox-55.0.en-US.mac.gtest.tests.zip'], 18:11:13 INFO - u'jittest': [u'firefox-55.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 18:11:13 INFO - u'mochitest': [u'firefox-55.0.en-US.mac.common.tests.zip', 18:11:13 INFO - u'firefox-55.0.en-US.mac.mochitest.tests.zip'], 18:11:13 INFO - u'mozbase': [u'firefox-55.0.en-US.mac.common.tests.zip'], 18:11:13 INFO - u'reftest': [u'firefox-55.0.en-US.mac.common.tests.zip', 18:11:13 INFO - u'firefox-55.0.en-US.mac.reftest.tests.zip'], 18:11:13 INFO - u'talos': [u'firefox-55.0.en-US.mac.common.tests.zip', 18:11:13 INFO - u'firefox-55.0.en-US.mac.talos.tests.zip'], 18:11:13 INFO - u'web-platform': [u'firefox-55.0.en-US.mac.common.tests.zip', 18:11:13 INFO - u'firefox-55.0.en-US.mac.web-platform.tests.tar.gz'], 18:11:13 INFO - u'xpcshell': [u'firefox-55.0.en-US.mac.common.tests.zip', 18:11:13 INFO - u'firefox-55.0.en-US.mac.xpcshell.tests.zip']} 18:11:13 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip'] for test suite category: common 18:11:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FWuyTHrhQdO-81icKoQdxQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 18:11:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FWuyTHrhQdO-81icKoQdxQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 18:11:13 INFO - Fetch https://queue.taskcluster.net/v1/task/FWuyTHrhQdO-81icKoQdxQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 18:11:14 INFO - Content-Length response header: 58983027 18:11:14 INFO - Bytes received: 58983027 18:11:33 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip', u'firefox-55.0.en-US.mac.talos.tests.zip'] for test suite category: talos 18:11:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FWuyTHrhQdO-81icKoQdxQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 18:11:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FWuyTHrhQdO-81icKoQdxQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 18:11:33 INFO - Fetch https://queue.taskcluster.net/v1/task/FWuyTHrhQdO-81icKoQdxQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 18:11:35 INFO - Content-Length response header: 58983027 18:11:35 INFO - Bytes received: 58983027 18:11:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FWuyTHrhQdO-81icKoQdxQ/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip 18:11:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FWuyTHrhQdO-81icKoQdxQ/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip'}, attempt #1 18:11:52 INFO - Fetch https://queue.taskcluster.net/v1/task/FWuyTHrhQdO-81icKoQdxQ/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip into memory 18:11:53 INFO - Content-Length response header: 14450018 18:11:53 INFO - Bytes received: 14450018 18:11: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')]} 18:11:54 INFO - trying https://queue.taskcluster.net/v1/task/FWuyTHrhQdO-81icKoQdxQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg 18:11:54 INFO - Downloading https://queue.taskcluster.net/v1/task/FWuyTHrhQdO-81icKoQdxQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0.en-US.mac.dmg 18:11:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FWuyTHrhQdO-81icKoQdxQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg'}, attempt #1 18:11:55 INFO - Downloaded 51078420 bytes. 18:11:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FWuyTHrhQdO-81icKoQdxQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg 18:11:55 INFO - mkdir: /builds/slave/test/properties 18:11:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:11:55 INFO - Writing to file /builds/slave/test/properties/build_url 18:11:55 INFO - Contents: 18:11:55 INFO - build_url:https://queue.taskcluster.net/v1/task/FWuyTHrhQdO-81icKoQdxQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg 18:11:55 INFO - Running post-action listener: _resource_record_post_action 18:11:55 INFO - Running post-action listener: set_extra_try_arguments 18:11:55 INFO - [mozharness: 2017-06-13 01:11:55.875671Z] Finished download-and-extract step (success) 18:11:55 INFO - [mozharness: 2017-06-13 01:11:55.875810Z] Running populate-webroot step. 18:11:55 INFO - Running pre-action listener: _resource_record_pre_action 18:11:55 INFO - Running main action method: populate_webroot 18:11:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:11:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:11:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:11:55 INFO - '--tppagecycles', 18:11:55 INFO - '7'], 18:11:55 INFO - 'tests': ['tsvgm']}}, 18:11:55 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:11:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:11:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:11:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:11:55 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:11:55 INFO - 'talos_options': ['--disable-e10s'], 18:11:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:11:55 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:11:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:11:55 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:11:55 INFO - 'talos_options': ['--disable-e10s'], 18:11:55 INFO - 'tests': ['damp', 'tps']}, 18:11:55 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:11:55 INFO - 'tests': ['damp', 'tps']}, 18:11:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:11:55 INFO - 'tests': ['dromaeo_dom']}, 18:11:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:11:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:11:55 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:11:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:11:55 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:11:55 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:11:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:11:55 INFO - 'tests': ['a11yr', 18:11:55 INFO - 'ts_paint', 18:11:55 INFO - 'tpaint', 18:11:55 INFO - 'sessionrestore', 18:11:55 INFO - 'sessionrestore_no_auto_restore', 18:11:55 INFO - 'tabpaint']}, 18:11:55 INFO - 'other-e10s': {'tests': ['a11yr', 18:11:55 INFO - 'ts_paint', 18:11:55 INFO - 'tpaint', 18:11:55 INFO - 'sessionrestore', 18:11:55 INFO - 'sessionrestore_no_auto_restore', 18:11:55 INFO - 'tabpaint']}, 18:11:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:11:55 INFO - 'ts_paint', 18:11:55 INFO - 'tpaint', 18:11:55 INFO - 'sessionrestore', 18:11:55 INFO - 'sessionrestore_no_auto_restore', 18:11:55 INFO - 'tabpaint']}, 18:11:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:11:55 INFO - 'ts_paint', 18:11:55 INFO - 'tpaint', 18:11:55 INFO - 'sessionrestore', 18:11:55 INFO - 'sessionrestore_no_auto_restore', 18:11:55 INFO - 'tabpaint']}, 18:11:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:11:55 INFO - 'tests': ['a11yr', 18:11:55 INFO - 'ts_paint', 18:11:55 INFO - 'tpaint', 18:11:55 INFO - 'sessionrestore', 18:11:55 INFO - 'sessionrestore_no_auto_restore', 18:11:55 INFO - 'tabpaint']}, 18:11:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:11:55 INFO - 'tests': ['a11yr', 18:11:55 INFO - 'ts_paint', 18:11:55 INFO - 'tpaint', 18:11:55 INFO - 'sessionrestore', 18:11:55 INFO - 'sessionrestore_no_auto_restore', 18:11:55 INFO - 'tabpaint']}, 18:11:55 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 18:11:55 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 18:11:55 INFO - 'bloom_basic_ref']}, 18:11:55 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:11:55 INFO - 'talos_options': ['--mitmproxy', 18:11:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:11:55 INFO - '--firstNonBlankPaint'], 18:11:55 INFO - 'tests': ['quantum_pageload_google', 18:11:55 INFO - 'quantum_pageload_youtube', 18:11:55 INFO - 'quantum_pageload_amazon', 18:11:55 INFO - 'quantum_pageload_facebook']}, 18:11:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:11:55 INFO - 'tests': ['tsvgx', 18:11:55 INFO - 'tsvgr_opacity', 18:11:55 INFO - 'tart', 18:11:55 INFO - 'tscrollx', 18:11:55 INFO - 'cart', 18:11:55 INFO - 'tsvg_static']}, 18:11:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:11:55 INFO - 'tsvgr_opacity', 18:11:55 INFO - 'tart', 18:11:55 INFO - 'tscrollx', 18:11:55 INFO - 'cart', 18:11:55 INFO - 'tsvg_static']}, 18:11:55 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:11:55 INFO - 'talos_options': ['--disable-e10s'], 18:11:55 INFO - 'tests': ['tp5o']}, 18:11:55 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:11:55 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:11:55 INFO - 'talos_options': ['--disable-e10s', 18:11:55 INFO - '--xperf_path', 18:11:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:11:55 INFO - 'tests': ['tp5n']}, 18:11:55 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:11:55 INFO - 'talos_options': ['--xperf_path', 18:11:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:11:55 INFO - 'tests': ['tp5n']}}, 18:11:55 INFO - 'talos.zip': {'path': '', 18:11:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:11:55 INFO - Running post-action listener: _resource_record_post_action 18:11:55 INFO - [mozharness: 2017-06-13 01:11:55.898378Z] Finished populate-webroot step (success) 18:11:55 INFO - [mozharness: 2017-06-13 01:11:55.898474Z] Running create-virtualenv step. 18:11:55 INFO - Running pre-action listener: _resource_record_pre_action 18:11:55 INFO - Running main action method: create_virtualenv 18:11:55 INFO - Creating virtualenv /builds/slave/test/build/venv 18:11:55 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:11:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:11:55 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:11:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:11:56 INFO - Using real prefix '/tools/python27' 18:11:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:11:56 INFO - Installing distribute.............................................................................................................................................................................................done. 18:11:59 INFO - Installing pip.................done. 18:11:59 INFO - Return code: 0 18:11:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:11:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:11:59 INFO - Reading from file tmpfile_stdout 18:11:59 INFO - Output received: 18:11:59 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 18:11:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:11:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:11:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:11:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:59 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 0x10813fc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108143768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaa2dbb960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e95d30>, '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 0x107e5fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e86be8>, '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.0calcjdzJU/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.xNfJFIU9OJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:11:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:11:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:11:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0calcjdzJU/Render', 18:11:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:11:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:11:59 INFO - 'HOME': '/Users/cltbld', 18:11:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:11:59 INFO - 'LOGNAME': 'cltbld', 18:11:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:59 INFO - 'MOZ_NO_REMOTE': '1', 18:11:59 INFO - 'NO_EM_RESTART': '1', 18:11:59 INFO - 'PAGER': '/bin/cat', 18:11:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:11:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:59 INFO - 'PWD': '/builds/slave/test', 18:11:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:11:59 INFO - 'SHELL': '/bin/bash', 18:11:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xNfJFIU9OJ/Listeners', 18:11:59 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 18:11:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:11:59 INFO - 'USER': 'cltbld', 18:11:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:11:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:11:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:59 INFO - 'XPC_FLAGS': '0x0', 18:11:59 INFO - 'XPC_SERVICE_NAME': '0', 18:11:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:12:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:00 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:12:00 INFO - Cleaning up... 18:12:00 INFO - Return code: 0 18:12:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:12: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')]} 18:12:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12: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')]} 18:12:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12: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', '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 0x10813fc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108143768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaa2dbb960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e95d30>, '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 0x107e5fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e86be8>, '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.0calcjdzJU/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.xNfJFIU9OJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:12: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', 'psutil>=3.1.1'] in /builds/slave/test/build 18:12: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 psutil>=3.1.1 18:12:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0calcjdzJU/Render', 18:12:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:12:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:12:00 INFO - 'HOME': '/Users/cltbld', 18:12:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:12:00 INFO - 'LOGNAME': 'cltbld', 18:12:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:00 INFO - 'MOZ_NO_REMOTE': '1', 18:12:00 INFO - 'NO_EM_RESTART': '1', 18:12:00 INFO - 'PAGER': '/bin/cat', 18:12:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:12:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:00 INFO - 'PWD': '/builds/slave/test', 18:12:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:12:00 INFO - 'SHELL': '/bin/bash', 18:12:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xNfJFIU9OJ/Listeners', 18:12:00 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 18:12:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:12:00 INFO - 'USER': 'cltbld', 18:12:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:12:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:12:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:00 INFO - 'XPC_FLAGS': '0x0', 18:12:00 INFO - 'XPC_SERVICE_NAME': '0', 18:12:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:12:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:00 INFO - Downloading/unpacking psutil>=3.1.1 18:12:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:12:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:12:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:12:02 INFO - Installing collected packages: psutil 18:12:02 INFO - Running setup.py install for psutil 18:12:02 INFO - building 'psutil._psutil_osx' extension 18:12:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 18:12:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 18:12:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 18:12:03 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 18:12:03 INFO - building 'psutil._psutil_posix' extension 18:12:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 18:12:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:12:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:12:03 INFO - ^ 18:12:03 INFO - 1 warning generated. 18:12:03 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 18:12:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:12:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:12:03 INFO - Successfully installed psutil 18:12:03 INFO - Cleaning up... 18:12:03 INFO - Return code: 0 18:12:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:12:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12: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')]} 18:12:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:04 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 0x10813fc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108143768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaa2dbb960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e95d30>, '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 0x107e5fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e86be8>, '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.0calcjdzJU/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.xNfJFIU9OJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:12:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:12:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:12:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0calcjdzJU/Render', 18:12:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:12:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:12:04 INFO - 'HOME': '/Users/cltbld', 18:12:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:12:04 INFO - 'LOGNAME': 'cltbld', 18:12:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:04 INFO - 'MOZ_NO_REMOTE': '1', 18:12:04 INFO - 'NO_EM_RESTART': '1', 18:12:04 INFO - 'PAGER': '/bin/cat', 18:12:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:12:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:04 INFO - 'PWD': '/builds/slave/test', 18:12:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:12:04 INFO - 'SHELL': '/bin/bash', 18:12:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xNfJFIU9OJ/Listeners', 18:12:04 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 18:12:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:12:04 INFO - 'USER': 'cltbld', 18:12:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:12:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:12:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:04 INFO - 'XPC_FLAGS': '0x0', 18:12:04 INFO - 'XPC_SERVICE_NAME': '0', 18:12:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:12:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:12:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:12:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:12:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:12:06 INFO - Installing collected packages: mozsystemmonitor 18:12:06 INFO - Running setup.py install for mozsystemmonitor 18:12:06 INFO - Successfully installed mozsystemmonitor 18:12:06 INFO - Cleaning up... 18:12:06 INFO - Return code: 0 18:12:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:12:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:06 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 0x10813fc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108143768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaa2dbb960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e95d30>, '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 0x107e5fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e86be8>, '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.0calcjdzJU/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.xNfJFIU9OJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:12:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:12:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:12:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0calcjdzJU/Render', 18:12:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:12:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:12:06 INFO - 'HOME': '/Users/cltbld', 18:12:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:12:06 INFO - 'LOGNAME': 'cltbld', 18:12:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:06 INFO - 'MOZ_NO_REMOTE': '1', 18:12:06 INFO - 'NO_EM_RESTART': '1', 18:12:06 INFO - 'PAGER': '/bin/cat', 18:12:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:12:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:06 INFO - 'PWD': '/builds/slave/test', 18:12:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:12:06 INFO - 'SHELL': '/bin/bash', 18:12:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xNfJFIU9OJ/Listeners', 18:12:06 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 18:12:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:12:06 INFO - 'USER': 'cltbld', 18:12:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:12:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:12:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:06 INFO - 'XPC_FLAGS': '0x0', 18:12:06 INFO - 'XPC_SERVICE_NAME': '0', 18:12:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:12:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:06 INFO - Downloading/unpacking jsonschema==2.5.1 18:12:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:12:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:08 INFO - Downloading functools32-3.2.3-2.tar.gz 18:12:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:12:08 INFO - warning: no files found matching '*.txt' 18:12:08 INFO - no previously-included directories found matching 'build' 18:12:08 INFO - no previously-included directories found matching 'dist' 18:12:08 INFO - no previously-included directories found matching '.git*' 18:12:08 INFO - Installing collected packages: jsonschema, functools32 18:12:08 INFO - Running setup.py install for functools32 18:12:08 INFO - warning: no files found matching '*.txt' 18:12:08 INFO - no previously-included directories found matching 'build' 18:12:08 INFO - no previously-included directories found matching 'dist' 18:12:08 INFO - no previously-included directories found matching '.git*' 18:12:08 INFO - Successfully installed jsonschema functools32 18:12:08 INFO - Cleaning up... 18:12:08 INFO - Return code: 0 18:12:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:12:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10813fc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108143768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaa2dbb960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e95d30>, '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 0x107e5fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e86be8>, '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.0calcjdzJU/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.xNfJFIU9OJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:12:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:12:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:12:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0calcjdzJU/Render', 18:12:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:12:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:12:08 INFO - 'HOME': '/Users/cltbld', 18:12:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:12:08 INFO - 'LOGNAME': 'cltbld', 18:12:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:08 INFO - 'MOZ_NO_REMOTE': '1', 18:12:08 INFO - 'NO_EM_RESTART': '1', 18:12:08 INFO - 'PAGER': '/bin/cat', 18:12:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:12:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:08 INFO - 'PWD': '/builds/slave/test', 18:12:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:12:08 INFO - 'SHELL': '/bin/bash', 18:12:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xNfJFIU9OJ/Listeners', 18:12:08 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 18:12:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:12:08 INFO - 'USER': 'cltbld', 18:12:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:12:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:12:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:08 INFO - 'XPC_FLAGS': '0x0', 18:12:08 INFO - 'XPC_SERVICE_NAME': '0', 18:12:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:12:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:12:09 INFO - Cleaning up... 18:12:09 INFO - Return code: 0 18:12:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:12: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')]} 18:12:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12: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')]} 18:12:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12: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 0x10813fc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108143768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaa2dbb960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e95d30>, '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 0x107e5fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e86be8>, '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.0calcjdzJU/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.xNfJFIU9OJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:12: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 18:12: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 18:12:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0calcjdzJU/Render', 18:12:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:12:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:12:09 INFO - 'HOME': '/Users/cltbld', 18:12:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:12:09 INFO - 'LOGNAME': 'cltbld', 18:12:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:09 INFO - 'MOZ_NO_REMOTE': '1', 18:12:09 INFO - 'NO_EM_RESTART': '1', 18:12:09 INFO - 'PAGER': '/bin/cat', 18:12:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:12:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:09 INFO - 'PWD': '/builds/slave/test', 18:12:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:12:09 INFO - 'SHELL': '/bin/bash', 18:12:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xNfJFIU9OJ/Listeners', 18:12:09 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 18:12:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:12:09 INFO - 'USER': 'cltbld', 18:12:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:12:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:12:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:09 INFO - 'XPC_FLAGS': '0x0', 18:12:09 INFO - 'XPC_SERVICE_NAME': '0', 18:12:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:12:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:09 INFO - Downloading/unpacking blobuploader==1.2.4 18:12: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 18:12: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 18:12: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 18:12: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 18:12:10 INFO - Downloading blobuploader-1.2.4.tar.gz 18:12:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:12:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:12: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 18:12: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 18:12: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 18:12: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 18:12:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:12:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:12: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 18:12: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 18:12: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 18:12: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 18:12:11 INFO - Downloading docopt-0.6.1.tar.gz 18:12:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:12:11 INFO - Installing collected packages: blobuploader, requests, docopt 18:12:11 INFO - Running setup.py install for blobuploader 18:12:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:12:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:12:11 INFO - Running setup.py install for requests 18:12:12 INFO - Running setup.py install for docopt 18:12:12 INFO - Successfully installed blobuploader requests docopt 18:12:12 INFO - Cleaning up... 18:12:12 INFO - Return code: 0 18:12:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:12:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:12 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 0x10813fc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108143768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaa2dbb960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e95d30>, '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 0x107e5fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e86be8>, '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.0calcjdzJU/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.xNfJFIU9OJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:12:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:12:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:12:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0calcjdzJU/Render', 18:12:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:12:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:12:12 INFO - 'HOME': '/Users/cltbld', 18:12:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:12:12 INFO - 'LOGNAME': 'cltbld', 18:12:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:12 INFO - 'MOZ_NO_REMOTE': '1', 18:12:12 INFO - 'NO_EM_RESTART': '1', 18:12:12 INFO - 'PAGER': '/bin/cat', 18:12:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:12:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:12 INFO - 'PWD': '/builds/slave/test', 18:12:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:12:12 INFO - 'SHELL': '/bin/bash', 18:12:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xNfJFIU9OJ/Listeners', 18:12:12 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 18:12:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:12:12 INFO - 'USER': 'cltbld', 18:12:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:12:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:12:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:12 INFO - 'XPC_FLAGS': '0x0', 18:12:12 INFO - 'XPC_SERVICE_NAME': '0', 18:12:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:12:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:12:12 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-na3577-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:12:12 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-5yTzyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:12:13 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-5VR3Z4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:12:13 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-b4ZdAx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:12:13 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-yYT1vX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:12:13 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-q5PK63-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:12:13 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-PISpji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:12:13 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-lTopb1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:12:13 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-K2b0cF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:12:13 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-_oWccg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:12:13 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-6gQluU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:12:14 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-FiRxBI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:12:14 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-OO8UCe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:12:14 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-l1EDX_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:12:14 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-DxKtuV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:12:14 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-WdSkhY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:12:14 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-HGq7Xb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:12:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:12:14 INFO - Running setup.py install for manifestparser 18:12:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:12:14 INFO - Running setup.py install for mozcrash 18:12:14 INFO - Running setup.py install for mozdebug 18:12:15 INFO - Running setup.py install for mozdevice 18:12:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:12:15 INFO - Running setup.py install for mozfile 18:12:15 INFO - Running setup.py install for mozhttpd 18:12:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:12:15 INFO - Running setup.py install for mozinfo 18:12:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:12:15 INFO - Running setup.py install for mozInstall 18:12:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:12:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:12:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:12:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:12:15 INFO - Running setup.py install for mozleak 18:12:15 INFO - Running setup.py install for mozlog 18:12:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:12:16 INFO - Running setup.py install for moznetwork 18:12:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:12:16 INFO - Running setup.py install for mozprocess 18:12:16 INFO - Running setup.py install for mozprofile 18:12:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:12:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:12:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:12:16 INFO - Running setup.py install for mozrunner 18:12:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:12:16 INFO - Running setup.py install for mozscreenshot 18:12:16 INFO - Running setup.py install for moztest 18:12:17 INFO - Running setup.py install for mozversion 18:12:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:12:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:12:17 INFO - Cleaning up... 18:12:17 INFO - Return code: 0 18:12:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:12: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')]} 18:12:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12: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')]} 18:12:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:17 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 0x10813fc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108143768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaa2dbb960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e95d30>, '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 0x107e5fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e86be8>, '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.0calcjdzJU/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.xNfJFIU9OJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:12:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:12:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:12:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0calcjdzJU/Render', 18:12:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:12:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:12:17 INFO - 'HOME': '/Users/cltbld', 18:12:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:12:17 INFO - 'LOGNAME': 'cltbld', 18:12:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:17 INFO - 'MOZ_NO_REMOTE': '1', 18:12:17 INFO - 'NO_EM_RESTART': '1', 18:12:17 INFO - 'PAGER': '/bin/cat', 18:12:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:12:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:17 INFO - 'PWD': '/builds/slave/test', 18:12:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:12:17 INFO - 'SHELL': '/bin/bash', 18:12:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xNfJFIU9OJ/Listeners', 18:12:17 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 18:12:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:12:17 INFO - 'USER': 'cltbld', 18:12:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:12:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:12:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:17 INFO - 'XPC_FLAGS': '0x0', 18:12:17 INFO - 'XPC_SERVICE_NAME': '0', 18:12:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:12:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:12:17 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-Lz0G6e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:12:17 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:12:17 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-m9wOnl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:12:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:12:17 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-ZEWD55-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:12:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:12:17 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-XkSv8z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:12:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:12:17 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-7NPQtJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:12: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)) 18:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:12:18 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-TDkSgg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:12: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)) 18:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:12:18 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-M3RMAI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:12: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)) 18:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:12:18 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-MxmNcn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:12:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:12:18 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-o4D3Kl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:12:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:12:18 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-GNWs0x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:12:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:12:18 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-zYekMb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:12:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:12:18 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-3NVfeb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:12:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:12:18 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-oVUmMi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:12:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:12:18 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-L7oazk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:12:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:12:18 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-D2GpAl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:12: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)) 18:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:12:19 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-YjkSLl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:12: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)) 18:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:12:19 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-pgI9Nc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:12:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:20 INFO - Downloading blessings-1.6.tar.gz 18:12:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:12:20 INFO - Installing collected packages: blessings 18:12:20 INFO - Running setup.py install for blessings 18:12:21 INFO - Successfully installed blessings 18:12:21 INFO - Cleaning up... 18:12:21 INFO - Return code: 0 18:12:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:12:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:12:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:12:21 INFO - Reading from file tmpfile_stdout 18:12:21 INFO - Current package versions: 18:12:21 INFO - blessings == 1.6 18:12:21 INFO - blobuploader == 1.2.4 18:12:21 INFO - docopt == 0.6.1 18:12:21 INFO - functools32 == 3.2.3-2 18:12:21 INFO - jsonschema == 2.5.1 18:12:21 INFO - manifestparser == 1.1 18:12:21 INFO - mozInstall == 1.13 18:12:21 INFO - mozcrash == 1.0 18:12:21 INFO - mozdebug == 0.1 18:12:21 INFO - mozdevice == 0.51 18:12:21 INFO - mozfile == 1.2 18:12:21 INFO - mozhttpd == 0.7 18:12:21 INFO - mozinfo == 0.10 18:12:21 INFO - mozleak == 0.1 18:12:21 INFO - mozlog == 3.4 18:12:21 INFO - moznetwork == 0.27 18:12:21 INFO - mozprocess == 0.25 18:12:21 INFO - mozprofile == 0.28 18:12:21 INFO - mozrunner == 6.13 18:12:21 INFO - mozscreenshot == 0.1 18:12:21 INFO - mozsystemmonitor == 0.3 18:12:21 INFO - moztest == 0.8 18:12:21 INFO - mozversion == 1.4 18:12:21 INFO - psutil == 3.1.1 18:12:21 INFO - requests == 1.2.3 18:12:21 INFO - wsgiref == 0.1.2 18:12:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:12:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:21 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 0x10813fc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108143768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaa2dbb960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e95d30>, '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 0x107e5fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e86be8>, '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.0calcjdzJU/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.xNfJFIU9OJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:12:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:12:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:12:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0calcjdzJU/Render', 18:12:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:12:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:12:21 INFO - 'HOME': '/Users/cltbld', 18:12:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:12:21 INFO - 'LOGNAME': 'cltbld', 18:12:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:21 INFO - 'MOZ_NO_REMOTE': '1', 18:12:21 INFO - 'NO_EM_RESTART': '1', 18:12:21 INFO - 'PAGER': '/bin/cat', 18:12:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:12:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:21 INFO - 'PWD': '/builds/slave/test', 18:12:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:12:21 INFO - 'SHELL': '/bin/bash', 18:12:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xNfJFIU9OJ/Listeners', 18:12:21 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 18:12:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:12:21 INFO - 'USER': 'cltbld', 18:12:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:12:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:12:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:21 INFO - 'XPC_FLAGS': '0x0', 18:12:21 INFO - 'XPC_SERVICE_NAME': '0', 18:12:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:12:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:12:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:12:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:12:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:12:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:12:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:12:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:12:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:12:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:12:21 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:12:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:23 INFO - Downloading marionette_driver-1.4.0.tar.gz 18:12:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 18:12: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)) 18:12: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)) 18:12: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)) 18:12: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)) 18:12:23 INFO - Installing collected packages: marionette-driver 18:12:23 INFO - Running setup.py install for marionette-driver 18:12:23 INFO - Successfully installed marionette-driver 18:12:23 INFO - Cleaning up... 18:12:23 INFO - Return code: 0 18:12:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:12:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10813fc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108143768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaa2dbb960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e95d30>, '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 0x107e5fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e86be8>, '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.0calcjdzJU/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.xNfJFIU9OJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:12:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:12:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:12:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0calcjdzJU/Render', 18:12:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:12:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:12:23 INFO - 'HOME': '/Users/cltbld', 18:12:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:12:23 INFO - 'LOGNAME': 'cltbld', 18:12:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:23 INFO - 'MOZ_NO_REMOTE': '1', 18:12:23 INFO - 'NO_EM_RESTART': '1', 18:12:23 INFO - 'PAGER': '/bin/cat', 18:12:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:12:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:23 INFO - 'PWD': '/builds/slave/test', 18:12:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:12:23 INFO - 'SHELL': '/bin/bash', 18:12:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xNfJFIU9OJ/Listeners', 18:12:23 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 18:12:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:12:23 INFO - 'USER': 'cltbld', 18:12:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:12:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:12:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:23 INFO - 'XPC_FLAGS': '0x0', 18:12:23 INFO - 'XPC_SERVICE_NAME': '0', 18:12:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:12:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:12:23 INFO - Cleaning up... 18:12:23 INFO - Return code: 0 18:12:23 INFO - Running post-action listener: _resource_record_post_action 18:12:23 INFO - Running post-action listener: _start_resource_monitoring 18:12:23 INFO - Starting resource monitoring. 18:12:23 INFO - [mozharness: 2017-06-13 01:12:23.688216Z] Finished create-virtualenv step (success) 18:12:23 INFO - [mozharness: 2017-06-13 01:12:23.689197Z] Running install step. 18:12:23 INFO - Running pre-action listener: _resource_record_pre_action 18:12:23 INFO - Running main action method: install 18:12:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:12:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:12:23 INFO - Reading from file tmpfile_stdout 18:12:23 INFO - Detecting whether we're running mozinstall >=1.0... 18:12:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:12:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:12:24 INFO - Reading from file tmpfile_stdout 18:12:24 INFO - Output received: 18:12:24 INFO - Usage: mozinstall [options] installer 18:12:24 INFO - Options: 18:12:24 INFO - -h, --help show this help message and exit 18:12:24 INFO - -d DEST, --destination=DEST 18:12:24 INFO - Directory to install application into. [default: 18:12:24 INFO - "/builds/slave/test"] 18:12:24 INFO - --app=APP Application being installed. [default: firefox] 18:12:24 INFO - mkdir: /builds/slave/test/build/application 18:12:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:12:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0.en-US.mac.dmg --destination /builds/slave/test/build/application 18:12:40 INFO - Reading from file tmpfile_stdout 18:12:40 INFO - Output received: 18:12:40 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 18:12:40 INFO - Running post-action listener: _resource_record_post_action 18:12:40 INFO - [mozharness: 2017-06-13 01:12:40.205833Z] Finished install step (success) 18:12:40 INFO - [mozharness: 2017-06-13 01:12:40.205962Z] Running setup-mitmproxy step. 18:12:40 INFO - Running pre-action listener: _resource_record_pre_action 18:12:40 INFO - Running main action method: setup_mitmproxy 18:12:40 INFO - Skipping: mitmproxy is not required 18:12:40 INFO - Running post-action listener: _resource_record_post_action 18:12:40 INFO - [mozharness: 2017-06-13 01:12:40.206598Z] Finished setup-mitmproxy step (success) 18:12:40 INFO - [mozharness: 2017-06-13 01:12:40.206717Z] Running run-tests step. 18:12:40 INFO - Running pre-action listener: _resource_record_pre_action 18:12:40 INFO - Running main action method: run_tests 18:12:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:12:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:12:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:12:40 INFO - 2017-06-12 18:12:40.220 screenresolution[2550:37125] starting screenresolution argv=screenresolution get 18:12:40 INFO - 2017-06-12 18:12:40.223 screenresolution[2550:37125] Display 0: 1600x1200x32@60 18:12:40 INFO - 2017-06-12 18:12:40.228 screenresolution[2551:37127] starting screenresolution argv=screenresolution list 18:12:40 INFO - Available Modes on Display 0 18:12:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:12:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:12:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:12:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:12:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:12:40 INFO - Intel Iris: 18:12:40 INFO - Chipset Model: Intel Iris 18:12:40 INFO - Type: GPU 18:12:40 INFO - Bus: Built-In 18:12:40 INFO - VRAM (Dynamic, Max): 1536 MB 18:12:40 INFO - Vendor: Intel (0x8086) 18:12:40 INFO - Device ID: 0x0a2e 18:12:40 INFO - Revision ID: 0x0009 18:12:40 INFO - Displays: 18:12:40 INFO - Display: 18:12:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:12:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:12:40 INFO - Display Serial Number: 1600x1200 60 18:12:40 INFO - Main Display: Yes 18:12:40 INFO - Mirror: Off 18:12:40 INFO - Online: Yes 18:12:40 INFO - Rotation: Supported 18:12:40 INFO - Return code: 0 18:12:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:12:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:12:40 INFO - Python 2.7.3 18:12:40 INFO - Return code: 0 18:12:40 INFO - grabbing minidump binary from tooltool 18:12:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:40 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 0x107e95d30>, '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 0x107e5fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e86be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:12:40 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:12:40 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 18:12:40 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:12:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:12:40 INFO - Return code: 0 18:12:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:12:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:12:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:12:40 INFO - ENV: RUST_BACKTRACE is now 1 18:12:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:12:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:12:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FWuyTHrhQdO-81icKoQdxQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0276', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 18:12:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FWuyTHrhQdO-81icKoQdxQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0276 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 18:12:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0calcjdzJU/Render', 18:12:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:12:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:12:40 INFO - 'HOME': '/Users/cltbld', 18:12:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:12:40 INFO - 'LOGNAME': 'cltbld', 18:12:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:12:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:12:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:40 INFO - 'MOZ_NO_REMOTE': '1', 18:12:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:12:40 INFO - 'NO_EM_RESTART': '1', 18:12:40 INFO - 'PAGER': '/bin/cat', 18:12:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:12:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:40 INFO - 'PWD': '/builds/slave/test', 18:12:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:12:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:12:40 INFO - 'RUST_BACKTRACE': '1', 18:12:40 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:12:40 INFO - 'SHELL': '/bin/bash', 18:12:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xNfJFIU9OJ/Listeners', 18:12:40 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 18:12:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:12:40 INFO - 'USER': 'cltbld', 18:12:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:12:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:12:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:40 INFO - 'XPC_FLAGS': '0x0', 18:12:40 INFO - 'XPC_SERVICE_NAME': '0', 18:12:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:12:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FWuyTHrhQdO-81icKoQdxQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0276', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 18:12:40 INFO - mozversion application_buildid: 20170612163418 18:12:40 INFO - mozversion application_changeset: 2d555a2ddb67aae0b5d4e52769ab4b2d54c4a35b 18:12:40 INFO - mozversion application_display_name: Firefox 18:12:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:12:40 INFO - mozversion application_name: Firefox 18:12:40 INFO - mozversion application_remotingname: firefox 18:12:40 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 18:12:40 INFO - mozversion application_vendor: Mozilla 18:12:40 INFO - mozversion application_version: 55.0 18:12:40 INFO - mozversion platform_buildid: 20170612163418 18:12:40 INFO - mozversion platform_changeset: 2d555a2ddb67aae0b5d4e52769ab4b2d54c4a35b 18:12:40 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 18:12:40 INFO - mozversion platform_version: 55.0 18:12:40 INFO - using testdate: 1497316360 18:12:40 INFO - actual date: 1497316360 18:12:40 INFO - starting webserver on 'localhost:59413' 18:12:40 INFO - SUITE-START | Running 2 tests 18:12:40 INFO - TEST-START | dromaeo_css 18:12:40 INFO - operating with platform_type : mac_ 18:12:40 INFO - Initialising browser for dromaeo_css test... 18:12:40 INFO - Application command: /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:59413/getInfo.html -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmpRhKeMA/profile 18:12:40 INFO - TEST-INFO | started process 2561 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:59413/getInfo.html -foreground) 18:13:13 INFO - TEST-INFO | 2561: killed by SIGTERM 18:13:13 INFO - Browser initialized. 18:13:13 INFO - Running cycle 1/1 for dromaeo_css test... 18:13:13 INFO - TEST-INFO | started process 2568 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmpRhKeMA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:13:15 INFO - PID 2568 | 2017-06-12 18:13:15.600 plugin-container[2569:37544] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 18:13:15 INFO - PID 2568 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:13:15 INFO - PID 2568 | 2017-06-12 18:13:15.600 plugin-container[2569:37544] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x470b, name = 'com.apple.CFPasteboardClient' 18:13:15 INFO - PID 2568 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:13:15 INFO - PID 2568 | 2017-06-12 18:13:15.600 plugin-container[2569:37544] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:13:25 INFO - PID 2568 | RSS: Main: 184221696 18:13:25 INFO - PID 2568 | 18:16:28 INFO - PID 2568 | Cycle 1(1): loaded http://localhost:59413/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59413/tests/dromaeo/cssquery-ext.html) 18:16:30 INFO - PID 2568 | RSS: Main: 204636160 18:16:30 INFO - PID 2568 | 18:19:32 INFO - PID 2568 | Cycle 1(1): loaded http://localhost:59413/tests/dromaeo/cssquery-ext.html (next: http://localhost:59413/tests/dromaeo/cssquery-jquery.html) 18:19:32 INFO - PID 2568 | RSS: Main: 215080960 18:19:32 INFO - PID 2568 | 18:22:34 INFO - PID 2568 | Cycle 1(1): loaded http://localhost:59413/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59413/tests/dromaeo/cssquery-mootools.html) 18:22:34 INFO - PID 2568 | RSS: Main: 216883200 18:22:34 INFO - PID 2568 | 18:25:36 INFO - PID 2568 | Cycle 1(1): loaded http://localhost:59413/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59413/tests/dromaeo/cssquery-prototype.html) 18:25:37 INFO - PID 2568 | RSS: Main: 219516928 18:25:37 INFO - PID 2568 | 18:28:39 INFO - PID 2568 | Cycle 1(1): loaded http://localhost:59413/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59413/tests/dromaeo/cssquery-yui.html) 18:28:40 INFO - PID 2568 | RSS: Main: 222306304 18:28:40 INFO - PID 2568 | 18:31:42 INFO - PID 2568 | Cycle 1(1): loaded http://localhost:59413/tests/dromaeo/cssquery-yui.html (next: http://localhost:59413/tests/dromaeo/cssquery-dojo.html) 18:31:42 INFO - PID 2568 | RSS: Main: 221585408 18:31:42 INFO - PID 2568 | 18:31:42 INFO - PID 2568 | __start_tp_report 18:31:42 INFO - PID 2568 | _x_x_mozilla_page_load 18:31:42 INFO - PID 2568 | _x_x_mozilla_page_load_details 18:31:42 INFO - PID 2568 | |i|pagename|runs| 18:31:42 INFO - PID 2568 | |0;dojo.html;3224.6775322467747;3649.1606930405987;3723.2181241939193;3850.0952276215085;3863.613638636137;5300.575953923687;6346.30190679025;6379.011253255747;6396.936030639692;6411.551191416603;6499.707513161908;6574.802755917318;6577.177852768404;6579.934200657992;6606.339366063391;5847.912281315784;5850.122481627758;5871.559633027518;5982.401759824026;5993.9400605993815;5899.439553242435;5995.640261584319;6011.278646562397;6014.0978853172155;6029.336772954972;5674.2339784129435;5821.50517206038;5872.823815285548;5880.441358070955;5884.882302353951;5753.280839895013;5856.795012174596;5896.204012458306;5906.409359064102;5927.614705044159;4139;5754.165645981309;5866.941330586725;5869.4717475427415;5870.735816888207;6477.028445733131;6508.674566271715;6523.641199734013;6549.5742776719835;6601.768938087144;6771.424428923548;7143.785686429415;7166.892496612555;7179.784606461814;7192.928070719278;6199.88402087624;6763.323667633246;6781.796546103625;6895.55178913369;7053.0830991971225;6621.966890165567;6641.169853768279;6670.699818508178;6719.227288861844;6723.428508576728;7413.777586672409;7470.4770666052855;7470.925290747023;7474.887876681854;7478.2147874473485;6769.120014398097;6859.142607174103;6860.348266914635;7014.894776578355;7024.086868707035;7027.227005029443;7031.08595882532;7042.718291268395;7042.823929401806;7047.295270473014;7015.473839462061;7024.192217895007;7055.541389809646;7100.822479438056;7108.3602475777425;6915.135608048994;6940.063091482686;6943.652817359111;6957.79126626202;6974.511784175059;5588.9720551397795;5601.439856014366;5605.607607467438;5610.719464026783;5620.803271885465;4893.779779909913;4939.234418665113;4959.429665588431;4975.4527002029745;4991.276264941564;6410.967945160338;6462.644554549451;6496.837579060561;6517.283098859121;6530.379613936669;7167;7230.168530619047;7231.349178573953;7244.384227340735;7277.818054548573;6638.502112341497;6645.966770166118;6651.23510796255;6665.533412661067;6701.865962680719;6283.937160628335;6328.588641738273;6330.556861019684;6379.872402552016;6401.615903045832;7159.319864612853;7397.4082073435075;7418.517796343221;7437.405007599487;7471.402287816962;7092.255313192247;7126.928730712627;7168.390686791681;7249.311315425026;7280.162781280009;7366.4106871449085;7461.030066091591;7509.061367329084;7513.924860751323;7516.774496765106;7080.504364694422;7107.82230444243;7111.573305601681;7142.071530700976;7153.785386438415;7300.221354490384;7300.96349518249;7317.597532135784;7318.817029574303;7321.707131714672;6761.86476270462;6831.180258368829;6832.282610326043;7060.364567188978;7068.540544864567;6822.727090916506;6927.93072069293;6980.301969802979;6982.476314276348;7009.754658586925;5595.692236927008;5690.971545142248;6382.138411314599;6773.593584384953;6790.72837086511;5673.886522269614;5734.856628584152;6831.6242606657115;6889.414399775875;6937.965310173417;6587.736490540325;6665.800025999228;6689.431398331197;6731.360520750521;6753.0883330751685 18:31:42 INFO - PID 2568 | |1;ext.html;829.100426037749;852.7953291210112;862.3446180902514;883.6612532014;893.3433926064341;38459.42310865339;39260.60739392605;39288.80355598222;39321.21357572847;39352;30916.381672366595;35191.648083519154;37584.81207593965;37810.43284350737;37964.620353796454;7725.652345644446;7849.215078492133;8409.663613455454;8556.401051926368;8586.613602387877;8471.237588617023;8542.658293668246;8580.570971451434;8580.871286930702;8584.74245772628;8521.318294536453;8546.44448110874;8572.528510931896;8581.871271930926;8585.742427727148;8562.357823163256;8562.400631955765;8566;8579.656813727475;8596.78508037304;7195.568265904062;8416.410851240416;8447.662093516314;8508.191721786483;8512.404131710782;36923.4461483078;37022.62977370219;37035.62964370376;37191.256174876624;37451.25097498035;3613.241219343941;3649.3066317399876;3652.6164752701116;3656.542932133483;3661.1213308805695;30383;30460.8476957616;30623;30744.8462757687;30813.767449302224;3209.646938836726;3269.0683155300862;3279.114639047468;3285.178705323669;3301.735861131104;263963.6801815979;264575;265820;336491;337595;47477;47655;47809.04381912342;48135.518644813805;48255;41359;49823.25265121026;50048.49854504371;50086.49913500891;50133.24800128001;15812.209389530477;15861.603459913595;15938.920305398398;15964.441244556385;15970;12981.675458113623;13041.804372934414;13069.215847049018;13105.082644214814;13106.934465327613;13367.799483007762;13451.932740336431;13489.325533723471;13577;13640.590782276548;5841.649501029867;5875.441833025856;5898.439648233411;5931.703414829328;5938.8812223755285;993.4039576254335;997.3965750720854;998.5905778630727;1054.2409465185056;1056.8995945385177;19655.115519801642;23170.304890853302;23388.532229355318;23504.882475587856;24107.674077925883;884.3072352857357;1113.1984970820995;1114.3035602748282;1136.2557524821254;1160.3502038858267;10803.081738052197;12145;14711;14800;15399.99900006496;268392;358053;360915;361066;362731;53223.467765322624;53231.669208270105;53312.20031699527;53383.46616533863;53516.73241633767;1342.2818791946263;1444.248990524921;1535.5700403886706;1538.2923855026813;1545.474538656862;20958.266460673804;24077;27545.724542754317;29216.977405790694;30809.537856931714;38991.415128772525;39009.6099039006;39231.41152882766;39308.80345598254;39309.2138157241;265216;305043;307413;310453;311807;29003.709962900102;29165;29181.854090729412;29263;33586.83206583952;36787.816060919526;40702.9824254396;41107.56123535782;42547;43224;28009.43981120407;28154.71845281603;29024.854875725487;29099.41801164007;30459.086227413212;29488.557671634513;30662.08013759591;30753.38493230078;30839.383212336077;31058.689413106484;34673.826630866686;35853.462198066525;36620.81689591535;36689;36700.63299366972;26656.333591660365;26674.733252668;29369.972050978115;29571.964981225552;30193.69806301909;22173.66739498941;24002.759972400054;24037.639435408833;24145.275641730776;24183.75816241815 18:31:42 INFO - PID 2568 | |2;jquery.html;451.3568165364356;483.73152900140406;539.9973000134999;560.5543592843688;562.4122791682692;22933.19733809315;23397;23646;24416.877915610443;24562;24388;25108;25307.62038569423;25315.873420632917;25441.10956116536;6458.354164583539;6512.544121911468;6517.217933847921;6569.277379488276;6585;5645.633033852807;5749.425057494259;6350.333215012426;6390;6409.1668083149125;6393.552451328409;6397.936020639804;6399.072134540512;6423.261324947644;6423.614583125004;6377.33038031007;6404.42360187583;6417.262014868304;6420.807375778711;6426.710798014101;5899.823005309848;5906.881862362771;5921.851953701149;5923.5261179106;5935.910961335583;28285.717142828515;29394.559081613792;29970.55044174339;29982.400351992837;30259.546106808415;2238.4292005538646;2265.8413911026237;2274.3290729234914;2280.908763649452;2282.2696737044153;14987.625309367244;19304.034798260032;19504.609907801907;19519;19522.511937201685;2210.414240226325;2238.653008783625;2279.6466547685136;2282.931512054641;2283.5775381554467;167587.32412675838;173873;174029;187131;200228;14402.279886005657;14490.582402744016;14512.129272243488;15998.200089995453;16058.67882642364;20473.283435079702;20539.897300513403;20574.279900203423;20585.382438526867;20661.690074648894;8319.958400208081;9896.208303335716;10414.791704166026;10435.91281743639;10552.050315471644;9220.90779092214;10110.342827716322;10139.290249682452;10223.846642300372;10233.641822536325;7413.851722965618;9086.273098152133;9204.447733136034;9217.631294748135;9224.584893679801;2325.662778897084;2447.3147518694796;2746.8489233092373;2751.119641714632;2855.971440285612;977.4379731654327;980.9264305176997;981.5299278113271;1033.34382667007;1035.11497669493;20261.392158235278;20568.794312056987;20696.689549656767;21234.938253087592;21260.149594016377;1013.8479228115694;1030.3302853145515;1044.8798388185303;1068.5672302717412;1101.1576144249655;10294.227932905062;10723.624673136403;10891.400972946563;11125.332480051062;11315.773684526263;584570;592313;600818;620666;626790;139807;161644;167513;169991.150044249;170706;1084.38732116354;1127.0420142878652;1290.4773566705312;1292.2892409174917;1321.042244372822;22266.22068227604;23353.76646233584;23680.860940705337;23893.522129556965;23918.88040559786;60398.79202416015;72316.63841680757;74363.62818185874;78895.60552197431;82139.5893020555;94851;97321;99680;102516.48741756243;103006;13925;16758;17024.829751702324;17645.647087058445;17776.733349000035;13814.240216788174;18477.983710895493;18529;18795;18916.716249256;14618.780718289447;15312.234388280764;15388.46140385126;15584;15593.064416135068;13216.405261763051;13434;13918.164910105426;14043.859561404664;14213.857861421668;15760.290786914387;15913.363465461714;15927.840721592636;15944.36222551085;15947;15695.450659226872;16737.246823893198;16945.830541694424;16982.83017169862;17181.828181718523;14900.701985960437;14937.477188298357;15004.549863504113;15058.316024924074;15117.319720612819 18:31:42 INFO - PID 2568 | |3;mootools.html;1501.797257370255;1535.8694510966568;1562.7419926958794;1564.3507944203163;1569.6782159657275;14073.577792666223;14586;14649.92675036626;14732.6316842079;14752.409903603857;13953.44186232552;14506.274686265671;14642.487512937023;14684.045537040112;14712.190829504372;2942.882284708609;2982.2544363909024;3006.0681188831422;3018.170003249102;3020.607321048272;2909.1708862974056;2938.1038783171125;2952.9852350738215;2959.482090634141;2965.24386281499;2812.5640525718445;2915.169176784617;2959.0383125484304;2960.6595241547175;2974.0780358088955;2965.2735079905347;3000.11496608501;3002.894898678536;3004.263955330936;3010.458117538842;2930.033089080598;2942.2497263197747;2948.9262768430744;2951.453981013498;2959.422912532057;14126.152430854181;14367.281635918162;14441.277936103257;14450.132992020512;14480.637984050483;7931.643076061591;8228.50628962264;8245.422820402575;8250.752477425687;8270.751877443687;4806.927896081562;4942.9011419771405;4981.302617633534;5032.320636714034;5147.510986456281;1476.2544914817947;1484.2504535209757;1495.9177245251615;1520.0347779160202;1522.543237028898;19445.513862153417;22113.778862211333;22142;22154.88922555377;22174.334769956928;5294.97352513235;5890.440408161217;6862.9313706863295;6931.514793964475;7082.29177082298;11669.88330116688;13923.791143132861;14902.329395177243;14948.775768363483;14959.775603365959;7686.730964416219;7949.562774047367;7956.681732730742;7974.641141148662;7979.760607181794;6218.844528886814;7070.222275549685;7316.890246646304;7361.411087113018;7375.225601311785;4584;4635.513271106485;5312.628116031918;5550.555955523549;5580.414056524088;1611.7260065788844;1613.8138468225845;1625.5123462961064;1630.649410376751;1663.168415792104;748.9812754681068;756.4742503959695;805.1666525146445;822.2558584481054;836.8827615133742;7204;7396.186419493852;8212.8357432851;8697.086805885418;8758.430702004349;982.3860087445347;1006.4766321512773;1032.1587905856702;1042.7549525861416;1046.2885238038207;4931.61086632897;5025.547700706881;5029.698218106925;5036.370453693288;5411.56707463402;19010.52473688169;19295.324663636704;19702.113404896812;19719.309824156087;19720.40838774839;19546.90226548858;19653.901730491256;19696.31062912791;19790.60418791616;19805.801941980397;9917.851232231522;10049;10116.59533618647;10209.030142136486;10230.846537301946;7306.525075870051;8433.95783021081;8485.490870547786;8582.055973842747;8757.255633271243;16793.244304006592;17358.652826943642;17746.733798993282;18059.81940180634;18111.909440452713;14014.668606482368;17105.486835394957;17302.875313104607;18398.54003649866;18570.535736606693;11422;11623.302601843916;11639.127065470293;11644.53421863116;11661.475233614678;12939.805902911652;12951.481940722642;12954.222746635232;12974;12998.285094319903;10116.89883101189;10214.387136771817;10533.473326333806;10821.729456763329;10931.45342732876;10407.947960260453;10467.790644187226;10842.349459032483;10860;10875;9647.276454265817;9710.368826026286;9733.513324333613;9743.756406089904;9773.609055637695;6909.654517274217;7011.50919435655;7122.786316410516;7157.141143062866;7371.078615173104;5743.339515955551;7168.354848063699;7256.1655409628565;7535.623218839146;7570.810729731801 18:31:42 INFO - PID 2568 | |4;prototype.html;414.93983372411003;457.29804749709206;481.1843924547891;502.7084291111156;502.90193412284594;6480.3843634854675;6498.967505162474;6523.9673801630925;6523.967380163104;6558.868822623551;5185.870353241171;5734.139879018139;6109.5723299369065;6262.248530176374;6364.490840732742;4252.255855225323;4753.762311884392;4753.809847606091;4794.520547945195;4798.496157903405;4292.6351260142765;4551.611758413683;4741.336212930192;4772.785224664881;4809.398825146856;4763.071201115784;4766.547178018082;4773.18855794514;4794.2329227323635;4807.903841923159;4128.752274863503;4533.523979982104;4760.476347601761;4761.880952976168;4775.522447755231;4159.646430053449;4228.386883901848;4232.809523571446;4250.234957707611;4253.574642535722;6188.81433556994;6210.875782484325;6234.563580549363;6265.530085243579;6283.497320214372;4537.818487260505;4734.827665464113;5567.916481252784;5587.972060139714;5637.210790489335;5871.900331429933;6543.247526534462;6745.3591908768585;6790.660466976681;6793.898091528631;4759.214729569617;4793.9280910786365;4799.984006717187;4815.783289751969;4823.565879070866;7172.928270717278;7178.841309823713;7204.17152027513;7212.170600380919;7272.927270727331;4877.317175595419;4886.462489126193;4901.166801643729;4906.779194936236;4907.901841963141;4290.699651024398;4953.628477864175;4971.825986090469;5007.649464537448;5040.193569029011;4333.8699839004885;5279.838435544174;5359.437259087817;5613.073842815971;5646.35066967304;5474.698891560923;6033.607815491979;6194.4115309045565;6875.074979005886;6880.552764070319;1456.3300881594373;1464.6045567696663;1642.3348543839763;1676.924538395775;1718.4672751447092;956.9138777509918;1081.4808891731855;1136.8237923121928;1164.4177911044478;1182.196106647488;6217.6891155442045;6315.5894866833505;6394.488440924715;6482.35176482348;6524.314946930599;6092.39076092396;6951.7914462566205;6962.911126661981;7030.683619237045;7085.3156358693195;6164.845878852975;6439.6495279222745;6458.773942911976;6477.157969464034;6506.902396463962;3288.7831502344284;3528.7000604948353;3579.319929213441;3629.7459177857822;3646.4894914711963;6605.2918303495335;7210.098737657793;7267.854642907112;7275.818104547323;7325.523840950321;6383.71273292703;6993.755218567326;7212.098487689039;7244.710211591478;7304.488685791944;6046.9720147574135;6442.8389290268115;6878.65606719662;7129.465290103263;7277.7452789153185;6558.901616475852;6872.381485666314;6892.724291028503;7013.649317534001;7013.859722805413;6878.415334696607;6882.311768823078;6898.724051038103;6913.2371245332115;6956.2000369958105;5768.192453056491;6294.779682711083;6402.903956440564;7173.892391614026;7209.567425954459;4746.7863946121815;4793.400824896888;4805.855824325136;4836.395450568679;4873.586659868598;6088.4215999479975;6810.28492008332;6990.161180658353;6991.3008699131715;7035.472339574655;5664.130836823921;6788.676208139365;6833.58998460094;6845.383915447634;6846;5682.318121825408;6225.8132256032395;6799.456043516606;6800.863982720219;6830.45356371499;6668.933310666831;6674.632895190811;6675.465962723068;6736.898946515708;6771.458283337222;5721.742521586455;5867.442592953663;6406.519511036598;6851.965740171467;6902.482313826584;5522.9447705524035;5626.774929002794;6318.178636777189;6346.238451385863;6696.1629796275465 18:31:42 INFO - PID 2568 | |5;yui.html;856.8157846063099;892.1524551675908;894.4856707393251;899.0604817965226;913.4975763330169;17973.640527189447;18146;18349.35777247795;18404.079796010195;18447.354342598013;15315.770263446057;15316.004459710111;15325.233738313096;15908.681826363465;18067.096645167756;1038.532660302863;1067.4929408530963;1068.7648717282186;1068.770214403903;1070.84472751451;1068.9893101068967;1071.1056268016216;1072.4208927179313;1073.5652060915343;1075.0539525217798;1132.122604981142;1132.9150313726461;1133.2294040052761;1134.0927258193453;1135.262079648231;989.3470309595668;1098.9175811814066;1104.9116070714324;1126.2510430563666;1131.7340425000118;797.6370751308141;798.2815466080574;800.8278220182689;801.1067659559608;802.3340627279345;17840.600782456197;18522.90738546312;18687.878727276406;18698;18704.158312875952;18329.541761456072;18601.627967440574;18742.1566029529;18750.437486875417;18775.24899004038;15613.765793513106;16069.196540173061;16130.75803862943;16142.596435089099;16168.434104806276;3881.961180388188;3902.843886244547;3908.335582950905;3909.1204478992;3909.9413508797393;128844.3557782205;154613.99999999886;157971;159134;164069;14320.785188222186;14355.282235888164;14432.927835360755;14713.705725885637;14726.263686815617;27833.582496262574;27838.304042398697;27899.023534176205;27988;28569.42861142807;7835.981322428048;7847.372210223169;7888.1323054463965;7936.087349954829;7958.522488650699;4198.748075115442;4277.9091331710215;4333.978330108329;4338;4377.518472967971;4953.876153096201;5038.974805125951;5050.3434553507805;5056.039352523009;5065.56942659878;906.2749800159846;934.4299977014016;938.3572253006712;944.8157609266265;949.292776881228;588.5938702295402;686.8214264291221;687.7386593094592;694.746417557598;723.9674214660353;11148.994374085793;12760.872391275969;12830.358482075859;12907.741845163231;12929;666.5567397680585;676.8984652302096;683.9418649414855;698.5005720909472;699.8390370214879;5309.548688361455;6062.484688801413;6462.709178086998;6581.14445122131;6608.140941677647;109801;134432.9835052475;139108;141292;141639;109516.452417739;110230.89769102367;113300.43349783198;114866;118482.40758796151;91979.54010229906;100802.4959875196;103823.48088259662;103922.96077039283;109278.45360773304;9952.00479951999;11827.940860295643;12477.750444990868;12948.352582371032;13259.270740109387;44522.554774453136;45887.54112458833;47034.29448558343;47054.29418558656;48014.519854801;63347;64343.35656643374;66427.33572664211;67280.66359668366;67671;17342.739858901874;17561.736573951144;17997;18199.636007280045;18449.53876153107;18951.810481895005;19046.714299285246;19153.233870645574;19272.22911083541;19666.21335146578;17650.382236621655;18141.818581814012;18477.445676629723;18639;18729.595280353755;17939.73090403619;18206.544836379195;18733.250669973593;18916.770409923312;18950.147243373805;17265.654686906444;17577.912110439367;17795.19921603505;18012.369567065613;18438.446846594623;9690.75773105678;11858.822117668415;12656.74686506283;12928.870711293142;13007.414666340226;13543.25512096844;14644.707105858037;14672.85327146715;14884.479043233436;14941.178235197169 18:31:42 INFO - PID 2568 | __end_tp_report 18:31:42 INFO - PID 2568 | __start_cc_report 18:31:42 INFO - PID 2568 | _x_x_mozilla_cycle_collect,387 18:31:42 INFO - PID 2568 | __end_cc_report 18:31:42 INFO - PID 2568 | __startTimestamp1497317502489__endTimestamp 18:31:42 INFO - PID 2568 | 18:31:42 INFO - PID 2568 | ------- Summary: start ------- 18:31:42 INFO - PID 2568 | Number of tests: 6 18:31:42 INFO - PID 2568 | 18:31:42 INFO - PID 2568 | [#0] dojo.html Cycles:165 Average:6511.60 Median:6719.23 stddev:817.85 (12.2%) stddev-sans-first:778.65 18:31:42 INFO - PID 2568 | Values: 3224.7 3649.2 3723.2 3850.1 3863.6 5300.6 6346.3 6379.0 6396.9 6411.6 6499.7 6574.8 6577.2 6579.9 6606.3 5847.9 5850.1 5871.6 5982.4 5993.9 5899.4 5995.6 6011.3 6014.1 6029.3 5674.2 5821.5 5872.8 5880.4 5884.9 5753.3 5856.8 5896.2 5906.4 5927.6 4139.0 5754.2 5866.9 5869.5 5870.7 6477.0 6508.7 6523.6 6549.6 6601.8 6771.4 7143.8 7166.9 7179.8 7192.9 6199.9 6763.3 6781.8 6895.6 7053.1 6622.0 6641.2 6670.7 6719.2 6723.4 7413.8 7470.5 7470.9 7474.9 7478.2 6769.1 6859.1 6860.3 7014.9 7024.1 7027.2 7031.1 7042.7 7042.8 7047.3 7015.5 7024.2 7055.5 7100.8 7108.4 6915.1 6940.1 6943.7 6957.8 6974.5 5589.0 5601.4 5605.6 5610.7 5620.8 4893.8 4939.2 4959.4 4975.5 4991.3 6411.0 6462.6 6496.8 6517.3 6530.4 7167.0 7230.2 7231.3 7244.4 7277.8 6638.5 6646.0 6651.2 6665.5 6701.9 6283.9 6328.6 6330.6 6379.9 6401.6 7159.3 7397.4 7418.5 7437.4 7471.4 7092.3 7126.9 7168.4 7249.3 7280.2 7366.4 7461.0 7509.1 7513.9 7516.8 7080.5 7107.8 7111.6 7142.1 7153.8 7300.2 7301.0 7317.6 7318.8 7321.7 6761.9 6831.2 6832.3 7060.4 7068.5 6822.7 6927.9 6980.3 6982.5 7009.8 5595.7 5691.0 6382.1 6773.6 6790.7 5673.9 5734.9 6831.6 6889.4 6938.0 6587.7 6665.8 6689.4 6731.4 6753.1 18:31:42 INFO - PID 2568 | 18:31:42 INFO - PID 2568 | [#1] ext.html Cycles:180 Average:45855.08 Median:196953.23 stddev:82597.32 (41.9%) stddev-sans-first:82759.84 18:31:42 INFO - PID 2568 | Values: 829.1 852.8 862.3 883.7 893.3 38459.4 39260.6 39288.8 39321.2 39352.0 30916.4 35191.6 37584.8 37810.4 37964.6 7725.7 7849.2 8409.7 8556.4 8586.6 8471.2 8542.7 8580.6 8580.9 8584.7 8521.3 8546.4 8572.5 8581.9 8585.7 8562.4 8562.4 8566.0 8579.7 8596.8 7195.6 8416.4 8447.7 8508.2 8512.4 36923.4 37022.6 37035.6 37191.3 37451.3 3613.2 3649.3 3652.6 3656.5 3661.1 30383.0 30460.8 30623.0 30744.8 30813.8 3209.6 3269.1 3279.1 3285.2 3301.7 263963.7 264575.0 265820.0 336491.0 337595.0 47477.0 47655.0 47809.0 48135.5 48255.0 41359.0 49823.3 50048.5 50086.5 50133.2 15812.2 15861.6 15938.9 15964.4 15970.0 12981.7 13041.8 13069.2 13105.1 13106.9 13367.8 13451.9 13489.3 13577.0 13640.6 5841.6 5875.4 5898.4 5931.7 5938.9 993.4 997.4 998.6 1054.2 1056.9 19655.1 23170.3 23388.5 23504.9 24107.7 884.3 1113.2 1114.3 1136.3 1160.4 10803.1 12145.0 14711.0 14800.0 15400.0 268392.0 358053.0 360915.0 361066.0 362731.0 53223.5 53231.7 53312.2 53383.5 53516.7 1342.3 1444.2 1535.6 1538.3 1545.5 20958.3 24077.0 27545.7 29217.0 30809.5 38991.4 39009.6 39231.4 39308.8 39309.2 265216.0 305043.0 307413.0 310453.0 311807.0 29003.7 29165.0 29181.9 29263.0 33586.8 36787.8 40703.0 41107.6 42547.0 43224.0 28009.4 28154.7 29024.9 29099.4 30459.1 29488.6 30662.1 30753.4 30839.4 31058.7 34673.8 35853.5 36620.8 36689.0 36700.6 26656.3 26674.7 29370.0 29572.0 30193.7 22173.7 24002.8 24037.6 24145.3 24183.8 18:31:42 INFO - PID 2568 | 18:31:42 INFO - PID 2568 | [#2] jquery.html Cycles:180 Average:41402.89 Median:11735.28 stddev:103774.78 (884.3%) stddev-sans-first:104020.35 18:31:42 INFO - PID 2568 | Values: 451.4 483.7 540.0 560.6 562.4 22933.2 23397.0 23646.0 24416.9 24562.0 24388.0 25108.0 25307.6 25315.9 25441.1 6458.4 6512.5 6517.2 6569.3 6585.0 5645.6 5749.4 6350.3 6390.0 6409.2 6393.6 6397.9 6399.1 6423.3 6423.6 6377.3 6404.4 6417.3 6420.8 6426.7 5899.8 5906.9 5921.9 5923.5 5935.9 28285.7 29394.6 29970.6 29982.4 30259.5 2238.4 2265.8 2274.3 2280.9 2282.3 14987.6 19304.0 19504.6 19519.0 19522.5 2210.4 2238.7 2279.6 2282.9 2283.6 167587.3 173873.0 174029.0 187131.0 200228.0 14402.3 14490.6 14512.1 15998.2 16058.7 20473.3 20539.9 20574.3 20585.4 20661.7 8320.0 9896.2 10414.8 10435.9 10552.1 9220.9 10110.3 10139.3 10223.8 10233.6 7413.9 9086.3 9204.4 9217.6 9224.6 2325.7 2447.3 2746.8 2751.1 2856.0 977.4 980.9 981.5 1033.3 1035.1 20261.4 20568.8 20696.7 21234.9 21260.1 1013.8 1030.3 1044.9 1068.6 1101.2 10294.2 10723.6 10891.4 11125.3 11315.8 584570.0 592313.0 600818.0 620666.0 626790.0 139807.0 161644.0 167513.0 169991.2 170706.0 1084.4 1127.0 1290.5 1292.3 1321.0 22266.2 23353.8 23680.9 23893.5 23918.9 60398.8 72316.6 74363.6 78895.6 82139.6 94851.0 97321.0 99680.0 102516.5 103006.0 13925.0 16758.0 17024.8 17645.6 17776.7 13814.2 18478.0 18529.0 18795.0 18916.7 14618.8 15312.2 15388.5 15584.0 15593.1 13216.4 13434.0 13918.2 14043.9 14213.9 15760.3 15913.4 15927.8 15944.4 15947.0 15695.5 16737.2 16945.8 16982.8 17181.8 14900.7 14937.5 15004.5 15058.3 15117.3 18:31:42 INFO - PID 2568 | 18:31:42 INFO - PID 2568 | [#3] mootools.html Cycles:180 Average:8755.92 Median:2922.60 stddev:5840.10 (199.8%) stddev-sans-first:5831.05 18:31:42 INFO - PID 2568 | Values: 1501.8 1535.9 1562.7 1564.4 1569.7 14073.6 14586.0 14649.9 14732.6 14752.4 13953.4 14506.3 14642.5 14684.0 14712.2 2942.9 2982.3 3006.1 3018.2 3020.6 2909.2 2938.1 2953.0 2959.5 2965.2 2812.6 2915.2 2959.0 2960.7 2974.1 2965.3 3000.1 3002.9 3004.3 3010.5 2930.0 2942.2 2948.9 2951.5 2959.4 14126.2 14367.3 14441.3 14450.1 14480.6 7931.6 8228.5 8245.4 8250.8 8270.8 4806.9 4942.9 4981.3 5032.3 5147.5 1476.3 1484.3 1495.9 1520.0 1522.5 19445.5 22113.8 22142.0 22154.9 22174.3 5295.0 5890.4 6862.9 6931.5 7082.3 11669.9 13923.8 14902.3 14948.8 14959.8 7686.7 7949.6 7956.7 7974.6 7979.8 6218.8 7070.2 7316.9 7361.4 7375.2 4584.0 4635.5 5312.6 5550.6 5580.4 1611.7 1613.8 1625.5 1630.6 1663.2 749.0 756.5 805.2 822.3 836.9 7204.0 7396.2 8212.8 8697.1 8758.4 982.4 1006.5 1032.2 1042.8 1046.3 4931.6 5025.5 5029.7 5036.4 5411.6 19010.5 19295.3 19702.1 19719.3 19720.4 19546.9 19653.9 19696.3 19790.6 19805.8 9917.9 10049.0 10116.6 10209.0 10230.8 7306.5 8434.0 8485.5 8582.1 8757.3 16793.2 17358.7 17746.7 18059.8 18111.9 14014.7 17105.5 17302.9 18398.5 18570.5 11422.0 11623.3 11639.1 11644.5 11661.5 12939.8 12951.5 12954.2 12974.0 12998.3 10116.9 10214.4 10533.5 10821.7 10931.5 10407.9 10467.8 10842.3 10860.0 10875.0 9647.3 9710.4 9733.5 9743.8 9773.6 6909.7 7011.5 7122.8 7157.1 7371.1 5743.3 7168.4 7256.2 7535.6 7570.8 18:31:42 INFO - PID 2568 | 18:31:42 INFO - PID 2568 | [#4] prototype.html Cycles:180 Average:5501.32 Median:6176.83 stddev:1679.04 (27.2%) stddev-sans-first:1639.78 18:31:42 INFO - PID 2568 | Values: 414.9 457.3 481.2 502.7 502.9 6480.4 6499.0 6524.0 6524.0 6558.9 5185.9 5734.1 6109.6 6262.2 6364.5 4252.3 4753.8 4753.8 4794.5 4798.5 4292.6 4551.6 4741.3 4772.8 4809.4 4763.1 4766.5 4773.2 4794.2 4807.9 4128.8 4533.5 4760.5 4761.9 4775.5 4159.6 4228.4 4232.8 4250.2 4253.6 6188.8 6210.9 6234.6 6265.5 6283.5 4537.8 4734.8 5567.9 5588.0 5637.2 5871.9 6543.2 6745.4 6790.7 6793.9 4759.2 4793.9 4800.0 4815.8 4823.6 7172.9 7178.8 7204.2 7212.2 7272.9 4877.3 4886.5 4901.2 4906.8 4907.9 4290.7 4953.6 4971.8 5007.6 5040.2 4333.9 5279.8 5359.4 5613.1 5646.4 5474.7 6033.6 6194.4 6875.1 6880.6 1456.3 1464.6 1642.3 1676.9 1718.5 956.9 1081.5 1136.8 1164.4 1182.2 6217.7 6315.6 6394.5 6482.4 6524.3 6092.4 6951.8 6962.9 7030.7 7085.3 6164.8 6439.6 6458.8 6477.2 6506.9 3288.8 3528.7 3579.3 3629.7 3646.5 6605.3 7210.1 7267.9 7275.8 7325.5 6383.7 6993.8 7212.1 7244.7 7304.5 6047.0 6442.8 6878.7 7129.5 7277.7 6558.9 6872.4 6892.7 7013.6 7013.9 6878.4 6882.3 6898.7 6913.2 6956.2 5768.2 6294.8 6402.9 7173.9 7209.6 4746.8 4793.4 4805.9 4836.4 4873.6 6088.4 6810.3 6990.2 6991.3 7035.5 5664.1 6788.7 6833.6 6845.4 6846.0 5682.3 6225.8 6799.5 6800.9 6830.5 6668.9 6674.6 6675.5 6736.9 6771.5 5721.7 5867.4 6406.5 6852.0 6902.5 5522.9 5626.8 6318.2 6346.2 6696.2 18:31:42 INFO - PID 2568 | 18:31:42 INFO - PID 2568 | [#5] yui.html Cycles:180 Average:25621.17 Median:18448.45 stddev:38342.33 (207.8%) stddev-sans-first:38404.80 18:31:42 INFO - PID 2568 | Values: 856.8 892.2 894.5 899.1 913.5 17973.6 18146.0 18349.4 18404.1 18447.4 15315.8 15316.0 15325.2 15908.7 18067.1 1038.5 1067.5 1068.8 1068.8 1070.8 1069.0 1071.1 1072.4 1073.6 1075.1 1132.1 1132.9 1133.2 1134.1 1135.3 989.3 1098.9 1104.9 1126.3 1131.7 797.6 798.3 800.8 801.1 802.3 17840.6 18522.9 18687.9 18698.0 18704.2 18329.5 18601.6 18742.2 18750.4 18775.2 15613.8 16069.2 16130.8 16142.6 16168.4 3882.0 3902.8 3908.3 3909.1 3909.9 128844.4 154614.0 157971.0 159134.0 164069.0 14320.8 14355.3 14432.9 14713.7 14726.3 27833.6 27838.3 27899.0 27988.0 28569.4 7836.0 7847.4 7888.1 7936.1 7958.5 4198.7 4277.9 4334.0 4338.0 4377.5 4953.9 5039.0 5050.3 5056.0 5065.6 906.3 934.4 938.4 944.8 949.3 588.6 686.8 687.7 694.7 724.0 11149.0 12760.9 12830.4 12907.7 12929.0 666.6 676.9 683.9 698.5 699.8 5309.5 6062.5 6462.7 6581.1 6608.1 109801.0 134433.0 139108.0 141292.0 141639.0 109516.5 110230.9 113300.4 114866.0 118482.4 91979.5 100802.5 103823.5 103923.0 109278.5 9952.0 11827.9 12477.8 12948.4 13259.3 44522.6 45887.5 47034.3 47054.3 48014.5 63347.0 64343.4 66427.3 67280.7 67671.0 17342.7 17561.7 17997.0 18199.6 18449.5 18951.8 19046.7 19153.2 19272.2 19666.2 17650.4 18141.8 18477.4 18639.0 18729.6 17939.7 18206.5 18733.3 18916.8 18950.1 17265.7 17577.9 17795.2 18012.4 18438.4 9690.8 11858.8 12656.7 12928.9 13007.4 13543.3 14644.7 14672.9 14884.5 14941.2 18:31:42 INFO - PID 2568 | -------- Summary: end -------- 18:31:42 INFO - PID 2568 | 18:31:43 INFO - TEST-INFO | 2568: exit 0 18:31:43 INFO - TEST-OK | dromaeo_css | took 1142180ms 18:31:43 INFO - TEST-START | kraken 18:31:43 INFO - operating with platform_type : mac_ 18:31:43 INFO - Initialising browser for kraken test... 18:31:43 INFO - Application command: /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:59413/getInfo.html -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmpmiCSrL/profile 18:31:43 INFO - TEST-INFO | started process 2575 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:59413/getInfo.html -foreground) 18:32:15 INFO - TEST-INFO | 2575: killed by SIGTERM 18:32:15 INFO - Browser initialized. 18:32:15 INFO - Running cycle 1/1 for kraken test... 18:32:15 INFO - TEST-INFO | started process 2578 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmpmiCSrL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:32:17 INFO - PID 2578 | 2017-06-12 18:32:17.502 plugin-container[2579:39888] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x933f, name = 'com.apple.tsm.portname' 18:32:17 INFO - PID 2578 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:32:17 INFO - PID 2578 | 2017-06-12 18:32:17.503 plugin-container[2579:39888] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x990b, name = 'com.apple.CFPasteboardClient' 18:32:17 INFO - PID 2578 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:32:17 INFO - PID 2578 | 2017-06-12 18:32:17.503 plugin-container[2579:39888] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:32:27 INFO - PID 2578 | RSS: Main: 184799232 18:32:27 INFO - PID 2578 | 18:32:30 INFO - PID 2578 | Cycle 1(1): loaded http://localhost:59413/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59413/tests/kraken/driver.html?testName=audio-beat-detection) 18:32:31 INFO - PID 2578 | RSS: Main: 197615616 18:32:31 INFO - PID 2578 | 18:32:33 INFO - PID 2578 | Cycle 1(1): loaded http://localhost:59413/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59413/tests/kraken/driver.html?testName=audio-dft) 18:32:34 INFO - PID 2578 | RSS: Main: 207015936 18:32:34 INFO - PID 2578 | 18:32:38 INFO - PID 2578 | Cycle 1(1): loaded http://localhost:59413/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59413/tests/kraken/driver.html?testName=audio-fft) 18:32:38 INFO - PID 2578 | RSS: Main: 209346560 18:32:38 INFO - PID 2578 | 18:32:40 INFO - PID 2578 | Cycle 1(1): loaded http://localhost:59413/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59413/tests/kraken/driver.html?testName=audio-oscillator) 18:32:41 INFO - PID 2578 | RSS: Main: 211951616 18:32:41 INFO - PID 2578 | 18:32:43 INFO - PID 2578 | Cycle 1(1): loaded http://localhost:59413/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59413/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:32:43 INFO - PID 2578 | RSS: Main: 213430272 18:32:43 INFO - PID 2578 | 18:32:46 INFO - PID 2578 | Cycle 1(1): loaded http://localhost:59413/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59413/tests/kraken/driver.html?testName=imaging-darkroom) 18:32:47 INFO - PID 2578 | RSS: Main: 219521024 18:32:47 INFO - PID 2578 | 18:32:50 INFO - PID 2578 | Cycle 1(1): loaded http://localhost:59413/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59413/tests/kraken/driver.html?testName=imaging-desaturate) 18:32:51 INFO - PID 2578 | RSS: Main: 219721728 18:32:51 INFO - PID 2578 | 18:32:54 INFO - PID 2578 | Cycle 1(1): loaded http://localhost:59413/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59413/tests/kraken/driver.html?testName=json-parse-financial) 18:32:54 INFO - PID 2578 | RSS: Main: 219496448 18:32:54 INFO - PID 2578 | 18:32:56 INFO - PID 2578 | Cycle 1(1): loaded http://localhost:59413/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59413/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:32:56 INFO - PID 2578 | RSS: Main: 219496448 18:32:56 INFO - PID 2578 | 18:32:58 INFO - PID 2578 | Cycle 1(1): loaded http://localhost:59413/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59413/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:32:59 INFO - PID 2578 | RSS: Main: 219508736 18:32:59 INFO - PID 2578 | 18:33:01 INFO - PID 2578 | Cycle 1(1): loaded http://localhost:59413/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59413/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:33:01 INFO - PID 2578 | RSS: Main: 219570176 18:33:01 INFO - PID 2578 | 18:33:04 INFO - PID 2578 | Cycle 1(1): loaded http://localhost:59413/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59413/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:33:05 INFO - PID 2578 | RSS: Main: 219836416 18:33:05 INFO - PID 2578 | 18:33:08 INFO - PID 2578 | Cycle 1(1): loaded http://localhost:59413/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59413/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:33:09 INFO - PID 2578 | RSS: Main: 219877376 18:33:09 INFO - PID 2578 | 18:33:10 INFO - PID 2578 | Cycle 1(1): loaded http://localhost:59413/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59413/tests/kraken/driver.html?testName=ai-astar) 18:33:11 INFO - PID 2578 | RSS: Main: 221564928 18:33:11 INFO - PID 2578 | 18:33:11 INFO - PID 2578 | __start_tp_report 18:33:11 INFO - PID 2578 | _x_x_mozilla_page_load 18:33:11 INFO - PID 2578 | _x_x_mozilla_page_load_details 18:33:11 INFO - PID 2578 | |i|pagename|runs| 18:33:11 INFO - PID 2578 | |0;ai-astar;103;115;106;127;115;115;115;139;166;132 18:33:11 INFO - PID 2578 | |1;audio-beat-detection;139;132;130;170;141;165;135;128;177;127 18:33:11 INFO - PID 2578 | |2;audio-dft;202;202;199;203;205;201;284;277;221;199 18:33:11 INFO - PID 2578 | |3;audio-fft;68;81;75;147;60;52;49;68;160;85 18:33:11 INFO - PID 2578 | |4;audio-oscillator;73;72;109;70;71;84;111;68;71;111 18:33:11 INFO - PID 2578 | |5;imaging-gaussian-blur;91;107;136;91;92;91;92;90;93;92 18:33:11 INFO - PID 2578 | |6;imaging-darkroom;96;126;113;150;149;153;97;97;122;106 18:33:11 INFO - PID 2578 | |7;imaging-desaturate;88;94;91;93;90;91;90;92;91;94 18:33:11 INFO - PID 2578 | |8;json-parse-financial;49;48;50;49;50;54;52;52;51;52 18:33:11 INFO - PID 2578 | |9;json-stringify-tinderbox;55;54;57;63;66;68;63;57;58;57 18:33:11 INFO - PID 2578 | |10;stanford-crypto-aes;79;78;79;99;76;86;80;78;78;81 18:33:11 INFO - PID 2578 | |11;stanford-crypto-ccm;140;125;135;146;144;140;132;170;146;159 18:33:11 INFO - PID 2578 | |12;stanford-crypto-pbkdf2;177;176;176;175;174;174;181;216;218;175 18:33:11 INFO - PID 2578 | |13;stanford-crypto-sha256-iterative;64;63;66;65;65;65;62;61;65;62 18:33:11 INFO - PID 2578 | __end_tp_report 18:33:11 INFO - PID 2578 | __start_cc_report 18:33:11 INFO - PID 2578 | _x_x_mozilla_cycle_collect,988 18:33:11 INFO - PID 2578 | __end_cc_report 18:33:11 INFO - PID 2578 | __startTimestamp1497317590999__endTimestamp 18:33:11 INFO - PID 2578 | 18:33:11 INFO - PID 2578 | ------- Summary: start ------- 18:33:11 INFO - PID 2578 | Number of tests: 14 18:33:11 INFO - PID 2578 | 18:33:11 INFO - PID 2578 | [#0] ai-astar Cycles:10 Average:123.30 Median:121.00 stddev:18.70 (15.5%) stddev-sans-first:18.33 18:33:11 INFO - PID 2578 | Values: 103.0 115.0 106.0 127.0 115.0 115.0 115.0 139.0 166.0 132.0 18:33:11 INFO - PID 2578 | 18:33:11 INFO - PID 2578 | [#1] audio-beat-detection Cycles:10 Average:144.40 Median:140.00 stddev:18.87 (13.5%) stddev-sans-first:19.91 18:33:11 INFO - PID 2578 | Values: 139.0 132.0 130.0 170.0 141.0 165.0 135.0 128.0 177.0 127.0 18:33:11 INFO - PID 2578 | 18:33:11 INFO - PID 2578 | [#2] audio-dft Cycles:10 Average:219.30 Median:204.00 stddev:32.91 (16.1%) stddev-sans-first:34.30 18:33:11 INFO - PID 2578 | Values: 202.0 202.0 199.0 203.0 205.0 201.0 284.0 277.0 221.0 199.0 18:33:11 INFO - PID 2578 | 18:33:11 INFO - PID 2578 | [#3] audio-fft Cycles:10 Average:84.50 Median:68.00 stddev:38.25 (56.3%) stddev-sans-first:40.11 18:33:11 INFO - PID 2578 | Values: 68.0 81.0 75.0 147.0 60.0 52.0 49.0 68.0 160.0 85.0 18:33:11 INFO - PID 2578 | 18:33:11 INFO - PID 2578 | [#4] audio-oscillator Cycles:10 Average:84.00 Median:71.00 stddev:18.67 (26.3%) stddev-sans-first:19.38 18:33:11 INFO - PID 2578 | Values: 73.0 72.0 109.0 70.0 71.0 84.0 111.0 68.0 71.0 111.0 18:33:11 INFO - PID 2578 | 18:33:11 INFO - PID 2578 | [#5] imaging-gaussian-blur Cycles:10 Average:97.50 Median:91.50 stddev:14.40 (15.7%) stddev-sans-first:15.08 18:33:11 INFO - PID 2578 | Values: 91.0 107.0 136.0 91.0 92.0 91.0 92.0 90.0 93.0 92.0 18:33:11 INFO - PID 2578 | 18:33:11 INFO - PID 2578 | [#6] imaging-darkroom Cycles:10 Average:120.90 Median:151.50 stddev:22.95 (15.1%) stddev-sans-first:22.51 18:33:11 INFO - PID 2578 | Values: 96.0 126.0 113.0 150.0 149.0 153.0 97.0 97.0 122.0 106.0 18:33:11 INFO - PID 2578 | 18:33:11 INFO - PID 2578 | [#7] imaging-desaturate Cycles:10 Average:91.40 Median:91.50 stddev:1.90 (2.1%) stddev-sans-first:1.56 18:33:11 INFO - PID 2578 | Values: 88.0 94.0 91.0 93.0 90.0 91.0 90.0 92.0 91.0 94.0 18:33:11 INFO - PID 2578 | 18:33:11 INFO - PID 2578 | [#8] json-parse-financial Cycles:10 Average:50.70 Median:51.50 stddev:1.83 (3.6%) stddev-sans-first:1.83 18:33:11 INFO - PID 2578 | Values: 49.0 48.0 50.0 49.0 50.0 54.0 52.0 52.0 51.0 52.0 18:33:11 INFO - PID 2578 | 18:33:11 INFO - PID 2578 | [#9] json-stringify-tinderbox Cycles:10 Average:59.80 Median:60.50 stddev:4.83 (8.0%) stddev-sans-first:4.80 18:33:11 INFO - PID 2578 | Values: 55.0 54.0 57.0 63.0 66.0 68.0 63.0 57.0 58.0 57.0 18:33:11 INFO - PID 2578 | 18:33:11 INFO - PID 2578 | [#10] stanford-crypto-aes Cycles:10 Average:81.40 Median:79.50 stddev:6.74 (8.5%) stddev-sans-first:7.09 18:33:11 INFO - PID 2578 | Values: 79.0 78.0 79.0 99.0 76.0 86.0 80.0 78.0 78.0 81.0 18:33:11 INFO - PID 2578 | 18:33:11 INFO - PID 2578 | [#11] stanford-crypto-ccm Cycles:10 Average:143.70 Median:145.00 stddev:13.02 (9.0%) stddev-sans-first:13.74 18:33:11 INFO - PID 2578 | Values: 140.0 125.0 135.0 146.0 144.0 140.0 132.0 170.0 146.0 159.0 18:33:11 INFO - PID 2578 | 18:33:11 INFO - PID 2578 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.20 Median:176.50 stddev:17.41 (9.9%) stddev-sans-first:18.27 18:33:11 INFO - PID 2578 | Values: 177.0 176.0 176.0 175.0 174.0 174.0 181.0 216.0 218.0 175.0 18:33:11 INFO - PID 2578 | 18:33:11 INFO - PID 2578 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.80 Median:65.00 stddev:1.69 (2.6%) stddev-sans-first:1.79 18:33:11 INFO - PID 2578 | Values: 64.0 63.0 66.0 65.0 65.0 65.0 62.0 61.0 65.0 62.0 18:33:11 INFO - PID 2578 | -------- Summary: end -------- 18:33:11 INFO - PID 2578 | 18:33:11 INFO - TEST-INFO | 2578: exit 0 18:33:11 INFO - TEST-OK | kraken | took 88746ms 18:33:11 INFO - SUITE-END | took 1230s 18:33:12 INFO - Completed test suite (00:20:31) 18:33:12 INFO - javascript benchmark 18:33:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8621.147752524388, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3224.6775322467747, 3649.1606930405987, 3723.2181241939193, 3850.0952276215085, 3863.613638636137, 5300.575953923687, 6346.30190679025, 6379.011253255747, 6396.936030639692, 6411.551191416603, 6499.707513161908, 6574.802755917318, 6577.177852768404, 6579.934200657992, 6606.339366063391, 5847.912281315784, 5850.122481627758, 5871.559633027518, 5982.401759824026, 5993.9400605993815, 5899.439553242435, 5995.640261584319, 6011.278646562397, 6014.0978853172155, 6029.336772954972, 5674.2339784129435, 5821.50517206038, 5872.823815285548, 5880.441358070955, 5884.882302353951, 5753.280839895013, 5856.795012174596, 5896.204012458306, 5906.409359064102, 5927.614705044159, 4139.0, 5754.165645981309, 5866.941330586725, 5869.4717475427415, 5870.735816888207, 6477.028445733131, 6508.674566271715, 6523.641199734013, 6549.5742776719835, 6601.768938087144, 6771.424428923548, 7143.785686429415, 7166.892496612555, 7179.784606461814, 7192.928070719278, 6199.88402087624, 6763.323667633246, 6781.796546103625, 6895.55178913369, 7053.0830991971225, 6621.966890165567, 6641.169853768279, 6670.699818508178, 6719.227288861844, 6723.428508576728, 7413.777586672409, 7470.4770666052855, 7470.925290747023, 7474.887876681854, 7478.2147874473485, 6769.120014398097, 6859.142607174103, 6860.348266914635, 7014.894776578355, 7024.086868707035, 7027.227005029443, 7031.08595882532, 7042.718291268395, 7042.823929401806, 7047.295270473014, 7015.473839462061, 7024.192217895007, 7055.541389809646, 7100.822479438056, 7108.3602475777425, 6915.135608048994, 6940.063091482686, 6943.652817359111, 6957.79126626202, 6974.511784175059, 5588.9720551397795, 5601.439856014366, 5605.607607467438, 5610.719464026783, 5620.803271885465, 4893.779779909913, 4939.234418665113, 4959.429665588431, 4975.4527002029745, 4991.276264941564, 6410.967945160338, 6462.644554549451, 6496.837579060561, 6517.283098859121, 6530.379613936669, 7167.0, 7230.168530619047, 7231.349178573953, 7244.384227340735, 7277.818054548573, 6638.502112341497, 6645.966770166118, 6651.23510796255, 6665.533412661067, 6701.865962680719, 6283.937160628335, 6328.588641738273, 6330.556861019684, 6379.872402552016, 6401.615903045832, 7159.319864612853, 7397.4082073435075, 7418.517796343221, 7437.405007599487, 7471.402287816962, 7092.255313192247, 7126.928730712627, 7168.390686791681, 7249.311315425026, 7280.162781280009, 7366.4106871449085, 7461.030066091591, 7509.061367329084, 7513.924860751323, 7516.774496765106, 7080.504364694422, 7107.82230444243, 7111.573305601681, 7142.071530700976, 7153.785386438415, 7300.221354490384, 7300.96349518249, 7317.597532135784, 7318.817029574303, 7321.707131714672, 6761.86476270462, 6831.180258368829, 6832.282610326043, 7060.364567188978, 7068.540544864567, 6822.727090916506, 6927.93072069293, 6980.301969802979, 6982.476314276348, 7009.754658586925, 5595.692236927008, 5690.971545142248, 6382.138411314599, 6773.593584384953, 6790.72837086511, 5673.886522269614, 5734.856628584152, 6831.6242606657115, 6889.414399775875, 6937.965310173417, 6587.736490540325, 6665.800025999228, 6689.431398331197, 6731.360520750521, 6753.0883330751685], "value": 6455.148490473528, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [829.100426037749, 852.7953291210112, 862.3446180902514, 883.6612532014, 893.3433926064341, 38459.42310865339, 39260.60739392605, 39288.80355598222, 39321.21357572847, 39352.0, 30916.381672366595, 35191.648083519154, 37584.81207593965, 37810.43284350737, 37964.620353796454, 7725.652345644446, 7849.215078492133, 8409.663613455454, 8556.401051926368, 8586.613602387877, 8471.237588617023, 8542.658293668246, 8580.570971451434, 8580.871286930702, 8584.74245772628, 8521.318294536453, 8546.44448110874, 8572.528510931896, 8581.871271930926, 8585.742427727148, 8562.357823163256, 8562.400631955765, 8566.0, 8579.656813727475, 8596.78508037304, 7195.568265904062, 8416.410851240416, 8447.662093516314, 8508.191721786483, 8512.404131710782, 36923.4461483078, 37022.62977370219, 37035.62964370376, 37191.256174876624, 37451.25097498035, 3613.241219343941, 3649.3066317399876, 3652.6164752701116, 3656.542932133483, 3661.1213308805695, 30383.0, 30460.8476957616, 30623.0, 30744.8462757687, 30813.767449302224, 3209.646938836726, 3269.0683155300862, 3279.114639047468, 3285.178705323669, 3301.735861131104, 263963.6801815979, 264575.0, 265820.0, 336491.0, 337595.0, 47477.0, 47655.0, 47809.04381912342, 48135.518644813805, 48255.0, 41359.0, 49823.25265121026, 50048.49854504371, 50086.49913500891, 50133.24800128001, 15812.209389530477, 15861.603459913595, 15938.920305398398, 15964.441244556385, 15970.0, 12981.675458113623, 13041.804372934414, 13069.215847049018, 13105.082644214814, 13106.934465327613, 13367.799483007762, 13451.932740336431, 13489.325533723471, 13577.0, 13640.590782276548, 5841.649501029867, 5875.441833025856, 5898.439648233411, 5931.703414829328, 5938.8812223755285, 993.4039576254335, 997.3965750720854, 998.5905778630727, 1054.2409465185056, 1056.8995945385177, 19655.115519801642, 23170.304890853302, 23388.532229355318, 23504.882475587856, 24107.674077925883, 884.3072352857357, 1113.1984970820995, 1114.3035602748282, 1136.2557524821254, 1160.3502038858267, 10803.081738052197, 12145.0, 14711.0, 14800.0, 15399.99900006496, 268392.0, 358053.0, 360915.0, 361066.0, 362731.0, 53223.467765322624, 53231.669208270105, 53312.20031699527, 53383.46616533863, 53516.73241633767, 1342.2818791946263, 1444.248990524921, 1535.5700403886706, 1538.2923855026813, 1545.474538656862, 20958.266460673804, 24077.0, 27545.724542754317, 29216.977405790694, 30809.537856931714, 38991.415128772525, 39009.6099039006, 39231.41152882766, 39308.80345598254, 39309.2138157241, 265216.0, 305043.0, 307413.0, 310453.0, 311807.0, 29003.709962900102, 29165.0, 29181.854090729412, 29263.0, 33586.83206583952, 36787.816060919526, 40702.9824254396, 41107.56123535782, 42547.0, 43224.0, 28009.43981120407, 28154.71845281603, 29024.854875725487, 29099.41801164007, 30459.086227413212, 29488.557671634513, 30662.08013759591, 30753.38493230078, 30839.383212336077, 31058.689413106484, 34673.826630866686, 35853.462198066525, 36620.81689591535, 36689.0, 36700.63299366972, 26656.333591660365, 26674.733252668, 29369.972050978115, 29571.964981225552, 30193.69806301909, 22173.66739498941, 24002.759972400054, 24037.639435408833, 24145.275641730776, 24183.75816241815], "value": 17531.228309538114, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [451.3568165364356, 483.73152900140406, 539.9973000134999, 560.5543592843688, 562.4122791682692, 22933.19733809315, 23397.0, 23646.0, 24416.877915610443, 24562.0, 24388.0, 25108.0, 25307.62038569423, 25315.873420632917, 25441.10956116536, 6458.354164583539, 6512.544121911468, 6517.217933847921, 6569.277379488276, 6585.0, 5645.633033852807, 5749.425057494259, 6350.333215012426, 6390.0, 6409.1668083149125, 6393.552451328409, 6397.936020639804, 6399.072134540512, 6423.261324947644, 6423.614583125004, 6377.33038031007, 6404.42360187583, 6417.262014868304, 6420.807375778711, 6426.710798014101, 5899.823005309848, 5906.881862362771, 5921.851953701149, 5923.5261179106, 5935.910961335583, 28285.717142828515, 29394.559081613792, 29970.55044174339, 29982.400351992837, 30259.546106808415, 2238.4292005538646, 2265.8413911026237, 2274.3290729234914, 2280.908763649452, 2282.2696737044153, 14987.625309367244, 19304.034798260032, 19504.609907801907, 19519.0, 19522.511937201685, 2210.414240226325, 2238.653008783625, 2279.6466547685136, 2282.931512054641, 2283.5775381554467, 167587.32412675838, 173873.0, 174029.0, 187131.0, 200228.0, 14402.279886005657, 14490.582402744016, 14512.129272243488, 15998.200089995453, 16058.67882642364, 20473.283435079702, 20539.897300513403, 20574.279900203423, 20585.382438526867, 20661.690074648894, 8319.958400208081, 9896.208303335716, 10414.791704166026, 10435.91281743639, 10552.050315471644, 9220.90779092214, 10110.342827716322, 10139.290249682452, 10223.846642300372, 10233.641822536325, 7413.851722965618, 9086.273098152133, 9204.447733136034, 9217.631294748135, 9224.584893679801, 2325.662778897084, 2447.3147518694796, 2746.8489233092373, 2751.119641714632, 2855.971440285612, 977.4379731654327, 980.9264305176997, 981.5299278113271, 1033.34382667007, 1035.11497669493, 20261.392158235278, 20568.794312056987, 20696.689549656767, 21234.938253087592, 21260.149594016377, 1013.8479228115694, 1030.3302853145515, 1044.8798388185303, 1068.5672302717412, 1101.1576144249655, 10294.227932905062, 10723.624673136403, 10891.400972946563, 11125.332480051062, 11315.773684526263, 584570.0, 592313.0, 600818.0, 620666.0, 626790.0, 139807.0, 161644.0, 167513.0, 169991.150044249, 170706.0, 1084.38732116354, 1127.0420142878652, 1290.4773566705312, 1292.2892409174917, 1321.042244372822, 22266.22068227604, 23353.76646233584, 23680.860940705337, 23893.522129556965, 23918.88040559786, 60398.79202416015, 72316.63841680757, 74363.62818185874, 78895.60552197431, 82139.5893020555, 94851.0, 97321.0, 99680.0, 102516.48741756243, 103006.0, 13925.0, 16758.0, 17024.829751702324, 17645.647087058445, 17776.733349000035, 13814.240216788174, 18477.983710895493, 18529.0, 18795.0, 18916.716249256, 14618.780718289447, 15312.234388280764, 15388.46140385126, 15584.0, 15593.064416135068, 13216.405261763051, 13434.0, 13918.164910105426, 14043.859561404664, 14213.857861421668, 15760.290786914387, 15913.363465461714, 15927.840721592636, 15944.36222551085, 15947.0, 15695.450659226872, 16737.246823893198, 16945.830541694424, 16982.83017169862, 17181.828181718523, 14900.701985960437, 14937.477188298357, 15004.549863504113, 15058.316024924074, 15117.319720612819], "value": 12210.596278056002, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1501.797257370255, 1535.8694510966568, 1562.7419926958794, 1564.3507944203163, 1569.6782159657275, 14073.577792666223, 14586.0, 14649.92675036626, 14732.6316842079, 14752.409903603857, 13953.44186232552, 14506.274686265671, 14642.487512937023, 14684.045537040112, 14712.190829504372, 2942.882284708609, 2982.2544363909024, 3006.0681188831422, 3018.170003249102, 3020.607321048272, 2909.1708862974056, 2938.1038783171125, 2952.9852350738215, 2959.482090634141, 2965.24386281499, 2812.5640525718445, 2915.169176784617, 2959.0383125484304, 2960.6595241547175, 2974.0780358088955, 2965.2735079905347, 3000.11496608501, 3002.894898678536, 3004.263955330936, 3010.458117538842, 2930.033089080598, 2942.2497263197747, 2948.9262768430744, 2951.453981013498, 2959.422912532057, 14126.152430854181, 14367.281635918162, 14441.277936103257, 14450.132992020512, 14480.637984050483, 7931.643076061591, 8228.50628962264, 8245.422820402575, 8250.752477425687, 8270.751877443687, 4806.927896081562, 4942.9011419771405, 4981.302617633534, 5032.320636714034, 5147.510986456281, 1476.2544914817947, 1484.2504535209757, 1495.9177245251615, 1520.0347779160202, 1522.543237028898, 19445.513862153417, 22113.778862211333, 22142.0, 22154.88922555377, 22174.334769956928, 5294.97352513235, 5890.440408161217, 6862.9313706863295, 6931.514793964475, 7082.29177082298, 11669.88330116688, 13923.791143132861, 14902.329395177243, 14948.775768363483, 14959.775603365959, 7686.730964416219, 7949.562774047367, 7956.681732730742, 7974.641141148662, 7979.760607181794, 6218.844528886814, 7070.222275549685, 7316.890246646304, 7361.411087113018, 7375.225601311785, 4584.0, 4635.513271106485, 5312.628116031918, 5550.555955523549, 5580.414056524088, 1611.7260065788844, 1613.8138468225845, 1625.5123462961064, 1630.649410376751, 1663.168415792104, 748.9812754681068, 756.4742503959695, 805.1666525146445, 822.2558584481054, 836.8827615133742, 7204.0, 7396.186419493852, 8212.8357432851, 8697.086805885418, 8758.430702004349, 982.3860087445347, 1006.4766321512773, 1032.1587905856702, 1042.7549525861416, 1046.2885238038207, 4931.61086632897, 5025.547700706881, 5029.698218106925, 5036.370453693288, 5411.56707463402, 19010.52473688169, 19295.324663636704, 19702.113404896812, 19719.309824156087, 19720.40838774839, 19546.90226548858, 19653.901730491256, 19696.31062912791, 19790.60418791616, 19805.801941980397, 9917.851232231522, 10049.0, 10116.59533618647, 10209.030142136486, 10230.846537301946, 7306.525075870051, 8433.95783021081, 8485.490870547786, 8582.055973842747, 8757.255633271243, 16793.244304006592, 17358.652826943642, 17746.733798993282, 18059.81940180634, 18111.909440452713, 14014.668606482368, 17105.486835394957, 17302.875313104607, 18398.54003649866, 18570.535736606693, 11422.0, 11623.302601843916, 11639.127065470293, 11644.53421863116, 11661.475233614678, 12939.805902911652, 12951.481940722642, 12954.222746635232, 12974.0, 12998.285094319903, 10116.89883101189, 10214.387136771817, 10533.473326333806, 10821.729456763329, 10931.45342732876, 10407.947960260453, 10467.790644187226, 10842.349459032483, 10860.0, 10875.0, 9647.276454265817, 9710.368826026286, 9733.513324333613, 9743.756406089904, 9773.609055637695, 6909.654517274217, 7011.50919435655, 7122.786316410516, 7157.141143062866, 7371.078615173104, 5743.339515955551, 7168.354848063699, 7256.1655409628565, 7535.623218839146, 7570.810729731801], "value": 6473.802471412653, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [414.93983372411003, 457.29804749709206, 481.1843924547891, 502.7084291111156, 502.90193412284594, 6480.3843634854675, 6498.967505162474, 6523.9673801630925, 6523.967380163104, 6558.868822623551, 5185.870353241171, 5734.139879018139, 6109.5723299369065, 6262.248530176374, 6364.490840732742, 4252.255855225323, 4753.762311884392, 4753.809847606091, 4794.520547945195, 4798.496157903405, 4292.6351260142765, 4551.611758413683, 4741.336212930192, 4772.785224664881, 4809.398825146856, 4763.071201115784, 4766.547178018082, 4773.18855794514, 4794.2329227323635, 4807.903841923159, 4128.752274863503, 4533.523979982104, 4760.476347601761, 4761.880952976168, 4775.522447755231, 4159.646430053449, 4228.386883901848, 4232.809523571446, 4250.234957707611, 4253.574642535722, 6188.81433556994, 6210.875782484325, 6234.563580549363, 6265.530085243579, 6283.497320214372, 4537.818487260505, 4734.827665464113, 5567.916481252784, 5587.972060139714, 5637.210790489335, 5871.900331429933, 6543.247526534462, 6745.3591908768585, 6790.660466976681, 6793.898091528631, 4759.214729569617, 4793.9280910786365, 4799.984006717187, 4815.783289751969, 4823.565879070866, 7172.928270717278, 7178.841309823713, 7204.17152027513, 7212.170600380919, 7272.927270727331, 4877.317175595419, 4886.462489126193, 4901.166801643729, 4906.779194936236, 4907.901841963141, 4290.699651024398, 4953.628477864175, 4971.825986090469, 5007.649464537448, 5040.193569029011, 4333.8699839004885, 5279.838435544174, 5359.437259087817, 5613.073842815971, 5646.35066967304, 5474.698891560923, 6033.607815491979, 6194.4115309045565, 6875.074979005886, 6880.552764070319, 1456.3300881594373, 1464.6045567696663, 1642.3348543839763, 1676.924538395775, 1718.4672751447092, 956.9138777509918, 1081.4808891731855, 1136.8237923121928, 1164.4177911044478, 1182.196106647488, 6217.6891155442045, 6315.5894866833505, 6394.488440924715, 6482.35176482348, 6524.314946930599, 6092.39076092396, 6951.7914462566205, 6962.911126661981, 7030.683619237045, 7085.3156358693195, 6164.845878852975, 6439.6495279222745, 6458.773942911976, 6477.157969464034, 6506.902396463962, 3288.7831502344284, 3528.7000604948353, 3579.319929213441, 3629.7459177857822, 3646.4894914711963, 6605.2918303495335, 7210.098737657793, 7267.854642907112, 7275.818104547323, 7325.523840950321, 6383.71273292703, 6993.755218567326, 7212.098487689039, 7244.710211591478, 7304.488685791944, 6046.9720147574135, 6442.8389290268115, 6878.65606719662, 7129.465290103263, 7277.7452789153185, 6558.901616475852, 6872.381485666314, 6892.724291028503, 7013.649317534001, 7013.859722805413, 6878.415334696607, 6882.311768823078, 6898.724051038103, 6913.2371245332115, 6956.2000369958105, 5768.192453056491, 6294.779682711083, 6402.903956440564, 7173.892391614026, 7209.567425954459, 4746.7863946121815, 4793.400824896888, 4805.855824325136, 4836.395450568679, 4873.586659868598, 6088.4215999479975, 6810.28492008332, 6990.161180658353, 6991.3008699131715, 7035.472339574655, 5664.130836823921, 6788.676208139365, 6833.58998460094, 6845.383915447634, 6846.0, 5682.318121825408, 6225.8132256032395, 6799.456043516606, 6800.863982720219, 6830.45356371499, 6668.933310666831, 6674.632895190811, 6675.465962723068, 6736.898946515708, 6771.458283337222, 5721.742521586455, 5867.442592953663, 6406.519511036598, 6851.965740171467, 6902.482313826584, 5522.9447705524035, 5626.774929002794, 6318.178636777189, 6346.238451385863, 6696.1629796275465], "value": 4995.160672286987, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [856.8157846063099, 892.1524551675908, 894.4856707393251, 899.0604817965226, 913.4975763330169, 17973.640527189447, 18146.0, 18349.35777247795, 18404.079796010195, 18447.354342598013, 15315.770263446057, 15316.004459710111, 15325.233738313096, 15908.681826363465, 18067.096645167756, 1038.532660302863, 1067.4929408530963, 1068.7648717282186, 1068.770214403903, 1070.84472751451, 1068.9893101068967, 1071.1056268016216, 1072.4208927179313, 1073.5652060915343, 1075.0539525217798, 1132.122604981142, 1132.9150313726461, 1133.2294040052761, 1134.0927258193453, 1135.262079648231, 989.3470309595668, 1098.9175811814066, 1104.9116070714324, 1126.2510430563666, 1131.7340425000118, 797.6370751308141, 798.2815466080574, 800.8278220182689, 801.1067659559608, 802.3340627279345, 17840.600782456197, 18522.90738546312, 18687.878727276406, 18698.0, 18704.158312875952, 18329.541761456072, 18601.627967440574, 18742.1566029529, 18750.437486875417, 18775.24899004038, 15613.765793513106, 16069.196540173061, 16130.75803862943, 16142.596435089099, 16168.434104806276, 3881.961180388188, 3902.843886244547, 3908.335582950905, 3909.1204478992, 3909.9413508797393, 128844.3557782205, 154613.99999999886, 157971.0, 159134.0, 164069.0, 14320.785188222186, 14355.282235888164, 14432.927835360755, 14713.705725885637, 14726.263686815617, 27833.582496262574, 27838.304042398697, 27899.023534176205, 27988.0, 28569.42861142807, 7835.981322428048, 7847.372210223169, 7888.1323054463965, 7936.087349954829, 7958.522488650699, 4198.748075115442, 4277.9091331710215, 4333.978330108329, 4338.0, 4377.518472967971, 4953.876153096201, 5038.974805125951, 5050.3434553507805, 5056.039352523009, 5065.56942659878, 906.2749800159846, 934.4299977014016, 938.3572253006712, 944.8157609266265, 949.292776881228, 588.5938702295402, 686.8214264291221, 687.7386593094592, 694.746417557598, 723.9674214660353, 11148.994374085793, 12760.872391275969, 12830.358482075859, 12907.741845163231, 12929.0, 666.5567397680585, 676.8984652302096, 683.9418649414855, 698.5005720909472, 699.8390370214879, 5309.548688361455, 6062.484688801413, 6462.709178086998, 6581.14445122131, 6608.140941677647, 109801.0, 134432.9835052475, 139108.0, 141292.0, 141639.0, 109516.452417739, 110230.89769102367, 113300.43349783198, 114866.0, 118482.40758796151, 91979.54010229906, 100802.4959875196, 103823.48088259662, 103922.96077039283, 109278.45360773304, 9952.00479951999, 11827.940860295643, 12477.750444990868, 12948.352582371032, 13259.270740109387, 44522.554774453136, 45887.54112458833, 47034.29448558343, 47054.29418558656, 48014.519854801, 63347.0, 64343.35656643374, 66427.33572664211, 67280.66359668366, 67671.0, 17342.739858901874, 17561.736573951144, 17997.0, 18199.636007280045, 18449.53876153107, 18951.810481895005, 19046.714299285246, 19153.233870645574, 19272.22911083541, 19666.21335146578, 17650.382236621655, 18141.818581814012, 18477.445676629723, 18639.0, 18729.595280353755, 17939.73090403619, 18206.544836379195, 18733.250669973593, 18916.770409923312, 18950.147243373805, 17265.654686906444, 17577.912110439367, 17795.19921603505, 18012.369567065613, 18438.446846594623, 9690.75773105678, 11858.822117668415, 12656.74686506283, 12928.870711293142, 13007.414666340226, 13543.25512096844, 14644.707105858037, 14672.85327146715, 14884.479043233436, 14941.178235197169], "value": 9187.54568029325, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1548.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 115.0, 106.0, 127.0, 115.0, 115.0, 115.0, 139.0, 166.0, 132.0], "value": 123.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 132.0, 130.0, 170.0, 141.0, 165.0, 135.0, 128.0, 177.0, 127.0], "value": 144.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 202.0, 199.0, 203.0, 205.0, 201.0, 284.0, 277.0, 221.0, 199.0], "value": 219.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 81.0, 75.0, 147.0, 60.0, 52.0, 49.0, 68.0, 160.0, 85.0], "value": 84.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 109.0, 70.0, 71.0, 84.0, 111.0, 68.0, 71.0, 111.0], "value": 84.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 107.0, 136.0, 91.0, 92.0, 91.0, 92.0, 90.0, 93.0, 92.0], "value": 97.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 126.0, 113.0, 150.0, 149.0, 153.0, 97.0, 97.0, 122.0, 106.0], "value": 120.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 94.0, 91.0, 93.0, 90.0, 91.0, 90.0, 92.0, 91.0, 94.0], "value": 91.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 48.0, 50.0, 49.0, 50.0, 54.0, 52.0, 52.0, 51.0, 52.0], "value": 50.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 57.0, 63.0, 66.0, 68.0, 63.0, 57.0, 58.0, 57.0], "value": 59.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 79.0, 99.0, 76.0, 86.0, 80.0, 78.0, 78.0, 81.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 125.0, 135.0, 146.0, 144.0, 140.0, 132.0, 170.0, 146.0, 159.0], "value": 143.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 176.0, 176.0, 175.0, 174.0, 174.0, 181.0, 216.0, 218.0, 175.0], "value": 184.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 66.0, 65.0, 65.0, 65.0, 62.0, 61.0, 65.0, 62.0], "value": 63.8, "unit": "score"}]}]} 18:33:12 INFO - Return code: 0 18:33:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:33:12 INFO - # TBPL SUCCESS # 18:33:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:33:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:33:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:33:12 INFO - 2017-06-12 18:33:12.400 screenresolution[2585:40266] starting screenresolution argv=screenresolution get 18:33:12 INFO - 2017-06-12 18:33:12.402 screenresolution[2585:40266] Display 0: 1600x1200x32@60 18:33:12 INFO - 2017-06-12 18:33:12.408 screenresolution[2586:40269] starting screenresolution argv=screenresolution list 18:33:12 INFO - Available Modes on Display 0 18:33:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:33:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:33:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:33:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:33:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:33:12 INFO - Intel Iris: 18:33:12 INFO - Chipset Model: Intel Iris 18:33:12 INFO - Type: GPU 18:33:12 INFO - Bus: Built-In 18:33:12 INFO - VRAM (Dynamic, Max): 1536 MB 18:33:12 INFO - Vendor: Intel (0x8086) 18:33:12 INFO - Device ID: 0x0a2e 18:33:12 INFO - Revision ID: 0x0009 18:33:12 INFO - Displays: 18:33:12 INFO - Display: 18:33:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:33:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:33:12 INFO - Display Serial Number: 1600x1200 60 18:33:12 INFO - Main Display: Yes 18:33:12 INFO - Mirror: Off 18:33:12 INFO - Online: Yes 18:33:12 INFO - Rotation: Supported 18:33:12 INFO - Return code: 0 18:33:12 INFO - Running post-action listener: _resource_record_post_action 18:33:12 INFO - [mozharness: 2017-06-13 01:33:12.643876Z] Finished run-tests step (success) 18:33:12 INFO - Running post-run listener: _resource_record_post_run 18:33:12 INFO - Total resource usage - Wall time: 1248s; CPU: 53.0%; Read bytes: 2466304; Write bytes: 469061120; Read time: 18; Write time: 1513 18:33:12 INFO - TinderboxPrint: CPU usage
53.1% 18:33:12 INFO - TinderboxPrint: I/O read bytes / time
2,466,304 / 18 18:33:12 INFO - TinderboxPrint: I/O write bytes / time
469,061,120 / 1,513 18:33:12 INFO - TinderboxPrint: CPU idle
2,339.8 (46.9%) 18:33:12 INFO - TinderboxPrint: CPU system
1,016.5 (20.4%) 18:33:12 INFO - TinderboxPrint: CPU user
1,637.4 (32.8%) 18:33:12 INFO - TinderboxPrint: Swap in / out
155,758,592 / 0 18:33:12 INFO - install - Wall time: 17s; CPU: 54.0%; Read bytes: 144425472; Write bytes: 136990720; Read time: 11728; Write time: 296 18:33:12 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:33:12 INFO - run-tests - Wall time: 1232s; CPU: 53.0%; Read bytes: 1602048; Write bytes: 313802240; Read time: 15; Write time: 1165 18:33:13 INFO - Running post-run listener: _upload_blobber_files 18:33:13 INFO - Blob upload gear active. 18:33:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:33:13 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 18:33:13 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:33:13 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:33:13 INFO - (blobuploader) - INFO - Open directory for files ... 18:33:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:33:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:33:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:33:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:33:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:33:14 INFO - (blobuploader) - INFO - Done attempting. 18:33:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:33:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:33:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:33:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:33:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:33:16 INFO - (blobuploader) - INFO - Done attempting. 18:33:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:33:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:33:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:33:17 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:33:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:33:17 INFO - (blobuploader) - INFO - Done attempting. 18:33:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:33:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:33:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:33:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:33:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:33:20 INFO - (blobuploader) - INFO - Done attempting. 18:33:20 INFO - (blobuploader) - INFO - Iteration through files over. 18:33:20 INFO - Return code: 0 18:33:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:33:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:33:20 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b8742ecbbb944f1628e792ca513021f6f78a1b882c5afc70c1732cdd0e666a799e100aa39f7e3bce047f9bd717433a6debd98f4472f17bc4579f3ca1fdd028b1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/15a66070b15f40b99b6b8bd2d2731675ea80e44cc7be47e44acdce03f3c475fe3f5347ba855d3de970772a4887c9dd362d7ebe47716647ad6a7190b8d6e6b040", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8722553d2214621f74bceff20e0b2956556f1e4f37fd85f09c4824c56a67e134281d754b53ea364067b588164f24445d7cfc2b37df773d4dbc2b8a7d69807fc7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6daba0973dccc32f547feb2a200a9840d413a7f66a8b7e7c006298e80e67d08a8546099185c603e12404442a729aebdb765c3ecfd9a4b44cab5b1a109e1c4756"} 18:33:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:33:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:33:20 INFO - Contents: 18:33:20 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b8742ecbbb944f1628e792ca513021f6f78a1b882c5afc70c1732cdd0e666a799e100aa39f7e3bce047f9bd717433a6debd98f4472f17bc4579f3ca1fdd028b1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/15a66070b15f40b99b6b8bd2d2731675ea80e44cc7be47e44acdce03f3c475fe3f5347ba855d3de970772a4887c9dd362d7ebe47716647ad6a7190b8d6e6b040", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8722553d2214621f74bceff20e0b2956556f1e4f37fd85f09c4824c56a67e134281d754b53ea364067b588164f24445d7cfc2b37df773d4dbc2b8a7d69807fc7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6daba0973dccc32f547feb2a200a9840d413a7f66a8b7e7c006298e80e67d08a8546099185c603e12404442a729aebdb765c3ecfd9a4b44cab5b1a109e1c4756"} 18:33:20 INFO - Running post-run listener: copy_logs_to_upload_dir 18:33:20 INFO - Copying logs to upload dir... 18:33:20 INFO - mkdir: /builds/slave/test/build/upload/logs 18:33:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1332.213021 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 12 secs) (at 2017-06-12 18:33:20.691326) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-12 18:33:20.697945) ========= 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.0calcjdzJU/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.xNfJFIU9OJ/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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/Mozilla-Beta/sha512/b8742ecbbb944f1628e792ca513021f6f78a1b882c5afc70c1732cdd0e666a799e100aa39f7e3bce047f9bd717433a6debd98f4472f17bc4579f3ca1fdd028b1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/15a66070b15f40b99b6b8bd2d2731675ea80e44cc7be47e44acdce03f3c475fe3f5347ba855d3de970772a4887c9dd362d7ebe47716647ad6a7190b8d6e6b040", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8722553d2214621f74bceff20e0b2956556f1e4f37fd85f09c4824c56a67e134281d754b53ea364067b588164f24445d7cfc2b37df773d4dbc2b8a7d69807fc7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6daba0973dccc32f547feb2a200a9840d413a7f66a8b7e7c006298e80e67d08a8546099185c603e12404442a729aebdb765c3ecfd9a4b44cab5b1a109e1c4756"} build_url:https://queue.taskcluster.net/v1/task/FWuyTHrhQdO-81icKoQdxQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011133 build_url: 'https://queue.taskcluster.net/v1/task/FWuyTHrhQdO-81icKoQdxQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b8742ecbbb944f1628e792ca513021f6f78a1b882c5afc70c1732cdd0e666a799e100aa39f7e3bce047f9bd717433a6debd98f4472f17bc4579f3ca1fdd028b1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/15a66070b15f40b99b6b8bd2d2731675ea80e44cc7be47e44acdce03f3c475fe3f5347ba855d3de970772a4887c9dd362d7ebe47716647ad6a7190b8d6e6b040", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8722553d2214621f74bceff20e0b2956556f1e4f37fd85f09c4824c56a67e134281d754b53ea364067b588164f24445d7cfc2b37df773d4dbc2b8a7d69807fc7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6daba0973dccc32f547feb2a200a9840d413a7f66a8b7e7c006298e80e67d08a8546099185c603e12404442a729aebdb765c3ecfd9a4b44cab5b1a109e1c4756"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-12 18:33:20.735486) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 18:33:20.735827) ========= 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.0calcjdzJU/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.xNfJFIU9OJ/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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.005147 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 18:33:20.784467) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-12 18:33:20.784886) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-12 18:33:20.785190) ========= ========= Total master_lag: 0.17 =========