builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0197 starttime: 1497462466.36 results: success (0) revision: 9ef7a635694e7b52b8ded9909dfac42e1b2106ea ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-14 10:47:46.361756) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-14 10:47:46.362247) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-14 10:47:46.362524) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-14 10:47:46.387429) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 10:47:46.387778) ========= 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.L2g25tvQRn/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.itMcUCEQZp/Listeners TMPDIR=/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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-14 10:47:46-- 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% 151M=0s 2017-06-14 10:47:46 (151 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.291537 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 10:47:46.701780) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 10:47:46.702085) ========= 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.L2g25tvQRn/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.itMcUCEQZp/Listeners TMPDIR=/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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.038501 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 10:47:46.761327) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-14 10:47:46.761654) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 9ef7a635694e7b52b8ded9909dfac42e1b2106ea --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 9ef7a635694e7b52b8ded9909dfac42e1b2106ea --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.L2g25tvQRn/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.itMcUCEQZp/Listeners TMPDIR=/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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-14 10:47:46,860 truncating revision to first 12 chars 2017-06-14 10:47:46,860 Setting DEBUG logging. 2017-06-14 10:47:46,860 attempt 1/10 2017-06-14 10:47:46,860 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/9ef7a635694e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-14 10:47:48,023 unpacking tar archive at: mozilla-beta-9ef7a635694e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.441972 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-14 10:47:48.225158) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-14 10:47:48.225495) ========= script_repo_revision: 9ef7a635694e7b52b8ded9909dfac42e1b2106ea ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-14 10:47:48.225855) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-14 10:47:48.226115) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-14 10:47:48.244624) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 10 secs) (at 2017-06-14 10:47:48.244949) ========= /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.L2g25tvQRn/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.itMcUCEQZp/Listeners TMPDIR=/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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 10:47:48 INFO - MultiFileLogger online at 20170614 10:47:48 in /builds/slave/test 10:47:48 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 10:47:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:47:48 INFO - {'append_to_log': False, 10:47:48 INFO - 'base_work_dir': '/builds/slave/test', 10:47:48 INFO - 'blob_upload_branch': 'Mozilla-Beta', 10:47:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:47:48 INFO - 'branch': 'Mozilla-Beta', 10:47:48 INFO - 'buildbot_json_path': 'buildprops.json', 10:47:48 INFO - 'config_files': ('talos/mac_config.py',), 10:47:48 INFO - 'default_actions': ('clobber', 10:47:48 INFO - 'read-buildbot-config', 10:47:48 INFO - 'download-and-extract', 10:47:48 INFO - 'populate-webroot', 10:47:48 INFO - 'create-virtualenv', 10:47:48 INFO - 'install', 10:47:48 INFO - 'setup-mitmproxy', 10:47:48 INFO - 'run-tests'), 10:47:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:47:48 INFO - 'download_minidump_stackwalk': True, 10:47:48 INFO - 'download_symbols': 'ondemand', 10:47:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:47:48 INFO - 'tooltool.py': '/tools/tooltool.py', 10:47:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:47:48 INFO - '/tools/misc-python/virtualenv.py')}, 10:47:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:47:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:47:48 INFO - 'gecko_profile': False, 10:47:48 INFO - 'gecko_profile_interval': 0, 10:47:48 INFO - 'installer_path': 'installer.exe', 10:47:48 INFO - 'log_level': 'info', 10:47:48 INFO - 'log_name': 'talos', 10:47:48 INFO - 'log_to_console': True, 10:47:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:47:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:47:48 INFO - 'opt_config_files': (), 10:47:48 INFO - 'pip_index': False, 10:47:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:47:48 INFO - 'cmd': ('bash', 10:47:48 INFO - '-c', 10:47:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:47:48 INFO - 'enabled': True, 10:47:48 INFO - 'halt_on_failure': False, 10:47:48 INFO - 'name': 'check_screen_resolution'},), 10:47:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:47:48 INFO - 'cmd': ('bash', 10:47:48 INFO - '-c', 10:47:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:47:48 INFO - 'enabled': True, 10:47:48 INFO - 'halt_on_failure': False, 10:47:48 INFO - 'name': 'check_screen_resolution'},), 10:47:48 INFO - 'run_cmd_checks_enabled': True, 10:47:48 INFO - 'suite': 'dromaeojs-e10s', 10:47:48 INFO - 'system_bits': '32', 10:47:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:47:48 INFO - 'title': 't-yosemite-r7-0197', 10:47:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:47:48 INFO - 'use_talos_json': True, 10:47:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:47:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:47:48 INFO - 'work_dir': 'build'} 10:47:48 INFO - [mozharness: 2017-06-14 17:47:48.457300Z] Running clobber step. 10:47:48 INFO - Running pre-action listener: _resource_record_pre_action 10:47:48 INFO - Running main action method: clobber 10:47:48 INFO - rmtree: /builds/slave/test/build 10:47:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:47:51 INFO - Running post-action listener: _resource_record_post_action 10:47:51 INFO - [mozharness: 2017-06-14 17:47:51.052919Z] Finished clobber step (success) 10:47:51 INFO - [mozharness: 2017-06-14 17:47:51.053088Z] Running read-buildbot-config step. 10:47:51 INFO - Running pre-action listener: _resource_record_pre_action 10:47:51 INFO - Running main action method: read_buildbot_config 10:47:51 INFO - Using buildbot properties: 10:47:51 INFO - { 10:47:51 INFO - "project": "", 10:47:51 INFO - "product": "firefox", 10:47:51 INFO - "who": "ffxbld@noreply.mozilla.org", 10:47:51 INFO - "installer_path": "public/build/firefox-55.0.en-US.mac.dmg", 10:47:51 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 10:47:51 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 10:47:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 10:47:51 INFO - "stage_platform": "macosx64", 10:47:51 INFO - "basedir": "/builds/slave/test", 10:47:51 INFO - "buildnumber": 1, 10:47:51 INFO - "platform": "yosemite_r7", 10:47:51 INFO - "parent_task_id": "dy4mtt07QGyoYZGm18SU3Q", 10:47:51 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 10:47:51 INFO - "slavebuilddir": "test", 10:47:51 INFO - "taskId": "SiAIF7o6Si2n124WKxTyXg", 10:47:51 INFO - "branch": "mozilla-beta", 10:47:51 INFO - "script_repo_revision": "production", 10:47:51 INFO - "revision": "9ef7a635694e7b52b8ded9909dfac42e1b2106ea", 10:47:51 INFO - "slavename": "t-yosemite-r7-0197", 10:47:51 INFO - "repo_path": "releases/mozilla-beta" 10:47:51 INFO - } 10:47:51 INFO - Finding installer, test and symbols from parent task. 10:47:51 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SiAIF7o6Si2n124WKxTyXg'}, attempt #1 10:47:51 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SiAIF7o6Si2n124WKxTyXg'}, attempt #1 10:47:52 INFO - Task dependencies: fH8Hr0V4SumvqJZerGLyOA dy4mtt07QGyoYZGm18SU3Q 10:47:52 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dy4mtt07QGyoYZGm18SU3Q'}, attempt #1 10:47:52 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/dy4mtt07QGyoYZGm18SU3Q/artifacts/public/build/firefox-55.0.en-US.mac.dmg 10:47:52 INFO - Set test_url: https://queue.taskcluster.net/v1/task/dy4mtt07QGyoYZGm18SU3Q/artifacts/public/build/test_packages.json 10:47:52 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/dy4mtt07QGyoYZGm18SU3Q/artifacts/public/build/target.crashreporter-symbols.zip 10:47:52 INFO - Running post-action listener: _resource_record_post_action 10:47:52 INFO - [mozharness: 2017-06-14 17:47:52.797362Z] Finished read-buildbot-config step (success) 10:47:52 INFO - [mozharness: 2017-06-14 17:47:52.797497Z] Running download-and-extract step. 10:47:52 INFO - Running pre-action listener: _resource_record_pre_action 10:47:52 INFO - Running main action method: download_and_extract 10:47:52 INFO - mkdir: /builds/slave/test/build/tests 10:47:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:47:52 INFO - trying https://queue.taskcluster.net/v1/task/dy4mtt07QGyoYZGm18SU3Q/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json 10:47:52 INFO - Downloading https://queue.taskcluster.net/v1/task/dy4mtt07QGyoYZGm18SU3Q/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 10:47:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dy4mtt07QGyoYZGm18SU3Q/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 10:47:53 INFO - Downloaded 1290 bytes. 10:47:53 INFO - Reading from file /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 10:47:53 INFO - Using the following test package requirements: 10:47:53 INFO - {u'awsy': [u'firefox-55.0.en-US.mac.common.tests.zip', 10:47:53 INFO - u'firefox-55.0.en-US.mac.awsy.tests.zip'], 10:47:53 INFO - u'common': [u'firefox-55.0.en-US.mac.common.tests.zip'], 10:47:53 INFO - u'cppunittest': [u'firefox-55.0.en-US.mac.common.tests.zip', 10:47:53 INFO - u'firefox-55.0.en-US.mac.cppunittest.tests.zip'], 10:47:53 INFO - u'gtest': [u'firefox-55.0.en-US.mac.common.tests.zip', 10:47:53 INFO - u'firefox-55.0.en-US.mac.gtest.tests.zip'], 10:47:53 INFO - u'jittest': [u'firefox-55.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 10:47:53 INFO - u'mochitest': [u'firefox-55.0.en-US.mac.common.tests.zip', 10:47:53 INFO - u'firefox-55.0.en-US.mac.mochitest.tests.zip'], 10:47:53 INFO - u'mozbase': [u'firefox-55.0.en-US.mac.common.tests.zip'], 10:47:53 INFO - u'reftest': [u'firefox-55.0.en-US.mac.common.tests.zip', 10:47:53 INFO - u'firefox-55.0.en-US.mac.reftest.tests.zip'], 10:47:53 INFO - u'talos': [u'firefox-55.0.en-US.mac.common.tests.zip', 10:47:53 INFO - u'firefox-55.0.en-US.mac.talos.tests.zip'], 10:47:53 INFO - u'web-platform': [u'firefox-55.0.en-US.mac.common.tests.zip', 10:47:53 INFO - u'firefox-55.0.en-US.mac.web-platform.tests.tar.gz'], 10:47:53 INFO - u'xpcshell': [u'firefox-55.0.en-US.mac.common.tests.zip', 10:47:53 INFO - u'firefox-55.0.en-US.mac.xpcshell.tests.zip']} 10:47:53 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip'] for test suite category: common 10:47:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dy4mtt07QGyoYZGm18SU3Q/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 10:47:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dy4mtt07QGyoYZGm18SU3Q/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 10:47:53 INFO - Fetch https://queue.taskcluster.net/v1/task/dy4mtt07QGyoYZGm18SU3Q/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 10:47:55 INFO - Content-Length response header: 58981162 10:47:55 INFO - Bytes received: 58981162 10:48:13 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 10:48:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dy4mtt07QGyoYZGm18SU3Q/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 10:48:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dy4mtt07QGyoYZGm18SU3Q/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 10:48:13 INFO - Fetch https://queue.taskcluster.net/v1/task/dy4mtt07QGyoYZGm18SU3Q/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 10:48:16 INFO - Content-Length response header: 58981162 10:48:16 INFO - Bytes received: 58981162 10:48:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dy4mtt07QGyoYZGm18SU3Q/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip 10:48:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dy4mtt07QGyoYZGm18SU3Q/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip'}, attempt #1 10:48:33 INFO - Fetch https://queue.taskcluster.net/v1/task/dy4mtt07QGyoYZGm18SU3Q/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip into memory 10:48:34 INFO - Content-Length response header: 14450018 10:48:34 INFO - Bytes received: 14450018 10:48:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:48:34 INFO - trying https://queue.taskcluster.net/v1/task/dy4mtt07QGyoYZGm18SU3Q/artifacts/public/build/firefox-55.0.en-US.mac.dmg 10:48:34 INFO - Downloading https://queue.taskcluster.net/v1/task/dy4mtt07QGyoYZGm18SU3Q/artifacts/public/build/firefox-55.0.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0.en-US.mac.dmg 10:48:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dy4mtt07QGyoYZGm18SU3Q/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 10:48:36 INFO - Downloaded 51078008 bytes. 10:48:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dy4mtt07QGyoYZGm18SU3Q/artifacts/public/build/firefox-55.0.en-US.mac.dmg 10:48:36 INFO - mkdir: /builds/slave/test/properties 10:48:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:48:36 INFO - Writing to file /builds/slave/test/properties/build_url 10:48:36 INFO - Contents: 10:48:36 INFO - build_url:https://queue.taskcluster.net/v1/task/dy4mtt07QGyoYZGm18SU3Q/artifacts/public/build/firefox-55.0.en-US.mac.dmg 10:48:36 INFO - Running post-action listener: _resource_record_post_action 10:48:36 INFO - Running post-action listener: set_extra_try_arguments 10:48:36 INFO - [mozharness: 2017-06-14 17:48:36.439096Z] Finished download-and-extract step (success) 10:48:36 INFO - [mozharness: 2017-06-14 17:48:36.439204Z] Running populate-webroot step. 10:48:36 INFO - Running pre-action listener: _resource_record_pre_action 10:48:36 INFO - Running main action method: populate_webroot 10:48:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:48:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:48:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:48:36 INFO - '--tppagecycles', 10:48:36 INFO - '7'], 10:48:36 INFO - 'tests': ['tsvgm']}}, 10:48:36 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:48:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:48:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:48:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:48:36 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:48:36 INFO - 'talos_options': ['--disable-e10s'], 10:48:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:48:36 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:48:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:48:36 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:48:36 INFO - 'talos_options': ['--disable-e10s'], 10:48:36 INFO - 'tests': ['damp', 'tps']}, 10:48:36 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:48:36 INFO - 'tests': ['damp', 'tps']}, 10:48:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:48:36 INFO - 'tests': ['dromaeo_dom']}, 10:48:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:48:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:48:36 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 10:48:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 10:48:36 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 10:48:36 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 10:48:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:48:36 INFO - 'tests': ['a11yr', 10:48:36 INFO - 'ts_paint', 10:48:36 INFO - 'tpaint', 10:48:36 INFO - 'sessionrestore', 10:48:36 INFO - 'sessionrestore_no_auto_restore', 10:48:36 INFO - 'tabpaint']}, 10:48:36 INFO - 'other-e10s': {'tests': ['a11yr', 10:48:36 INFO - 'ts_paint', 10:48:36 INFO - 'tpaint', 10:48:36 INFO - 'sessionrestore', 10:48:36 INFO - 'sessionrestore_no_auto_restore', 10:48:36 INFO - 'tabpaint']}, 10:48:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:48:36 INFO - 'ts_paint', 10:48:36 INFO - 'tpaint', 10:48:36 INFO - 'sessionrestore', 10:48:36 INFO - 'sessionrestore_no_auto_restore', 10:48:36 INFO - 'tabpaint']}, 10:48:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:48:36 INFO - 'ts_paint', 10:48:36 INFO - 'tpaint', 10:48:36 INFO - 'sessionrestore', 10:48:36 INFO - 'sessionrestore_no_auto_restore', 10:48:36 INFO - 'tabpaint']}, 10:48:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:48:36 INFO - 'tests': ['a11yr', 10:48:36 INFO - 'ts_paint', 10:48:36 INFO - 'tpaint', 10:48:36 INFO - 'sessionrestore', 10:48:36 INFO - 'sessionrestore_no_auto_restore', 10:48:36 INFO - 'tabpaint']}, 10:48:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:48:36 INFO - 'tests': ['a11yr', 10:48:36 INFO - 'ts_paint', 10:48:36 INFO - 'tpaint', 10:48:36 INFO - 'sessionrestore', 10:48:36 INFO - 'sessionrestore_no_auto_restore', 10:48:36 INFO - 'tabpaint']}, 10:48:36 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 10:48:36 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 10:48:36 INFO - 'bloom_basic_ref']}, 10:48:36 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 10:48:36 INFO - 'talos_options': ['--mitmproxy', 10:48:36 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 10:48:36 INFO - '--firstNonBlankPaint'], 10:48:36 INFO - 'tests': ['quantum_pageload_google', 10:48:36 INFO - 'quantum_pageload_youtube', 10:48:36 INFO - 'quantum_pageload_amazon', 10:48:36 INFO - 'quantum_pageload_facebook']}, 10:48:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:48:36 INFO - 'tests': ['tsvgx', 10:48:36 INFO - 'tsvgr_opacity', 10:48:36 INFO - 'tart', 10:48:36 INFO - 'tscrollx', 10:48:36 INFO - 'cart', 10:48:36 INFO - 'tsvg_static']}, 10:48:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:48:36 INFO - 'tsvgr_opacity', 10:48:36 INFO - 'tart', 10:48:36 INFO - 'tscrollx', 10:48:36 INFO - 'cart', 10:48:36 INFO - 'tsvg_static']}, 10:48:36 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:48:36 INFO - 'talos_options': ['--disable-e10s'], 10:48:36 INFO - 'tests': ['tp5o']}, 10:48:36 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:48:36 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:48:36 INFO - 'talos_options': ['--disable-e10s', 10:48:36 INFO - '--xperf_path', 10:48:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:48:36 INFO - 'tests': ['tp5n']}, 10:48:36 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:48:36 INFO - 'talos_options': ['--xperf_path', 10:48:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:48:36 INFO - 'tests': ['tp5n']}}, 10:48:36 INFO - 'talos.zip': {'path': '', 10:48:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:48:36 INFO - Running post-action listener: _resource_record_post_action 10:48:36 INFO - [mozharness: 2017-06-14 17:48:36.462224Z] Finished populate-webroot step (success) 10:48:36 INFO - [mozharness: 2017-06-14 17:48:36.462356Z] Running create-virtualenv step. 10:48:36 INFO - Running pre-action listener: _resource_record_pre_action 10:48:36 INFO - Running main action method: create_virtualenv 10:48:36 INFO - Creating virtualenv /builds/slave/test/build/venv 10:48:36 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 10:48:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:48:36 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 10:48:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:48:36 INFO - Using real prefix '/tools/python27' 10:48:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:48:37 INFO - Installing distribute.............................................................................................................................................................................................done. 10:48:40 INFO - Installing pip.................done. 10:48:40 INFO - Return code: 0 10:48:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:48:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:48:40 INFO - Reading from file tmpfile_stdout 10:48:40 INFO - Output received: 10:48:40 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) 10:48:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:48: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')]} 10:48:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48: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')]} 10:48:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c2c9c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2cd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdfbd6c1d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c01fd30>, '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 0x10bfe9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c010be8>, '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.L2g25tvQRn/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.itMcUCEQZp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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 10:48:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:48:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:48:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L2g25tvQRn/Render', 10:48:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:40 INFO - 'HOME': '/Users/cltbld', 10:48:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:40 INFO - 'LOGNAME': 'cltbld', 10:48:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:40 INFO - 'MOZ_NO_REMOTE': '1', 10:48:40 INFO - 'NO_EM_RESTART': '1', 10:48:40 INFO - 'PAGER': '/bin/cat', 10:48:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:40 INFO - 'PWD': '/builds/slave/test', 10:48:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:40 INFO - 'SHELL': '/bin/bash', 10:48:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.itMcUCEQZp/Listeners', 10:48:40 INFO - 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/', 10:48:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:40 INFO - 'USER': 'cltbld', 10:48:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:40 INFO - 'XPC_FLAGS': '0x0', 10:48:40 INFO - 'XPC_SERVICE_NAME': '0', 10:48:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:40 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 10:48:40 INFO - Cleaning up... 10:48:40 INFO - Return code: 0 10:48:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:48: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')]} 10:48:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48: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')]} 10:48:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c2c9c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2cd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdfbd6c1d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c01fd30>, '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 0x10bfe9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c010be8>, '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.L2g25tvQRn/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.itMcUCEQZp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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 10:48:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:48:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:48:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L2g25tvQRn/Render', 10:48:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:40 INFO - 'HOME': '/Users/cltbld', 10:48:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:40 INFO - 'LOGNAME': 'cltbld', 10:48:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:40 INFO - 'MOZ_NO_REMOTE': '1', 10:48:40 INFO - 'NO_EM_RESTART': '1', 10:48:40 INFO - 'PAGER': '/bin/cat', 10:48:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:40 INFO - 'PWD': '/builds/slave/test', 10:48:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:40 INFO - 'SHELL': '/bin/bash', 10:48:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.itMcUCEQZp/Listeners', 10:48:40 INFO - 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/', 10:48:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:40 INFO - 'USER': 'cltbld', 10:48:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:40 INFO - 'XPC_FLAGS': '0x0', 10:48:40 INFO - 'XPC_SERVICE_NAME': '0', 10:48:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:41 INFO - Downloading/unpacking psutil>=3.1.1 10:48:41 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 10:48:41 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 10:48:41 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 10:48:41 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 10:48:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:48:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:48:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:48:42 INFO - Installing collected packages: psutil 10:48:42 INFO - Running setup.py install for psutil 10:48:43 INFO - building 'psutil._psutil_osx' extension 10:48:43 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 10:48:43 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 10:48:43 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 10:48:43 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 10:48:43 INFO - building 'psutil._psutil_posix' extension 10:48:43 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 10:48:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:48:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:48:43 INFO - ^ 10:48:44 INFO - 1 warning generated. 10:48:44 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 10:48:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:48:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:48:44 INFO - Successfully installed psutil 10:48:44 INFO - Cleaning up... 10:48:44 INFO - Return code: 0 10:48:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:48:44 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')]} 10:48:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:44 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')]} 10:48:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:44 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 0x10c2c9c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2cd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdfbd6c1d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c01fd30>, '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 0x10bfe9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c010be8>, '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.L2g25tvQRn/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.itMcUCEQZp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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 10:48:44 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 10:48:44 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 10:48:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L2g25tvQRn/Render', 10:48:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:44 INFO - 'HOME': '/Users/cltbld', 10:48:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:44 INFO - 'LOGNAME': 'cltbld', 10:48:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:44 INFO - 'MOZ_NO_REMOTE': '1', 10:48:44 INFO - 'NO_EM_RESTART': '1', 10:48:44 INFO - 'PAGER': '/bin/cat', 10:48:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:44 INFO - 'PWD': '/builds/slave/test', 10:48:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:44 INFO - 'SHELL': '/bin/bash', 10:48:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.itMcUCEQZp/Listeners', 10:48:44 INFO - 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/', 10:48:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:44 INFO - 'USER': 'cltbld', 10:48:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:44 INFO - 'XPC_FLAGS': '0x0', 10:48:44 INFO - 'XPC_SERVICE_NAME': '0', 10:48:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:48:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:48:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:48:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:48:47 INFO - Installing collected packages: mozsystemmonitor 10:48:47 INFO - Running setup.py install for mozsystemmonitor 10:48:47 INFO - Successfully installed mozsystemmonitor 10:48:47 INFO - Cleaning up... 10:48:47 INFO - Return code: 0 10:48:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:48:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:48:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:48:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c2c9c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2cd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdfbd6c1d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c01fd30>, '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 0x10bfe9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c010be8>, '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.L2g25tvQRn/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.itMcUCEQZp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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 10:48:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:48:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:48:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L2g25tvQRn/Render', 10:48:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:47 INFO - 'HOME': '/Users/cltbld', 10:48:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:47 INFO - 'LOGNAME': 'cltbld', 10:48:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:47 INFO - 'MOZ_NO_REMOTE': '1', 10:48:47 INFO - 'NO_EM_RESTART': '1', 10:48:47 INFO - 'PAGER': '/bin/cat', 10:48:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:47 INFO - 'PWD': '/builds/slave/test', 10:48:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:47 INFO - 'SHELL': '/bin/bash', 10:48:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.itMcUCEQZp/Listeners', 10:48:47 INFO - 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/', 10:48:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:47 INFO - 'USER': 'cltbld', 10:48:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:47 INFO - 'XPC_FLAGS': '0x0', 10:48:47 INFO - 'XPC_SERVICE_NAME': '0', 10:48:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:47 INFO - Downloading/unpacking jsonschema==2.5.1 10:48:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:48:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:48:48 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 10:48:48 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 10:48:48 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 10:48:48 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 10:48:49 INFO - Downloading functools32-3.2.3-2.tar.gz 10:48:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:48:49 INFO - warning: no files found matching '*.txt' 10:48:49 INFO - no previously-included directories found matching 'build' 10:48:49 INFO - no previously-included directories found matching 'dist' 10:48:49 INFO - no previously-included directories found matching '.git*' 10:48:49 INFO - Installing collected packages: jsonschema, functools32 10:48:49 INFO - Running setup.py install for functools32 10:48:49 INFO - warning: no files found matching '*.txt' 10:48:49 INFO - no previously-included directories found matching 'build' 10:48:49 INFO - no previously-included directories found matching 'dist' 10:48:49 INFO - no previously-included directories found matching '.git*' 10:48:49 INFO - Successfully installed jsonschema functools32 10:48:49 INFO - Cleaning up... 10:48:49 INFO - Return code: 0 10:48:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:48:49 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')]} 10:48:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:49 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')]} 10:48:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:49 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 0x10c2c9c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2cd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdfbd6c1d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c01fd30>, '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 0x10bfe9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c010be8>, '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.L2g25tvQRn/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.itMcUCEQZp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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 10:48:49 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 10:48:49 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 10:48:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L2g25tvQRn/Render', 10:48:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:49 INFO - 'HOME': '/Users/cltbld', 10:48:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:49 INFO - 'LOGNAME': 'cltbld', 10:48:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:49 INFO - 'MOZ_NO_REMOTE': '1', 10:48:49 INFO - 'NO_EM_RESTART': '1', 10:48:49 INFO - 'PAGER': '/bin/cat', 10:48:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:49 INFO - 'PWD': '/builds/slave/test', 10:48:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:49 INFO - 'SHELL': '/bin/bash', 10:48:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.itMcUCEQZp/Listeners', 10:48:49 INFO - 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/', 10:48:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:49 INFO - 'USER': 'cltbld', 10:48:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:49 INFO - 'XPC_FLAGS': '0x0', 10:48:49 INFO - 'XPC_SERVICE_NAME': '0', 10:48:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:48:49 INFO - Cleaning up... 10:48:49 INFO - Return code: 0 10:48:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:48:49 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')]} 10:48:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:49 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')]} 10:48:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:49 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 0x10c2c9c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2cd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdfbd6c1d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c01fd30>, '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 0x10bfe9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c010be8>, '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.L2g25tvQRn/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.itMcUCEQZp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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 10:48:49 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 10:48:49 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 10:48:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L2g25tvQRn/Render', 10:48:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:49 INFO - 'HOME': '/Users/cltbld', 10:48:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:49 INFO - 'LOGNAME': 'cltbld', 10:48:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:49 INFO - 'MOZ_NO_REMOTE': '1', 10:48:49 INFO - 'NO_EM_RESTART': '1', 10:48:49 INFO - 'PAGER': '/bin/cat', 10:48:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:49 INFO - 'PWD': '/builds/slave/test', 10:48:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:49 INFO - 'SHELL': '/bin/bash', 10:48:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.itMcUCEQZp/Listeners', 10:48:49 INFO - 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/', 10:48:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:49 INFO - 'USER': 'cltbld', 10:48:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:49 INFO - 'XPC_FLAGS': '0x0', 10:48:49 INFO - 'XPC_SERVICE_NAME': '0', 10:48:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:50 INFO - Downloading/unpacking blobuploader==1.2.4 10:48:50 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 10:48:50 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 10:48:50 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 10:48:50 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 10:48:51 INFO - Downloading blobuploader-1.2.4.tar.gz 10:48:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:48:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:48:51 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 10:48:51 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 10:48:51 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 10:48:51 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 10:48:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:48:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:48:52 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 10:48:52 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 10:48:52 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 10:48:52 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 10:48:52 INFO - Downloading docopt-0.6.1.tar.gz 10:48:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:48:52 INFO - Installing collected packages: blobuploader, requests, docopt 10:48:52 INFO - Running setup.py install for blobuploader 10:48:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:48:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:48:52 INFO - Running setup.py install for requests 10:48:53 INFO - Running setup.py install for docopt 10:48:53 INFO - Successfully installed blobuploader requests docopt 10:48:53 INFO - Cleaning up... 10:48:53 INFO - Return code: 0 10:48:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:48:53 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')]} 10:48:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:53 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')]} 10:48:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:53 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 0x10c2c9c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2cd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdfbd6c1d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c01fd30>, '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 0x10bfe9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c010be8>, '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.L2g25tvQRn/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.itMcUCEQZp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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 10:48:53 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 10:48:53 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 10:48:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L2g25tvQRn/Render', 10:48:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:53 INFO - 'HOME': '/Users/cltbld', 10:48:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:53 INFO - 'LOGNAME': 'cltbld', 10:48:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:53 INFO - 'MOZ_NO_REMOTE': '1', 10:48:53 INFO - 'NO_EM_RESTART': '1', 10:48:53 INFO - 'PAGER': '/bin/cat', 10:48:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:53 INFO - 'PWD': '/builds/slave/test', 10:48:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:53 INFO - 'SHELL': '/bin/bash', 10:48:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.itMcUCEQZp/Listeners', 10:48:53 INFO - 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/', 10:48:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:53 INFO - 'USER': 'cltbld', 10:48:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:53 INFO - 'XPC_FLAGS': '0x0', 10:48:53 INFO - 'XPC_SERVICE_NAME': '0', 10:48:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:48:53 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-6UdznO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:48:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:48:53 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-QfqZ8D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:48:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:48:53 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-_tCC8v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:48:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:48:54 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-F7k0tc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:48:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:48:54 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-6e_ADD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:48:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:48:54 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-jlrfFA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:48:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:48:54 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-Pv_ze5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:48:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:48:54 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-IQRLX8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:48:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:48:54 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-7s1ZsH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:48:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:48:54 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-F2bh9a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:48:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:48:54 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-Sj_ovQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:48:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:48:54 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-K0cVpc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:48:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:48:54 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-2g4fVM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:48:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:48:55 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-FxYG72-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:48:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:48:55 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-iKVPC1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:48:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:48:55 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-jUq5XU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:48:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:48:55 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-1yoymY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:48:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:48:55 INFO - Running setup.py install for manifestparser 10:48:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:48:55 INFO - Running setup.py install for mozcrash 10:48:55 INFO - Running setup.py install for mozdebug 10:48:55 INFO - Running setup.py install for mozdevice 10:48:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:48:56 INFO - Running setup.py install for mozfile 10:48:56 INFO - Running setup.py install for mozhttpd 10:48:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:48:56 INFO - Running setup.py install for mozinfo 10:48:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:48:56 INFO - Running setup.py install for mozInstall 10:48:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:48:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:48:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:48:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:48:56 INFO - Running setup.py install for mozleak 10:48:56 INFO - Running setup.py install for mozlog 10:48:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:48:56 INFO - Running setup.py install for moznetwork 10:48:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:48:57 INFO - Running setup.py install for mozprocess 10:48:57 INFO - Running setup.py install for mozprofile 10:48:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:48:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:48:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:48:57 INFO - Running setup.py install for mozrunner 10:48:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:48:57 INFO - Running setup.py install for mozscreenshot 10:48:57 INFO - Running setup.py install for moztest 10:48:57 INFO - Running setup.py install for mozversion 10:48:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:48:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:48:58 INFO - Cleaning up... 10:48:58 INFO - Return code: 0 10:48:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:48:58 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')]} 10:48:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:58 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')]} 10:48:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:58 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 0x10c2c9c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2cd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdfbd6c1d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c01fd30>, '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 0x10bfe9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c010be8>, '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.L2g25tvQRn/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.itMcUCEQZp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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 10:48:58 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 10:48:58 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 10:48:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L2g25tvQRn/Render', 10:48:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:58 INFO - 'HOME': '/Users/cltbld', 10:48:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:58 INFO - 'LOGNAME': 'cltbld', 10:48:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:58 INFO - 'MOZ_NO_REMOTE': '1', 10:48:58 INFO - 'NO_EM_RESTART': '1', 10:48:58 INFO - 'PAGER': '/bin/cat', 10:48:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:58 INFO - 'PWD': '/builds/slave/test', 10:48:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:58 INFO - 'SHELL': '/bin/bash', 10:48:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.itMcUCEQZp/Listeners', 10:48:58 INFO - 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/', 10:48:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:58 INFO - 'USER': 'cltbld', 10:48:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:58 INFO - 'XPC_FLAGS': '0x0', 10:48:58 INFO - 'XPC_SERVICE_NAME': '0', 10:48:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:48:58 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-nsT0vq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:48:58 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)) 10:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:48:58 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-wCXxDW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:48:58 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)) 10:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:48:58 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-RundBX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:48:58 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)) 10:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:48:58 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-irrF_l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:48:58 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)) 10:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:48:58 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-psQQZT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:48:58 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)) 10:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:48:58 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-JTWeJF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:48:58 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)) 10:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:48:58 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-87aKu7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:48:59 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)) 10:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:48:59 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-02KWmL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:48:59 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)) 10:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:48:59 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-h808Tm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:48:59 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)) 10:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:48:59 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-Y8n65u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:48:59 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)) 10:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:48:59 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-5V5Jsw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:48:59 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)) 10:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:48:59 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-uBVJa4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:48:59 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)) 10:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:48:59 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-A9TsuC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:48:59 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)) 10:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:48:59 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-E0AlGt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:48:59 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)) 10:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:48:59 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-p36QY8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:48:59 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)) 10:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:48:59 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-S52bF8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:48:59 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)) 10:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:48:59 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-PykwsW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:49:00 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)) 10:49:00 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)) 10:49:00 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)) 10:49:00 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)) 10:49:00 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)) 10:49:00 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)) 10:49:00 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)) 10:49:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:49: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 10:49: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 10:49: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 10:49: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 10:49:01 INFO - Downloading blessings-1.6.tar.gz 10:49:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:49:01 INFO - Installing collected packages: blessings 10:49:01 INFO - Running setup.py install for blessings 10:49:01 INFO - Successfully installed blessings 10:49:01 INFO - Cleaning up... 10:49:01 INFO - Return code: 0 10:49:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:49:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:49:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:49:02 INFO - Reading from file tmpfile_stdout 10:49:02 INFO - Current package versions: 10:49:02 INFO - blessings == 1.6 10:49:02 INFO - blobuploader == 1.2.4 10:49:02 INFO - docopt == 0.6.1 10:49:02 INFO - functools32 == 3.2.3-2 10:49:02 INFO - jsonschema == 2.5.1 10:49:02 INFO - manifestparser == 1.1 10:49:02 INFO - mozInstall == 1.13 10:49:02 INFO - mozcrash == 1.0 10:49:02 INFO - mozdebug == 0.1 10:49:02 INFO - mozdevice == 0.51 10:49:02 INFO - mozfile == 1.2 10:49:02 INFO - mozhttpd == 0.7 10:49:02 INFO - mozinfo == 0.10 10:49:02 INFO - mozleak == 0.1 10:49:02 INFO - mozlog == 3.4 10:49:02 INFO - moznetwork == 0.27 10:49:02 INFO - mozprocess == 0.25 10:49:02 INFO - mozprofile == 0.28 10:49:02 INFO - mozrunner == 6.13 10:49:02 INFO - mozscreenshot == 0.1 10:49:02 INFO - mozsystemmonitor == 0.3 10:49:02 INFO - moztest == 0.8 10:49:02 INFO - mozversion == 1.4 10:49:02 INFO - psutil == 3.1.1 10:49:02 INFO - requests == 1.2.3 10:49:02 INFO - wsgiref == 0.1.2 10:49:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:49:02 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')]} 10:49:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:49:02 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')]} 10:49:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:49:02 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 0x10c2c9c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2cd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdfbd6c1d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c01fd30>, '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 0x10bfe9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c010be8>, '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.L2g25tvQRn/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.itMcUCEQZp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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 10:49:02 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 10:49:02 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 10:49:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L2g25tvQRn/Render', 10:49:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:49:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:49:02 INFO - 'HOME': '/Users/cltbld', 10:49:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:49:02 INFO - 'LOGNAME': 'cltbld', 10:49:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:49:02 INFO - 'MOZ_NO_REMOTE': '1', 10:49:02 INFO - 'NO_EM_RESTART': '1', 10:49:02 INFO - 'PAGER': '/bin/cat', 10:49:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:49:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:49:02 INFO - 'PWD': '/builds/slave/test', 10:49:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:49:02 INFO - 'SHELL': '/bin/bash', 10:49:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.itMcUCEQZp/Listeners', 10:49:02 INFO - 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/', 10:49:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:49:02 INFO - 'USER': 'cltbld', 10:49:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:49:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:49:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:49:02 INFO - 'XPC_FLAGS': '0x0', 10:49:02 INFO - 'XPC_SERVICE_NAME': '0', 10:49:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:49:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:49:02 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)) 10:49:02 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)) 10:49:02 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)) 10:49:02 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)) 10:49:02 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)) 10:49:02 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)) 10:49:02 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)) 10:49:02 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)) 10:49:02 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)) 10:49:02 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 10:49:02 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 10:49:02 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 10:49:02 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 10:49:02 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 10:49:03 INFO - Downloading marionette_driver-1.4.0.tar.gz 10:49:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 10:49:03 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)) 10:49:03 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)) 10:49:03 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)) 10:49:03 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)) 10:49:03 INFO - Installing collected packages: marionette-driver 10:49:03 INFO - Running setup.py install for marionette-driver 10:49:04 INFO - Successfully installed marionette-driver 10:49:04 INFO - Cleaning up... 10:49:04 INFO - Return code: 0 10:49:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:49: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')]} 10:49:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:49: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')]} 10:49:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:49: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c2c9c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2cd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdfbd6c1d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c01fd30>, '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 0x10bfe9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c010be8>, '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.L2g25tvQRn/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.itMcUCEQZp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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 10:49: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', 'jsonschema'] in /builds/slave/test/build 10:49: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 jsonschema 10:49:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L2g25tvQRn/Render', 10:49:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:49:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:49:04 INFO - 'HOME': '/Users/cltbld', 10:49:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:49:04 INFO - 'LOGNAME': 'cltbld', 10:49:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:49:04 INFO - 'MOZ_NO_REMOTE': '1', 10:49:04 INFO - 'NO_EM_RESTART': '1', 10:49:04 INFO - 'PAGER': '/bin/cat', 10:49:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:49:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:49:04 INFO - 'PWD': '/builds/slave/test', 10:49:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:49:04 INFO - 'SHELL': '/bin/bash', 10:49:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.itMcUCEQZp/Listeners', 10:49:04 INFO - 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/', 10:49:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:49:04 INFO - 'USER': 'cltbld', 10:49:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:49:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:49:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:49:04 INFO - 'XPC_FLAGS': '0x0', 10:49:04 INFO - 'XPC_SERVICE_NAME': '0', 10:49:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:49:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:49:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:49:04 INFO - Cleaning up... 10:49:04 INFO - Return code: 0 10:49:04 INFO - Running post-action listener: _resource_record_post_action 10:49:04 INFO - Running post-action listener: _start_resource_monitoring 10:49:04 INFO - Starting resource monitoring. 10:49:04 INFO - [mozharness: 2017-06-14 17:49:04.379607Z] Finished create-virtualenv step (success) 10:49:04 INFO - [mozharness: 2017-06-14 17:49:04.380581Z] Running install step. 10:49:04 INFO - Running pre-action listener: _resource_record_pre_action 10:49:04 INFO - Running main action method: install 10:49:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:49:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:49:04 INFO - Reading from file tmpfile_stdout 10:49:04 INFO - Detecting whether we're running mozinstall >=1.0... 10:49:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:49:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:49:04 INFO - Reading from file tmpfile_stdout 10:49:04 INFO - Output received: 10:49:04 INFO - Usage: mozinstall [options] installer 10:49:04 INFO - Options: 10:49:04 INFO - -h, --help show this help message and exit 10:49:04 INFO - -d DEST, --destination=DEST 10:49:04 INFO - Directory to install application into. [default: 10:49:04 INFO - "/builds/slave/test"] 10:49:04 INFO - --app=APP Application being installed. [default: firefox] 10:49:04 INFO - mkdir: /builds/slave/test/build/application 10:49:04 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'] 10:49:04 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 10:49:20 INFO - Reading from file tmpfile_stdout 10:49:20 INFO - Output received: 10:49:20 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 10:49:20 INFO - Running post-action listener: _resource_record_post_action 10:49:20 INFO - [mozharness: 2017-06-14 17:49:20.475491Z] Finished install step (success) 10:49:20 INFO - [mozharness: 2017-06-14 17:49:20.475646Z] Running setup-mitmproxy step. 10:49:20 INFO - Running pre-action listener: _resource_record_pre_action 10:49:20 INFO - Running main action method: setup_mitmproxy 10:49:20 INFO - Skipping: mitmproxy is not required 10:49:20 INFO - Running post-action listener: _resource_record_post_action 10:49:20 INFO - [mozharness: 2017-06-14 17:49:20.476325Z] Finished setup-mitmproxy step (success) 10:49:20 INFO - [mozharness: 2017-06-14 17:49:20.476464Z] Running run-tests step. 10:49:20 INFO - Running pre-action listener: _resource_record_pre_action 10:49:20 INFO - Running main action method: run_tests 10:49:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:49:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:49:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:49:20 INFO - 2017-06-14 10:49:20.490 screenresolution[1630:7308] starting screenresolution argv=screenresolution get 10:49:20 INFO - 2017-06-14 10:49:20.493 screenresolution[1630:7308] Display 0: 1600x1200x32@60 10:49:20 INFO - 2017-06-14 10:49:20.498 screenresolution[1631:7310] starting screenresolution argv=screenresolution list 10:49:20 INFO - Available Modes on Display 0 10:49:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:49:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:49:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:49:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:49:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:49:20 INFO - Intel Iris: 10:49:20 INFO - Chipset Model: Intel Iris 10:49:20 INFO - Type: GPU 10:49:20 INFO - Bus: Built-In 10:49:20 INFO - VRAM (Dynamic, Max): 1536 MB 10:49:20 INFO - Vendor: Intel (0x8086) 10:49:20 INFO - Device ID: 0x0a2e 10:49:20 INFO - Revision ID: 0x0009 10:49:20 INFO - Displays: 10:49:20 INFO - Display: 10:49:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:49:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:49:20 INFO - Display Serial Number: 1600x1200 60 10:49:20 INFO - Main Display: Yes 10:49:20 INFO - Mirror: Off 10:49:20 INFO - Online: Yes 10:49:20 INFO - Rotation: Supported 10:49:20 INFO - Return code: 0 10:49:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:49:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:49:20 INFO - Python 2.7.3 10:49:20 INFO - Return code: 0 10:49:20 INFO - grabbing minidump binary from tooltool 10:49:20 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')]} 10:49:20 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 0x10c01fd30>, '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 0x10bfe9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c010be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:49:20 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 10:49:20 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 10:49:20 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 10:49:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:49:20 INFO - Return code: 0 10:49:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:49:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:49:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:49:20 INFO - ENV: RUST_BACKTRACE is now 1 10:49:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:49:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:49:20 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/dy4mtt07QGyoYZGm18SU3Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0197', '--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 10:49:20 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/dy4mtt07QGyoYZGm18SU3Q/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0197 --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 10:49:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L2g25tvQRn/Render', 10:49:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:49:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:49:20 INFO - 'HOME': '/Users/cltbld', 10:49:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:49:20 INFO - 'LOGNAME': 'cltbld', 10:49:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:49:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:49:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:49:20 INFO - 'MOZ_NO_REMOTE': '1', 10:49:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:49:20 INFO - 'NO_EM_RESTART': '1', 10:49:20 INFO - 'PAGER': '/bin/cat', 10:49:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:49:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:49:20 INFO - 'PWD': '/builds/slave/test', 10:49:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:49:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:49:20 INFO - 'RUST_BACKTRACE': '1', 10:49:20 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 10:49:20 INFO - 'SHELL': '/bin/bash', 10:49:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.itMcUCEQZp/Listeners', 10:49:20 INFO - 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/', 10:49:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:49:20 INFO - 'USER': 'cltbld', 10:49:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:49:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:49:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:49:20 INFO - 'XPC_FLAGS': '0x0', 10:49:20 INFO - 'XPC_SERVICE_NAME': '0', 10:49:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:49:20 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/dy4mtt07QGyoYZGm18SU3Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0197', '--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 10:49:21 INFO - mozversion application_buildid: 20170614092950 10:49:21 INFO - mozversion application_changeset: 9ef7a635694e7b52b8ded9909dfac42e1b2106ea 10:49:21 INFO - mozversion application_display_name: Firefox 10:49:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:49:21 INFO - mozversion application_name: Firefox 10:49:21 INFO - mozversion application_remotingname: firefox 10:49:21 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 10:49:21 INFO - mozversion application_vendor: Mozilla 10:49:21 INFO - mozversion application_version: 55.0 10:49:21 INFO - mozversion platform_buildid: 20170614092950 10:49:21 INFO - mozversion platform_changeset: 9ef7a635694e7b52b8ded9909dfac42e1b2106ea 10:49:21 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 10:49:21 INFO - mozversion platform_version: 55.0 10:49:21 INFO - using testdate: 1497462561 10:49:21 INFO - actual date: 1497462561 10:49:21 INFO - starting webserver on 'localhost:49233' 10:49:21 INFO - SUITE-START | Running 2 tests 10:49:21 INFO - TEST-START | dromaeo_css 10:49:21 INFO - operating with platform_type : mac_ 10:49:21 INFO - Initialising browser for dromaeo_css test... 10:49:21 INFO - Application command: /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49233/getInfo.html -foreground -profile /var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/tmp_yk_yf/profile 10:49:21 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49233/getInfo.html -foreground) 10:49:54 INFO - TEST-INFO | 1641: killed by SIGTERM 10:49:54 INFO - Browser initialized. 10:49:54 INFO - Running cycle 1/1 for dromaeo_css test... 10:49:54 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/tmp_yk_yf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:49:55 INFO - PID 1651 | 2017-06-14 10:49:55.934 plugin-container[1652:7953] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9337, name = 'com.apple.tsm.portname' 10:49:55 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:49:55 INFO - PID 1651 | 2017-06-14 10:49:55.934 plugin-container[1652:7953] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 10:49:55 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:49:55 INFO - PID 1651 | 2017-06-14 10:49:55.934 plugin-container[1652:7953] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:50:06 INFO - PID 1651 | RSS: Main: 184983552 10:50:06 INFO - PID 1651 | 10:53:08 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49233/tests/dromaeo/cssquery-ext.html) 10:53:11 INFO - PID 1651 | RSS: Main: 199024640 10:53:11 INFO - PID 1651 | 10:56:12 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-ext.html (next: http://localhost:49233/tests/dromaeo/cssquery-jquery.html) 10:56:13 INFO - PID 1651 | RSS: Main: 210857984 10:56:13 INFO - PID 1651 | 10:59:14 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49233/tests/dromaeo/cssquery-mootools.html) 10:59:15 INFO - PID 1651 | RSS: Main: 212582400 10:59:15 INFO - PID 1651 | 11:02:17 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49233/tests/dromaeo/cssquery-prototype.html) 11:02:17 INFO - PID 1651 | RSS: Main: 214155264 11:02:17 INFO - PID 1651 | 11:05:20 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49233/tests/dromaeo/cssquery-yui.html) 11:05:21 INFO - PID 1651 | RSS: Main: 214843392 11:05:21 INFO - PID 1651 | 11:08:22 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-yui.html (next: http://localhost:49233/tests/dromaeo/cssquery-dojo.html) 11:08:23 INFO - PID 1651 | RSS: Main: 212393984 11:08:23 INFO - PID 1651 | 11:08:23 INFO - PID 1651 | __start_tp_report 11:08:23 INFO - PID 1651 | _x_x_mozilla_page_load 11:08:23 INFO - PID 1651 | _x_x_mozilla_page_load_details 11:08:23 INFO - PID 1651 | |i|pagename|runs| 11:08:23 INFO - PID 1651 | |0;dojo.html;3757.0795155186984;3864.3442725075374;3870.3033453978287;3890.61093890611;3903.6486716195554;6407.839804004902;6416.839579010521;6447.194100737402;6467.838304042401;6508.153939987807;6607.900881486769;6608.2070151581875;6609.735610575583;6611.801645950617;6617.966910165443;5814.1278808178795;5817.360090390053;5825.067989121741;5934.109883517485;6028.517718582525;5860.091685788689;5894.528437724972;5924.466797988181;6011.879762404749;6015.669138197398;5189.766460509286;5648.74580643872;5695.088785794253;5702.857428564319;5859.765609375641;5426.213199086111;5819.0689489681445;5856.32652244993;5896.823095307148;5906.26171728534;4872.707637541724;5630.268065151545;5788.739506722208;5840.182374467578;5840.386759390245;5724.8282551523525;6185.845353866144;6417.90895547758;6589.682063587302;6598.967005164944;5684.232628595169;7135.143782746052;7153.928460715379;7179.49743517954;7192.784216473514;7063.187733410621;7168.46236532257;7259.564426134449;7277.3814225790875;7302.160251571084;6683.7994860153785;6686.6656667166935;6719.899201511884;6781.287964763727;6788.083608712761;7441.665125069385;7462.776116716507;7475.0656167979005;7478.813029674193;7503.474756767083;6817.420519255819;6931.445484361239;6941.167059952838;6953.930460695328;7074.398676112588;7040.084788977502;7078.92921070793;7096.53872498286;7098.503104782616;7152.28477152291;7132.465065120136;7142.21435642079;7153.0701008869555;7173.92826071743;7185.820354491179;7149.89275160873;7171.92828071723;7183.42532597391;7184.497085203986;7187.532810367309;5586.776528938878;5596.216529685766;5605.8598535036135;5667.376588575254;5770.336411312753;4438.467383913951;4973.801047958041;5000.574951129195;5031.245313203049;5070.543651071422;6158.538109641795;6771.424428923498;6800.115984921928;6812.557183783038;6858;7261.8547629047125;7336.486445948839;7337.523061001125;7344.742933997284;7353.117625884928;6742.190937087581;6756;6763;6764.222114456802;6805.897911531331;4686.203345431285;6131.0096813291075;6143.296949342364;6203.038529028008;6205.441510264098;7430.777076687817;7468.962655186907;7475.887861682079;7508.474406791582;7534.208908064794;7306.086739157606;7325.743598974011;7335.083114610674;7341.889871651822;7350.338469537767;7456.32893039629;7475.140358858584;7500.512466689648;7512.924870751222;7535.246475352421;7154.463415243981;7178.641067946686;7190.856182876209;7195.172555156015;7209.3872020876715;6951.652417379009;7106.7157313706875;7124.821879453055;7169.677364518505;7305.926940730525;7006.299370062953;7038.472114591324;7047.048648432396;7074.3633073023675;7089.291070892869;5859.912101318569;7051.753188638373;7058.188308344401;7069.893951590628;7070.611116388648;6557.803265902031;6731.663416829237;6788.456923446014;6896.310368963063;6929.792106236821;6118.954222337368;6844.178698556205;6855.5886646801355;6868.965655171693;6892.586444813327;5549.278593782782;5688.7440065198;6919.135108111486;6921.688524016331;6928.411085057826 11:08:23 INFO - PID 1651 | |1;ext.html;767.9884801727972;865.1002956924799;872.4242000279817;887.3344991256556;905.0813424374261;40559.188816223694;41846.16307673852;41907.37138942914;41939.58060419402;42000.57999420005;41661.375079373836;41785.58214417855;41901;41916.53292134776;42073.78963105188;7983.52098874068;8060.395470339748;8073.354131669453;8082.71710490133;8097.2307630774985;7085.858282834341;7979.56112413817;8027.237412445808;8034.758957231264;8080.191980801932;8858.955705221464;8873.068327825582;8927.107289271053;8927.55362231889;8930.285577153812;7953.562554059525;8269.751907442786;8865.379423440361;8904.154105360012;8929.95535022324;8697.130286971253;8746.300295976307;8753.868691969625;8757.124287571256;8784.64861405543;38735;38944;38972.610273897466;39045.828625141294;39138.412923806165;3630.6006339302658;3660.8535658573624;3661.7802931824176;3670.687991520724;3673.1551743099235;31735;32046.519302210487;32048.679513204803;32089;32147.83926080378;3197.2806118623357;3335.266241426882;3337.2824842658947;3351.6648335166533;3354.111160542458;239009;241069.79465102562;241107;321453;322258;42450;51711.48288517142;52251.47748522542;52477.7376113117;52543.73728131335;54527;54537.72731136398;54627;54628.45371546313;54675.72662136744;16569.83430165707;16703.248353823863;16745.246463909152;16833.494995150166;16885.746713799304;13659.521916732867;13719.725605487834;13737.862621373859;13740.65648358799;13751.587452376445;14529.418823246951;14578.416863325348;14585.093037319903;14608.634784130481;14702.89728270384;6196.47329976957;6266.561340706106;6297.118403423525;6303.9684801575695;6307.4954003679595;1039.407537703517;1040.167865707431;1044.6187141693226;1098.549594666183;1100.8423489959769;22198.77801221967;23850.40373990664;24855.6329401881;25366.239012829643;25424.618630720553;950.5817440326234;1108.9465008242203;1156.8264760285856;1182.1547593470539;1206.7043574324346;12444.81332780009;12731.93634031824;13044.934775326063;13151.145175563557;13158.73682526344;269483;309632;322472.3876380603;323463;323912.760872393;58601;58865.70567147137;58913.70543147257;59022.819543608886;59177.40822591805;1330.1154732103043;1496.453794365049;1527.5951872753728;1593.6653302806308;1597.8082630084232;23565.528689425773;23944.64083038721;23983.52032959296;24173.395665108514;24857.751422485544;53540;54678;54738;54861;54893.72553137368;270904;271411.2858871386;271950;271963;272121;31566.053018409486;31857.681423186397;31870.68129318677;34431.827840860635;35169.64830351664;45513.63459096233;45721.77139114283;45826.31260531028;46078.53921460742;47525.52474475211;27771.91710621671;29464.968726094485;29468.11595652134;29542.261443464085;32380.35239295154;23940.640890387007;28867.5669864948;31704.36591268208;31713.04860854178;32197;36945;37285.25429491341;37728.81135594304;38077.238455231294;38082.428763568016;30399.69600304057;30977.209738201178;31104.688953111083;31577.842110789297;32054.679453205168;22067.889660552235;23848.569085854906;25101.623475648244;25375;25577.488450231263 11:08:23 INFO - PID 1651 | |2;jquery.html;513.5198589318987;537.8440252326825;542.487349454765;547.3923944421691;549.5219159331381;24041.639375409362;24860.502789944192;24944;24959.500809983834;25019.249422517307;25329.87335063327;28010.439791204164;28128.01551945682;28128.578071328895;28154.155375338774;6855.1431071115985;6957.339052789976;6960.825979350506;7005.019297298357;7016.649167541628;6929.341712537301;6984.301569843001;6985.965070174642;6999.790006299819;7004.369606735393;6254.7498100075945;6471.932131198347;6867.759628413007;6894.655267236643;6909.965450172742;6879.965600171992;6896.20693620235;6910.5853648780985;6932.930670693304;6940.063091482686;6359.109724638554;6364.427201551836;6365.267994180682;6373.521985851034;6374.394432528902;32692.182695432566;33324.3335133296;33467;33615;33835.32329353424;2335.112657190257;2393.1504315967754;2393.8563686178886;2395.1616934072936;2397.3167647220457;16811.915940420342;17663.293468261254;19482.733622314503;20093.698594521094;20623.071961761758;2280.8631482111127;2326.3718795925174;2347.859128452298;2361.031976889484;2371.4822513591316;193156.06843931662;216274;216887;219684;222434;13154.671133221555;16110.838891611169;16217.35130594763;16440.095794731165;16456.6708665826;18234.817651823578;22185.889070554866;22284.108635654717;22317.884105794645;22326.33021009372;11050.281731687415;11078.446077696082;11190.888091119148;11211.831822522669;11265.662030139109;10481.790364192673;10529.262951593468;10534.789304213873;10535.157187424988;10600.787984240425;7954.164812694699;8009.118996910335;9723.708288751348;9831.950840245896;9915.107640312406;2591.766740993282;2798.328401183742;2803.8177518461234;2814.521531339677;2890.8265504069404;875.6847534887436;1004.4124187350482;1010.1262408017012;1063.8138325792954;1068.3002633275216;22070.33788986333;22130.44673883166;22324.218652347416;22375;23057.884710576676;1033.2199189611945;1037.5434808684306;1075.5052675769077;1088.983665245022;1097.2209731090854;11308.886911130949;11437.428128593703;11465;11506.252093613888;11940.701482462835;560134;561813;570726;579699;580036.0998195066;168089;168963;169724;170289;172963;1150.3098141115431;1223.7552489502136;1239.9814002789965;1328.8870446012197;1334.1128149780288;22740.545189096458;25172.11897583649;25216.873915630305;25270.620940686273;25324.873375633004;72332.27667723256;75016.49967000738;75116.62441687756;75620;76129.61935190289;94213.58679619948;96260.51869740606;96827;98078.01921980688;98813;10360.430176340373;12141.332226727614;12316.384180790745;12404.503819847467;12486;15388.769168462706;19201;19287.614247715246;19436;20119.798802011792;16143.515694529184;16168.353265869233;16310.429134980219;16337.346506139622;16450.259738312045;13858.099223550436;13911;14016.579502614937;14468.27658617086;14715.632109197355;16442.424515141913;16784.32862685479;16808.243629036966;16933.49199524016;16934.322627094778;12467.875321247035;12566.937165314115;12699.492020319454;12720.68198295013;12721.491140354652;16094.356225750838;16108.27512761952;16122.274497647399;16142.354305827635;16233.756493652842 11:08:23 INFO - PID 1651 | |3;mootools.html;1293.5084667826227;1487.0631502153642;1495.0805254768313;1495.9551213463594;1505.8343582205955;14163;14722.337494812733;14757.704845903076;14776.33506492209;14815;14186.148831070142;14413.63965900853;14438.927805360985;14591;14600.707985840278;2910.4522074156416;2914.8396838173894;2953.3650265192978;2956.216602600313;2957.3493831357173;2909.11272062021;2924.0350684274244;2927.4291513154944;2928.4582352264792;2928.970710292902;2864.8997285095024;2895.8407287599175;2912.956305655406;2915.5043642580813;2919.5912572239904;2374.3589230907733;2870.598116263725;2871.3252385689348;2894.102828123278;2895.551189565621;2838.630977972871;2853.4721076600895;2859.2137162280333;2860.75683566895;2872.482953068447;13944.302784860818;14174.92912535441;14185.574432767033;14244.359003844851;14252.429902803875;7899.05211374633;8197.385196110316;8202;8203.30271926887;8213.466124701876;4932.8520144395725;5162.896742065175;5170.405403378622;5175.430702622709;5238.947610523884;1464.5459907428624;1483.7625979843174;1516.8483151684854;1516.9848301517063;1523.5200911712775;19691.21235150592;19821.801781981994;21906.561868762696;21994.34016979493;22141.66787498189;6373.458256048288;6593.3736295051895;7377.852442951111;7449.292317229854;7505.512141710771;15222.314995825214;15981.520554383387;16867.240974155946;17327.22027508765;17483.912580437016;8117.553534555656;8221.958890205511;8267.545285009262;8286.337093032544;8306.044804847399;7071.469639777038;7356.080489938758;7481.989931359197;7503;7513.173550909396;5728.914066289009;5971.253593300838;5981.132735753292;5988.401159884064;5997.850053748692;1551.9301631426613;1797.0232743981517;1842.3643842874187;1862.208561361416;1873.8875667459995;841.8610929196736;859.4542465534431;868.7306934850217;871.2724874729672;887.9289656827567;6059.424354686298;7827.373810095179;7963.0046244219475;8180.386471014698;8303.543305118277;717.7129148340601;728.3735987051132;731.2687312687312;750.5871770526189;756.8902509136144;3971.7616942983514;4839.951600484021;4956.10790057793;5331.626786124934;5335.493128152819;18157.182926768328;18353.54116147107;18528.81471185271;19022.334218302294;19087.618247635248;18370;18943.673942824145;18977.620447590973;19028.238870445304;19055.809441905403;8747.737567872846;9940.353876997971;10029.849552256575;10038.447885366375;10173.745656358651;7442.106947166375;7846.489978151402;7915.722949696733;8044.879326809985;8507.617157227816;16574.17129143562;16598.66802663964;17255.737000214554;17515.21181546859;17612.73580896313;16439.178041098137;17420.216090275713;17732.202050907483;17845.018523981307;17901.462956111336;11375.31748095117;11535.423228838692;11540.94229528847;11548.133889958119;11556.422178890854;13041.217526948414;13068.215907045607;13094.279814610289;13100;13108.541201057917;8367.790805229917;10229.232807539613;10677.786444270916;10825.621103261663;10926.071283940953;7454.962725186556;10289.691309260734;10337.397703355991;10621.89378106209;10684.09185219265;9426.293028022788;9606.66376676841;9723.611055557698;9745.756356091155;9755.756106097404;6944.097267355211;7046.788596342118;7064.222935477196;7292.088488938883;7390.371818395497;6449.935760599448;6716.731330746716;7195.532290401107;7414.258574142543;7482.738104166328 11:08:23 INFO - PID 1651 | |4;prototype.html;452.0314032388944;455.61045306263145;474.1986043586341;483.4923330502971;493.9454265143919;6287.7799277025315;6648.204984654147;6673.833154171142;6696.196456425224;6718.899216511756;5301.973490132544;6251.749930002795;6479.384458476437;6501.609903405787;6529.90205146922;3732.4774531565467;3745.756525825813;4378.562143785628;4781.250780801045;4987.376577927759;3788.7347885648014;4300.913981720364;4942.555170034696;4955.876103097434;4984.77568509418;4115.506139263292;4558.111168322179;4819.951800482003;4830.637702172351;4920.3111564381015;4767.856964291077;4830.661853670244;4897.191963326047;4911.673848061004;4924.630652701062;3652.433872749728;3659.6889264412557;3751.81817727415;4283.293256612656;4335.631471324909;5769.615292329852;6102.589477156543;6133.601315914451;6262.561620686553;6285.622862628257;4817.132916075105;5624.091256576681;5856.677882716449;5857.589968702193;5884.58807883444;6921.307869213088;6935.549189302679;6940.410065144469;6949.44404447643;6951.582905025714;4644.815572029145;4888.608911287089;4948.653594248404;4955.380577427822;4963.45402005779;6820.590764554093;7435.40516758658;7462.365698915599;7489.737859174903;7510.9624451877935;4184.8744537663915;5007.599392048627;5058.797648094109;5076.949230507647;5091.210862316347;4314.546972567898;5130.486951304914;5184.585233181337;5190.195519694454;5197.58419326453;4879.4388645305535;5872.471477567039;5883.646981181143;5890.028145355968;5891.263592050994;6489.967550162219;6773.766396002498;7067.257937916547;7074.646267686698;7189.388901943394;1743.494386627889;1744.842964133234;1756.0868348458985;1756.3238153311067;1764.0738612228427;1085.8751243607087;1167.3637867362138;1176.311857563318;1203.3201241298639;1211.3640338822188;6176.6911654418;6481.708323125471;6548.934510654928;6662;6730.259671436138;6775.119234499483;7270.309320614533;7301.963490182515;7318.3047610476915;7324.780256592204;6586;6711.899321510181;6712.395884370332;6712.731490740413;6722.092517510171;3303.9504407433906;3705.796181210005;3767.7550959187565;3787.223619158066;3791.298609757204;6618.86762264752;7497.437692173109;7507.986421832983;7516.5114267572435;7546.396288296923;5863.73613187399;6519.706613202417;7157.4989750718;7439.851202976019;7501.437392195608;6127.724252408563;7011.754588589477;7346.228645992094;7439.888401673979;7498.725216713063;6302.936970630235;7242.565446073252;7277.3086556777025;7286.198518162892;7302.488825782144;6687.461883766859;7148.142222933281;7157.821054473471;7169.211386747558;7230.963845180951;5601.159826026129;5990.9700451497465;6706.798796036127;7222.783316500513;7511.661975211019;3787.9242415150993;4262.296721041118;4989.376327959005;4991.875203119951;5016.322796422436;6415;7259.310365515267;7297.708091676486;7310.159331676925;7376.926230737624;6258.718357674006;6641.169853768279;7031.824204394931;7044.718211271492;7075.363217310467;5489.64317319373;6937.965310173417;7015.824604384727;7026.208316879163;7047.647617619201;5099.847004589868;5798.883407556296;6409.7436102555375;6833.555818871758;6915.308469153045;5597.720113994203;5780.913286300624;7024.4029257513685;7053.858922821412;7080.468964827555;6405.19935008124;6417.480966368826;6841.486888483483;6844.178698556205;6876.312368763083 11:08:23 INFO - PID 1651 | |5;yui.html;859.6131740716675;885.8272636835816;887.5562218890555;897.9910200897989;907.709532949456;17877.91061044696;18777.812221877793;18820.43538693838;18821.529461763428;18833.71749423758;16681.416150434736;16766.916165419185;17307.048112353816;17587;17716.911415442937;1019.4138370437009;1040.4225654761603;1048.8269435543127;1049.5382031905974;1049.774298525817;1059.1579694143174;1059.2108878885203;1061.4427425601543;1061.9734506637358;1062.097217365241;946.30919428817;1092.9289596176263;1111.1055600241802;1111.416506334173;1114.5151858941385;1062.2617280989716;1084.8264277715607;1096.5668560918432;1108.3959242213014;1110.5779803674593;790.6837265093951;790.746960972489;802.743122200896;805.9274665280153;809.6235250608437;15778.76331855023;15818.841811581851;15864.603384915354;15964.680706385923;15980.76028859568;14390.424383024667;14593.905457090654;14946.850531494654;15108.848911510853;15302.617434564114;12028.63914082579;12492.188007779465;13846.10000349974;13993.72012559743;14189.645258868508;3572.5534308211472;3667.3032123896373;3708.9814550927344;3713.7771733695795;3715.2198038411966;104023.47988260086;105694.47152764341;148442;152707;154226;11914.225575337576;11953.342566158944;12166.635000949986;12235.082368822195;12815.16701414405;22052.338429847132;22441.775582244296;25474.85363158625;26037.73962260391;26040.73959260421;8505.574721264036;8583.18459746323;8586.227239548345;8623.611937462829;8643.697470588499;4136.772477513706;4203.810828512725;4235.449391579075;4237.724547904377;4243.0241044559925;4837.685550439211;4864.464908860023;4893.584045356185;4923.089228492669;4935.703857768545;895.6014573514724;903.5256490342623;1018.8013337399136;1040.7294103532984;1047.0942634621;605.5034871405405;667.8831204539187;673.1451057157383;685.4413652872893;686.8145600687886;10342.793144137075;11961.88038119625;12589.181703189446;12807.679808004874;12979.766922142382;665.6771465839064;667.1527160506226;685.348918527401;696.9965150174216;705.0693085127684;5665.3201615806365;5800.970995144998;6080.027195648763;6108.51131909455;6645.734170633121;137969.62030379768;145789.5421045797;147544;147745;156347.21826390794;78426.6078669603;90288;126110;128294;132036;47831.5216847834;100901;101539.49230253801;103145.48427257816;109179.45410272898;9368.203702685349;11044.558217671203;12699.746005080031;13239.27184004889;13462.259575723428;46951;47775.76112119417;49196.52410427693;50017.49982500129;50146.99706005932;74674.13314667177;75135.62432187804;85599.4320170396;87496.68754968554;88187.55906220684;17843.107844607977;17907.28370865203;18225.270989160814;18228.36200732968;18355.908220459347;15829.920850396134;18486.352977645718;18629.161687723812;18914.52713682169;19283.132259048096;15211;17465.476035718948;17900.373486927896;17959.551011224823;18153.00158491243;16879.028631389818;17583.912080439517;17841;18197.090145492937;18252.26990920348;17316;17674.734878977084;17749.911250443663;17782.733259000866;17938;11573.942130289632;11705.414729263674;12020.519179233082;12471.314077725812;12589.622311330308;13573.321333933067;15042.172680502677;15129.924350378178;15160.772588411402;15187.240637968278 11:08:23 INFO - PID 1651 | __end_tp_report 11:08:23 INFO - PID 1651 | __start_cc_report 11:08:23 INFO - PID 1651 | _x_x_mozilla_cycle_collect,385 11:08:23 INFO - PID 1651 | __end_cc_report 11:08:23 INFO - PID 1651 | __startTimestamp1497463703161__endTimestamp 11:08:23 INFO - PID 1651 | 11:08:23 INFO - PID 1651 | ------- Summary: start ------- 11:08:23 INFO - PID 1651 | Number of tests: 6 11:08:23 INFO - PID 1651 | 11:08:23 INFO - PID 1651 | [#0] dojo.html Cycles:165 Average:6554.37 Median:6817.42 stddev:843.04 (12.4%) stddev-sans-first:816.56 11:08:23 INFO - PID 1651 | Values: 3757.1 3864.3 3870.3 3890.6 3903.6 6407.8 6416.8 6447.2 6467.8 6508.2 6607.9 6608.2 6609.7 6611.8 6618.0 5814.1 5817.4 5825.1 5934.1 6028.5 5860.1 5894.5 5924.5 6011.9 6015.7 5189.8 5648.7 5695.1 5702.9 5859.8 5426.2 5819.1 5856.3 5896.8 5906.3 4872.7 5630.3 5788.7 5840.2 5840.4 5724.8 6185.8 6417.9 6589.7 6599.0 5684.2 7135.1 7153.9 7179.5 7192.8 7063.2 7168.5 7259.6 7277.4 7302.2 6683.8 6686.7 6719.9 6781.3 6788.1 7441.7 7462.8 7475.1 7478.8 7503.5 6817.4 6931.4 6941.2 6953.9 7074.4 7040.1 7078.9 7096.5 7098.5 7152.3 7132.5 7142.2 7153.1 7173.9 7185.8 7149.9 7171.9 7183.4 7184.5 7187.5 5586.8 5596.2 5605.9 5667.4 5770.3 4438.5 4973.8 5000.6 5031.2 5070.5 6158.5 6771.4 6800.1 6812.6 6858.0 7261.9 7336.5 7337.5 7344.7 7353.1 6742.2 6756.0 6763.0 6764.2 6805.9 4686.2 6131.0 6143.3 6203.0 6205.4 7430.8 7469.0 7475.9 7508.5 7534.2 7306.1 7325.7 7335.1 7341.9 7350.3 7456.3 7475.1 7500.5 7512.9 7535.2 7154.5 7178.6 7190.9 7195.2 7209.4 6951.7 7106.7 7124.8 7169.7 7305.9 7006.3 7038.5 7047.0 7074.4 7089.3 5859.9 7051.8 7058.2 7069.9 7070.6 6557.8 6731.7 6788.5 6896.3 6929.8 6119.0 6844.2 6855.6 6869.0 6892.6 5549.3 5688.7 6919.1 6921.7 6928.4 11:08:23 INFO - PID 1651 | 11:08:23 INFO - PID 1651 | [#1] ext.html Cycles:180 Average:45150.25 Median:3352.89 stddev:74998.87 (2236.8%) stddev-sans-first:75135.23 11:08:23 INFO - PID 1651 | Values: 768.0 865.1 872.4 887.3 905.1 40559.2 41846.2 41907.4 41939.6 42000.6 41661.4 41785.6 41901.0 41916.5 42073.8 7983.5 8060.4 8073.4 8082.7 8097.2 7085.9 7979.6 8027.2 8034.8 8080.2 8859.0 8873.1 8927.1 8927.6 8930.3 7953.6 8269.8 8865.4 8904.2 8930.0 8697.1 8746.3 8753.9 8757.1 8784.6 38735.0 38944.0 38972.6 39045.8 39138.4 3630.6 3660.9 3661.8 3670.7 3673.2 31735.0 32046.5 32048.7 32089.0 32147.8 3197.3 3335.3 3337.3 3351.7 3354.1 239009.0 241069.8 241107.0 321453.0 322258.0 42450.0 51711.5 52251.5 52477.7 52543.7 54527.0 54537.7 54627.0 54628.5 54675.7 16569.8 16703.2 16745.2 16833.5 16885.7 13659.5 13719.7 13737.9 13740.7 13751.6 14529.4 14578.4 14585.1 14608.6 14702.9 6196.5 6266.6 6297.1 6304.0 6307.5 1039.4 1040.2 1044.6 1098.5 1100.8 22198.8 23850.4 24855.6 25366.2 25424.6 950.6 1108.9 1156.8 1182.2 1206.7 12444.8 12731.9 13044.9 13151.1 13158.7 269483.0 309632.0 322472.4 323463.0 323912.8 58601.0 58865.7 58913.7 59022.8 59177.4 1330.1 1496.5 1527.6 1593.7 1597.8 23565.5 23944.6 23983.5 24173.4 24857.8 53540.0 54678.0 54738.0 54861.0 54893.7 270904.0 271411.3 271950.0 271963.0 272121.0 31566.1 31857.7 31870.7 34431.8 35169.6 45513.6 45721.8 45826.3 46078.5 47525.5 27771.9 29465.0 29468.1 29542.3 32380.4 23940.6 28867.6 31704.4 31713.0 32197.0 36945.0 37285.3 37728.8 38077.2 38082.4 30399.7 30977.2 31104.7 31577.8 32054.7 22067.9 23848.6 25101.6 25375.0 25577.5 11:08:23 INFO - PID 1651 | 11:08:23 INFO - PID 1651 | [#2] jquery.html Cycles:180 Average:41917.03 Median:22325.27 stddev:100135.62 (448.5%) stddev-sans-first:100368.28 11:08:23 INFO - PID 1651 | Values: 513.5 537.8 542.5 547.4 549.5 24041.6 24860.5 24944.0 24959.5 25019.2 25329.9 28010.4 28128.0 28128.6 28154.2 6855.1 6957.3 6960.8 7005.0 7016.6 6929.3 6984.3 6986.0 6999.8 7004.4 6254.7 6471.9 6867.8 6894.7 6910.0 6880.0 6896.2 6910.6 6932.9 6940.1 6359.1 6364.4 6365.3 6373.5 6374.4 32692.2 33324.3 33467.0 33615.0 33835.3 2335.1 2393.2 2393.9 2395.2 2397.3 16811.9 17663.3 19482.7 20093.7 20623.1 2280.9 2326.4 2347.9 2361.0 2371.5 193156.1 216274.0 216887.0 219684.0 222434.0 13154.7 16110.8 16217.4 16440.1 16456.7 18234.8 22185.9 22284.1 22317.9 22326.3 11050.3 11078.4 11190.9 11211.8 11265.7 10481.8 10529.3 10534.8 10535.2 10600.8 7954.2 8009.1 9723.7 9832.0 9915.1 2591.8 2798.3 2803.8 2814.5 2890.8 875.7 1004.4 1010.1 1063.8 1068.3 22070.3 22130.4 22324.2 22375.0 23057.9 1033.2 1037.5 1075.5 1089.0 1097.2 11308.9 11437.4 11465.0 11506.3 11940.7 560134.0 561813.0 570726.0 579699.0 580036.1 168089.0 168963.0 169724.0 170289.0 172963.0 1150.3 1223.8 1240.0 1328.9 1334.1 22740.5 25172.1 25216.9 25270.6 25324.9 72332.3 75016.5 75116.6 75620.0 76129.6 94213.6 96260.5 96827.0 98078.0 98813.0 10360.4 12141.3 12316.4 12404.5 12486.0 15388.8 19201.0 19287.6 19436.0 20119.8 16143.5 16168.4 16310.4 16337.3 16450.3 13858.1 13911.0 14016.6 14468.3 14715.6 16442.4 16784.3 16808.2 16933.5 16934.3 12467.9 12566.9 12699.5 12720.7 12721.5 16094.4 16108.3 16122.3 16142.4 16233.8 11:08:23 INFO - PID 1651 | 11:08:23 INFO - PID 1651 | [#3] mootools.html Cycles:180 Average:8735.77 Median:2894.83 stddev:5781.64 (199.7%) stddev-sans-first:5770.81 11:08:23 INFO - PID 1651 | Values: 1293.5 1487.1 1495.1 1496.0 1505.8 14163.0 14722.3 14757.7 14776.3 14815.0 14186.1 14413.6 14438.9 14591.0 14600.7 2910.5 2914.8 2953.4 2956.2 2957.3 2909.1 2924.0 2927.4 2928.5 2929.0 2864.9 2895.8 2913.0 2915.5 2919.6 2374.4 2870.6 2871.3 2894.1 2895.6 2838.6 2853.5 2859.2 2860.8 2872.5 13944.3 14174.9 14185.6 14244.4 14252.4 7899.1 8197.4 8202.0 8203.3 8213.5 4932.9 5162.9 5170.4 5175.4 5238.9 1464.5 1483.8 1516.8 1517.0 1523.5 19691.2 19821.8 21906.6 21994.3 22141.7 6373.5 6593.4 7377.9 7449.3 7505.5 15222.3 15981.5 16867.2 17327.2 17483.9 8117.6 8222.0 8267.5 8286.3 8306.0 7071.5 7356.1 7482.0 7503.0 7513.2 5728.9 5971.3 5981.1 5988.4 5997.9 1551.9 1797.0 1842.4 1862.2 1873.9 841.9 859.5 868.7 871.3 887.9 6059.4 7827.4 7963.0 8180.4 8303.5 717.7 728.4 731.3 750.6 756.9 3971.8 4840.0 4956.1 5331.6 5335.5 18157.2 18353.5 18528.8 19022.3 19087.6 18370.0 18943.7 18977.6 19028.2 19055.8 8747.7 9940.4 10029.8 10038.4 10173.7 7442.1 7846.5 7915.7 8044.9 8507.6 16574.2 16598.7 17255.7 17515.2 17612.7 16439.2 17420.2 17732.2 17845.0 17901.5 11375.3 11535.4 11540.9 11548.1 11556.4 13041.2 13068.2 13094.3 13100.0 13108.5 8367.8 10229.2 10677.8 10825.6 10926.1 7455.0 10289.7 10337.4 10621.9 10684.1 9426.3 9606.7 9723.6 9745.8 9755.8 6944.1 7046.8 7064.2 7292.1 7390.4 6449.9 6716.7 7195.5 7414.3 7482.7 11:08:23 INFO - PID 1651 | 11:08:23 INFO - PID 1651 | [#4] prototype.html Cycles:180 Average:5632.53 Median:6260.64 stddev:1762.63 (28.2%) stddev-sans-first:1724.15 11:08:23 INFO - PID 1651 | Values: 452.0 455.6 474.2 483.5 493.9 6287.8 6648.2 6673.8 6696.2 6718.9 5302.0 6251.7 6479.4 6501.6 6529.9 3732.5 3745.8 4378.6 4781.3 4987.4 3788.7 4300.9 4942.6 4955.9 4984.8 4115.5 4558.1 4820.0 4830.6 4920.3 4767.9 4830.7 4897.2 4911.7 4924.6 3652.4 3659.7 3751.8 4283.3 4335.6 5769.6 6102.6 6133.6 6262.6 6285.6 4817.1 5624.1 5856.7 5857.6 5884.6 6921.3 6935.5 6940.4 6949.4 6951.6 4644.8 4888.6 4948.7 4955.4 4963.5 6820.6 7435.4 7462.4 7489.7 7511.0 4184.9 5007.6 5058.8 5076.9 5091.2 4314.5 5130.5 5184.6 5190.2 5197.6 4879.4 5872.5 5883.6 5890.0 5891.3 6490.0 6773.8 7067.3 7074.6 7189.4 1743.5 1744.8 1756.1 1756.3 1764.1 1085.9 1167.4 1176.3 1203.3 1211.4 6176.7 6481.7 6548.9 6662.0 6730.3 6775.1 7270.3 7302.0 7318.3 7324.8 6586.0 6711.9 6712.4 6712.7 6722.1 3304.0 3705.8 3767.8 3787.2 3791.3 6618.9 7497.4 7508.0 7516.5 7546.4 5863.7 6519.7 7157.5 7439.9 7501.4 6127.7 7011.8 7346.2 7439.9 7498.7 6302.9 7242.6 7277.3 7286.2 7302.5 6687.5 7148.1 7157.8 7169.2 7231.0 5601.2 5991.0 6706.8 7222.8 7511.7 3787.9 4262.3 4989.4 4991.9 5016.3 6415.0 7259.3 7297.7 7310.2 7376.9 6258.7 6641.2 7031.8 7044.7 7075.4 5489.6 6938.0 7015.8 7026.2 7047.6 5099.8 5798.9 6409.7 6833.6 6915.3 5597.7 5780.9 7024.4 7053.9 7080.5 6405.2 6417.5 6841.5 6844.2 6876.3 11:08:23 INFO - PID 1651 | 11:08:23 INFO - PID 1651 | [#5] yui.html Cycles:180 Average:25195.37 Median:17766.32 stddev:38322.72 (215.7%) stddev-sans-first:38386.66 11:08:23 INFO - PID 1651 | Values: 859.6 885.8 887.6 898.0 907.7 17877.9 18777.8 18820.4 18821.5 18833.7 16681.4 16766.9 17307.0 17587.0 17716.9 1019.4 1040.4 1048.8 1049.5 1049.8 1059.2 1059.2 1061.4 1062.0 1062.1 946.3 1092.9 1111.1 1111.4 1114.5 1062.3 1084.8 1096.6 1108.4 1110.6 790.7 790.7 802.7 805.9 809.6 15778.8 15818.8 15864.6 15964.7 15980.8 14390.4 14593.9 14946.9 15108.8 15302.6 12028.6 12492.2 13846.1 13993.7 14189.6 3572.6 3667.3 3709.0 3713.8 3715.2 104023.5 105694.5 148442.0 152707.0 154226.0 11914.2 11953.3 12166.6 12235.1 12815.2 22052.3 22441.8 25474.9 26037.7 26040.7 8505.6 8583.2 8586.2 8623.6 8643.7 4136.8 4203.8 4235.4 4237.7 4243.0 4837.7 4864.5 4893.6 4923.1 4935.7 895.6 903.5 1018.8 1040.7 1047.1 605.5 667.9 673.1 685.4 686.8 10342.8 11961.9 12589.2 12807.7 12979.8 665.7 667.2 685.3 697.0 705.1 5665.3 5801.0 6080.0 6108.5 6645.7 137969.6 145789.5 147544.0 147745.0 156347.2 78426.6 90288.0 126110.0 128294.0 132036.0 47831.5 100901.0 101539.5 103145.5 109179.5 9368.2 11044.6 12699.7 13239.3 13462.3 46951.0 47775.8 49196.5 50017.5 50147.0 74674.1 75135.6 85599.4 87496.7 88187.6 17843.1 17907.3 18225.3 18228.4 18355.9 15829.9 18486.4 18629.2 18914.5 19283.1 15211.0 17465.5 17900.4 17959.6 18153.0 16879.0 17583.9 17841.0 18197.1 18252.3 17316.0 17674.7 17749.9 17782.7 17938.0 11573.9 11705.4 12020.5 12471.3 12589.6 13573.3 15042.2 15129.9 15160.8 15187.2 11:08:23 INFO - PID 1651 | -------- Summary: end -------- 11:08:23 INFO - PID 1651 | 11:08:23 INFO - TEST-INFO | 1651: exit 0 11:08:23 INFO - TEST-OK | dromaeo_css | took 1142597ms 11:08:23 INFO - TEST-START | kraken 11:08:23 INFO - operating with platform_type : mac_ 11:08:23 INFO - Initialising browser for kraken test... 11:08:23 INFO - Application command: /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49233/getInfo.html -foreground -profile /var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/tmpjXe29H/profile 11:08:23 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49233/getInfo.html -foreground) 11:08:56 INFO - TEST-INFO | 1662: killed by SIGTERM 11:08:56 INFO - Browser initialized. 11:08:56 INFO - Running cycle 1/1 for kraken test... 11:08:56 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/tmpjXe29H/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:08:58 INFO - PID 1665 | 2017-06-14 11:08:58.347 plugin-container[1666:10898] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 11:08:58 INFO - PID 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:08:58 INFO - PID 1665 | 2017-06-14 11:08:58.348 plugin-container[1666:10898] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9417, name = 'com.apple.CFPasteboardClient' 11:08:58 INFO - PID 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:08:58 INFO - PID 1665 | 2017-06-14 11:08:58.348 plugin-container[1666:10898] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:09:08 INFO - PID 1665 | RSS: Main: 184750080 11:09:08 INFO - PID 1665 | 11:09:11 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49233/tests/kraken/driver.html?testName=audio-beat-detection) 11:09:12 INFO - PID 1665 | RSS: Main: 198479872 11:09:12 INFO - PID 1665 | 11:09:14 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49233/tests/kraken/driver.html?testName=audio-dft) 11:09:15 INFO - PID 1665 | RSS: Main: 209006592 11:09:15 INFO - PID 1665 | 11:09:18 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49233/tests/kraken/driver.html?testName=audio-fft) 11:09:19 INFO - PID 1665 | RSS: Main: 213946368 11:09:19 INFO - PID 1665 | 11:09:21 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49233/tests/kraken/driver.html?testName=audio-oscillator) 11:09:21 INFO - PID 1665 | RSS: Main: 214454272 11:09:21 INFO - PID 1665 | 11:09:23 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49233/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:09:24 INFO - PID 1665 | RSS: Main: 214253568 11:09:24 INFO - PID 1665 | 11:09:27 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49233/tests/kraken/driver.html?testName=imaging-darkroom) 11:09:27 INFO - PID 1665 | RSS: Main: 218132480 11:09:27 INFO - PID 1665 | 11:09:30 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49233/tests/kraken/driver.html?testName=imaging-desaturate) 11:09:31 INFO - PID 1665 | RSS: Main: 219697152 11:09:31 INFO - PID 1665 | 11:09:34 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49233/tests/kraken/driver.html?testName=json-parse-financial) 11:09:34 INFO - PID 1665 | RSS: Main: 219676672 11:09:34 INFO - PID 1665 | 11:09:36 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49233/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:09:37 INFO - PID 1665 | RSS: Main: 219631616 11:09:37 INFO - PID 1665 | 11:09:38 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:09:39 INFO - PID 1665 | RSS: Main: 219619328 11:09:39 INFO - PID 1665 | 11:09:41 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:09:42 INFO - PID 1665 | RSS: Main: 219287552 11:09:42 INFO - PID 1665 | 11:09:44 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:09:45 INFO - PID 1665 | RSS: Main: 219131904 11:09:45 INFO - PID 1665 | 11:09:48 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:09:49 INFO - PID 1665 | RSS: Main: 219414528 11:09:49 INFO - PID 1665 | 11:09:50 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49233/tests/kraken/driver.html?testName=ai-astar) 11:09:51 INFO - PID 1665 | RSS: Main: 220930048 11:09:51 INFO - PID 1665 | 11:09:51 INFO - PID 1665 | __start_tp_report 11:09:51 INFO - PID 1665 | _x_x_mozilla_page_load 11:09:51 INFO - PID 1665 | _x_x_mozilla_page_load_details 11:09:51 INFO - PID 1665 | |i|pagename|runs| 11:09:51 INFO - PID 1665 | |0;ai-astar;99;114;104;113;115;145;171;135;116;117 11:09:51 INFO - PID 1665 | |1;audio-beat-detection;131;108;130;182;138;127;113;139;145;107 11:09:51 INFO - PID 1665 | |2;audio-dft;193;198;196;200;195;197;195;196;195;202 11:09:51 INFO - PID 1665 | |3;audio-fft;66;87;79;170;51;85;87;98;158;50 11:09:51 INFO - PID 1665 | |4;audio-oscillator;67;70;109;83;102;108;135;69;69;111 11:09:51 INFO - PID 1665 | |5;imaging-gaussian-blur;95;90;89;88;89;88;89;90;89;90 11:09:51 INFO - PID 1665 | |6;imaging-darkroom;96;97;96;96;96;96;95;96;95;95 11:09:51 INFO - PID 1665 | |7;imaging-desaturate;92;93;91;91;99;146;146;90;89;90 11:09:51 INFO - PID 1665 | |8;json-parse-financial;49;47;49;51;50;50;51;52;52;60 11:09:51 INFO - PID 1665 | |9;json-stringify-tinderbox;54;54;57;69;81;89;90;57;55;54 11:09:51 INFO - PID 1665 | |10;stanford-crypto-aes;80;81;78;83;79;80;81;80;80;90 11:09:51 INFO - PID 1665 | |11;stanford-crypto-ccm;130;126;140;149;129;150;131;144;162;179 11:09:51 INFO - PID 1665 | |12;stanford-crypto-pbkdf2;175;178;178;174;172;176;175;179;176;176 11:09:51 INFO - PID 1665 | |13;stanford-crypto-sha256-iterative;62;61;61;58;84;95;85;67;69;85 11:09:51 INFO - PID 1665 | __end_tp_report 11:09:51 INFO - PID 1665 | __start_cc_report 11:09:51 INFO - PID 1665 | _x_x_mozilla_cycle_collect,977 11:09:51 INFO - PID 1665 | __end_cc_report 11:09:51 INFO - PID 1665 | __startTimestamp1497463791180__endTimestamp 11:09:51 INFO - PID 1665 | 11:09:51 INFO - PID 1665 | ------- Summary: start ------- 11:09:51 INFO - PID 1665 | Number of tests: 14 11:09:51 INFO - PID 1665 | 11:09:51 INFO - PID 1665 | [#0] ai-astar Cycles:10 Average:122.90 Median:126.00 stddev:21.60 (17.1%) stddev-sans-first:21.11 11:09:51 INFO - PID 1665 | Values: 99.0 114.0 104.0 113.0 115.0 145.0 171.0 135.0 116.0 117.0 11:09:51 INFO - PID 1665 | 11:09:51 INFO - PID 1665 | [#1] audio-beat-detection Cycles:10 Average:132.00 Median:134.50 stddev:21.97 (16.3%) stddev-sans-first:23.30 11:09:51 INFO - PID 1665 | Values: 131.0 108.0 130.0 182.0 138.0 127.0 113.0 139.0 145.0 107.0 11:09:51 INFO - PID 1665 | 11:09:51 INFO - PID 1665 | [#2] audio-dft Cycles:10 Average:196.70 Median:196.50 stddev:2.67 (1.4%) stddev-sans-first:2.47 11:09:51 INFO - PID 1665 | Values: 193.0 198.0 196.0 200.0 195.0 197.0 195.0 196.0 195.0 202.0 11:09:51 INFO - PID 1665 | 11:09:51 INFO - PID 1665 | [#3] audio-fft Cycles:10 Average:93.10 Median:82.00 stddev:40.62 (49.5%) stddev-sans-first:41.89 11:09:51 INFO - PID 1665 | Values: 66.0 87.0 79.0 170.0 51.0 85.0 87.0 98.0 158.0 50.0 11:09:51 INFO - PID 1665 | 11:09:51 INFO - PID 1665 | [#4] audio-oscillator Cycles:10 Average:92.30 Median:68.00 stddev:23.81 (35.0%) stddev-sans-first:23.43 11:09:51 INFO - PID 1665 | Values: 67.0 70.0 109.0 83.0 102.0 108.0 135.0 69.0 69.0 111.0 11:09:51 INFO - PID 1665 | 11:09:51 INFO - PID 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:89.70 Median:89.50 stddev:2.00 (2.2%) stddev-sans-first:0.78 11:09:51 INFO - PID 1665 | Values: 95.0 90.0 89.0 88.0 89.0 88.0 89.0 90.0 89.0 90.0 11:09:51 INFO - PID 1665 | 11:09:51 INFO - PID 1665 | [#6] imaging-darkroom Cycles:10 Average:95.80 Median:96.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 11:09:51 INFO - PID 1665 | Values: 96.0 97.0 96.0 96.0 96.0 96.0 95.0 96.0 95.0 95.0 11:09:51 INFO - PID 1665 | 11:09:51 INFO - PID 1665 | [#7] imaging-desaturate Cycles:10 Average:102.70 Median:91.00 stddev:22.99 (25.3%) stddev-sans-first:24.05 11:09:51 INFO - PID 1665 | Values: 92.0 93.0 91.0 91.0 99.0 146.0 146.0 90.0 89.0 90.0 11:09:51 INFO - PID 1665 | 11:09:51 INFO - PID 1665 | [#8] json-parse-financial Cycles:10 Average:51.10 Median:51.00 stddev:3.48 (6.8%) stddev-sans-first:3.61 11:09:51 INFO - PID 1665 | Values: 49.0 47.0 49.0 51.0 50.0 50.0 51.0 52.0 52.0 60.0 11:09:51 INFO - PID 1665 | 11:09:51 INFO - PID 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:66.00 Median:63.00 stddev:15.11 (24.0%) stddev-sans-first:15.39 11:09:51 INFO - PID 1665 | Values: 54.0 54.0 57.0 69.0 81.0 89.0 90.0 57.0 55.0 54.0 11:09:51 INFO - PID 1665 | 11:09:51 INFO - PID 1665 | [#10] stanford-crypto-aes Cycles:10 Average:81.20 Median:80.50 stddev:3.36 (4.2%) stddev-sans-first:3.54 11:09:51 INFO - PID 1665 | Values: 80.0 81.0 78.0 83.0 79.0 80.0 81.0 80.0 80.0 90.0 11:09:51 INFO - PID 1665 | 11:09:51 INFO - PID 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:144.00 Median:146.50 stddev:16.80 (11.5%) stddev-sans-first:17.04 11:09:51 INFO - PID 1665 | Values: 130.0 126.0 140.0 149.0 129.0 150.0 131.0 144.0 162.0 179.0 11:09:51 INFO - PID 1665 | 11:09:51 INFO - PID 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.90 Median:176.00 stddev:2.08 (1.2%) stddev-sans-first:2.18 11:09:51 INFO - PID 1665 | Values: 175.0 178.0 178.0 174.0 172.0 176.0 175.0 179.0 176.0 176.0 11:09:51 INFO - PID 1665 | 11:09:51 INFO - PID 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.70 Median:76.50 stddev:13.24 (17.3%) stddev-sans-first:13.47 11:09:51 INFO - PID 1665 | Values: 62.0 61.0 61.0 58.0 84.0 95.0 85.0 67.0 69.0 85.0 11:09:51 INFO - PID 1665 | -------- Summary: end -------- 11:09:51 INFO - PID 1665 | 11:09:52 INFO - TEST-INFO | 1665: exit 0 11:09:52 INFO - TEST-OK | kraken | took 88390ms 11:09:52 INFO - SUITE-END | took 1230s 11:09:52 INFO - Completed test suite (00:20:31) 11:09:52 INFO - javascript benchmark 11:09:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8713.960109592077, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3757.0795155186984, 3864.3442725075374, 3870.3033453978287, 3890.61093890611, 3903.6486716195554, 6407.839804004902, 6416.839579010521, 6447.194100737402, 6467.838304042401, 6508.153939987807, 6607.900881486769, 6608.2070151581875, 6609.735610575583, 6611.801645950617, 6617.966910165443, 5814.1278808178795, 5817.360090390053, 5825.067989121741, 5934.109883517485, 6028.517718582525, 5860.091685788689, 5894.528437724972, 5924.466797988181, 6011.879762404749, 6015.669138197398, 5189.766460509286, 5648.74580643872, 5695.088785794253, 5702.857428564319, 5859.765609375641, 5426.213199086111, 5819.0689489681445, 5856.32652244993, 5896.823095307148, 5906.26171728534, 4872.707637541724, 5630.268065151545, 5788.739506722208, 5840.182374467578, 5840.386759390245, 5724.8282551523525, 6185.845353866144, 6417.90895547758, 6589.682063587302, 6598.967005164944, 5684.232628595169, 7135.143782746052, 7153.928460715379, 7179.49743517954, 7192.784216473514, 7063.187733410621, 7168.46236532257, 7259.564426134449, 7277.3814225790875, 7302.160251571084, 6683.7994860153785, 6686.6656667166935, 6719.899201511884, 6781.287964763727, 6788.083608712761, 7441.665125069385, 7462.776116716507, 7475.0656167979005, 7478.813029674193, 7503.474756767083, 6817.420519255819, 6931.445484361239, 6941.167059952838, 6953.930460695328, 7074.398676112588, 7040.084788977502, 7078.92921070793, 7096.53872498286, 7098.503104782616, 7152.28477152291, 7132.465065120136, 7142.21435642079, 7153.0701008869555, 7173.92826071743, 7185.820354491179, 7149.89275160873, 7171.92828071723, 7183.42532597391, 7184.497085203986, 7187.532810367309, 5586.776528938878, 5596.216529685766, 5605.8598535036135, 5667.376588575254, 5770.336411312753, 4438.467383913951, 4973.801047958041, 5000.574951129195, 5031.245313203049, 5070.543651071422, 6158.538109641795, 6771.424428923498, 6800.115984921928, 6812.557183783038, 6858.0, 7261.8547629047125, 7336.486445948839, 7337.523061001125, 7344.742933997284, 7353.117625884928, 6742.190937087581, 6756.0, 6763.0, 6764.222114456802, 6805.897911531331, 4686.203345431285, 6131.0096813291075, 6143.296949342364, 6203.038529028008, 6205.441510264098, 7430.777076687817, 7468.962655186907, 7475.887861682079, 7508.474406791582, 7534.208908064794, 7306.086739157606, 7325.743598974011, 7335.083114610674, 7341.889871651822, 7350.338469537767, 7456.32893039629, 7475.140358858584, 7500.512466689648, 7512.924870751222, 7535.246475352421, 7154.463415243981, 7178.641067946686, 7190.856182876209, 7195.172555156015, 7209.3872020876715, 6951.652417379009, 7106.7157313706875, 7124.821879453055, 7169.677364518505, 7305.926940730525, 7006.299370062953, 7038.472114591324, 7047.048648432396, 7074.3633073023675, 7089.291070892869, 5859.912101318569, 7051.753188638373, 7058.188308344401, 7069.893951590628, 7070.611116388648, 6557.803265902031, 6731.663416829237, 6788.456923446014, 6896.310368963063, 6929.792106236821, 6118.954222337368, 6844.178698556205, 6855.5886646801355, 6868.965655171693, 6892.586444813327, 5549.278593782782, 5688.7440065198, 6919.135108111486, 6921.688524016331, 6928.411085057826], "value": 6497.290778515667, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [767.9884801727972, 865.1002956924799, 872.4242000279817, 887.3344991256556, 905.0813424374261, 40559.188816223694, 41846.16307673852, 41907.37138942914, 41939.58060419402, 42000.57999420005, 41661.375079373836, 41785.58214417855, 41901.0, 41916.53292134776, 42073.78963105188, 7983.52098874068, 8060.395470339748, 8073.354131669453, 8082.71710490133, 8097.2307630774985, 7085.858282834341, 7979.56112413817, 8027.237412445808, 8034.758957231264, 8080.191980801932, 8858.955705221464, 8873.068327825582, 8927.107289271053, 8927.55362231889, 8930.285577153812, 7953.562554059525, 8269.751907442786, 8865.379423440361, 8904.154105360012, 8929.95535022324, 8697.130286971253, 8746.300295976307, 8753.868691969625, 8757.124287571256, 8784.64861405543, 38735.0, 38944.0, 38972.610273897466, 39045.828625141294, 39138.412923806165, 3630.6006339302658, 3660.8535658573624, 3661.7802931824176, 3670.687991520724, 3673.1551743099235, 31735.0, 32046.519302210487, 32048.679513204803, 32089.0, 32147.83926080378, 3197.2806118623357, 3335.266241426882, 3337.2824842658947, 3351.6648335166533, 3354.111160542458, 239009.0, 241069.79465102562, 241107.0, 321453.0, 322258.0, 42450.0, 51711.48288517142, 52251.47748522542, 52477.7376113117, 52543.73728131335, 54527.0, 54537.72731136398, 54627.0, 54628.45371546313, 54675.72662136744, 16569.83430165707, 16703.248353823863, 16745.246463909152, 16833.494995150166, 16885.746713799304, 13659.521916732867, 13719.725605487834, 13737.862621373859, 13740.65648358799, 13751.587452376445, 14529.418823246951, 14578.416863325348, 14585.093037319903, 14608.634784130481, 14702.89728270384, 6196.47329976957, 6266.561340706106, 6297.118403423525, 6303.9684801575695, 6307.4954003679595, 1039.407537703517, 1040.167865707431, 1044.6187141693226, 1098.549594666183, 1100.8423489959769, 22198.77801221967, 23850.40373990664, 24855.6329401881, 25366.239012829643, 25424.618630720553, 950.5817440326234, 1108.9465008242203, 1156.8264760285856, 1182.1547593470539, 1206.7043574324346, 12444.81332780009, 12731.93634031824, 13044.934775326063, 13151.145175563557, 13158.73682526344, 269483.0, 309632.0, 322472.3876380603, 323463.0, 323912.760872393, 58601.0, 58865.70567147137, 58913.70543147257, 59022.819543608886, 59177.40822591805, 1330.1154732103043, 1496.453794365049, 1527.5951872753728, 1593.6653302806308, 1597.8082630084232, 23565.528689425773, 23944.64083038721, 23983.52032959296, 24173.395665108514, 24857.751422485544, 53540.0, 54678.0, 54738.0, 54861.0, 54893.72553137368, 270904.0, 271411.2858871386, 271950.0, 271963.0, 272121.0, 31566.053018409486, 31857.681423186397, 31870.68129318677, 34431.827840860635, 35169.64830351664, 45513.63459096233, 45721.77139114283, 45826.31260531028, 46078.53921460742, 47525.52474475211, 27771.91710621671, 29464.968726094485, 29468.11595652134, 29542.261443464085, 32380.35239295154, 23940.640890387007, 28867.5669864948, 31704.36591268208, 31713.04860854178, 32197.0, 36945.0, 37285.25429491341, 37728.81135594304, 38077.238455231294, 38082.428763568016, 30399.69600304057, 30977.209738201178, 31104.688953111083, 31577.842110789297, 32054.679453205168, 22067.889660552235, 23848.569085854906, 25101.623475648244, 25375.0, 25577.488450231263], "value": 18166.000044685374, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [513.5198589318987, 537.8440252326825, 542.487349454765, 547.3923944421691, 549.5219159331381, 24041.639375409362, 24860.502789944192, 24944.0, 24959.500809983834, 25019.249422517307, 25329.87335063327, 28010.439791204164, 28128.01551945682, 28128.578071328895, 28154.155375338774, 6855.1431071115985, 6957.339052789976, 6960.825979350506, 7005.019297298357, 7016.649167541628, 6929.341712537301, 6984.301569843001, 6985.965070174642, 6999.790006299819, 7004.369606735393, 6254.7498100075945, 6471.932131198347, 6867.759628413007, 6894.655267236643, 6909.965450172742, 6879.965600171992, 6896.20693620235, 6910.5853648780985, 6932.930670693304, 6940.063091482686, 6359.109724638554, 6364.427201551836, 6365.267994180682, 6373.521985851034, 6374.394432528902, 32692.182695432566, 33324.3335133296, 33467.0, 33615.0, 33835.32329353424, 2335.112657190257, 2393.1504315967754, 2393.8563686178886, 2395.1616934072936, 2397.3167647220457, 16811.915940420342, 17663.293468261254, 19482.733622314503, 20093.698594521094, 20623.071961761758, 2280.8631482111127, 2326.3718795925174, 2347.859128452298, 2361.031976889484, 2371.4822513591316, 193156.06843931662, 216274.0, 216887.0, 219684.0, 222434.0, 13154.671133221555, 16110.838891611169, 16217.35130594763, 16440.095794731165, 16456.6708665826, 18234.817651823578, 22185.889070554866, 22284.108635654717, 22317.884105794645, 22326.33021009372, 11050.281731687415, 11078.446077696082, 11190.888091119148, 11211.831822522669, 11265.662030139109, 10481.790364192673, 10529.262951593468, 10534.789304213873, 10535.157187424988, 10600.787984240425, 7954.164812694699, 8009.118996910335, 9723.708288751348, 9831.950840245896, 9915.107640312406, 2591.766740993282, 2798.328401183742, 2803.8177518461234, 2814.521531339677, 2890.8265504069404, 875.6847534887436, 1004.4124187350482, 1010.1262408017012, 1063.8138325792954, 1068.3002633275216, 22070.33788986333, 22130.44673883166, 22324.218652347416, 22375.0, 23057.884710576676, 1033.2199189611945, 1037.5434808684306, 1075.5052675769077, 1088.983665245022, 1097.2209731090854, 11308.886911130949, 11437.428128593703, 11465.0, 11506.252093613888, 11940.701482462835, 560134.0, 561813.0, 570726.0, 579699.0, 580036.0998195066, 168089.0, 168963.0, 169724.0, 170289.0, 172963.0, 1150.3098141115431, 1223.7552489502136, 1239.9814002789965, 1328.8870446012197, 1334.1128149780288, 22740.545189096458, 25172.11897583649, 25216.873915630305, 25270.620940686273, 25324.873375633004, 72332.27667723256, 75016.49967000738, 75116.62441687756, 75620.0, 76129.61935190289, 94213.58679619948, 96260.51869740606, 96827.0, 98078.01921980688, 98813.0, 10360.430176340373, 12141.332226727614, 12316.384180790745, 12404.503819847467, 12486.0, 15388.769168462706, 19201.0, 19287.614247715246, 19436.0, 20119.798802011792, 16143.515694529184, 16168.353265869233, 16310.429134980219, 16337.346506139622, 16450.259738312045, 13858.099223550436, 13911.0, 14016.579502614937, 14468.27658617086, 14715.632109197355, 16442.424515141913, 16784.32862685479, 16808.243629036966, 16933.49199524016, 16934.322627094778, 12467.875321247035, 12566.937165314115, 12699.492020319454, 12720.68198295013, 12721.491140354652, 16094.356225750838, 16108.27512761952, 16122.274497647399, 16142.354305827635, 16233.756493652842], "value": 12610.078986335004, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1293.5084667826227, 1487.0631502153642, 1495.0805254768313, 1495.9551213463594, 1505.8343582205955, 14163.0, 14722.337494812733, 14757.704845903076, 14776.33506492209, 14815.0, 14186.148831070142, 14413.63965900853, 14438.927805360985, 14591.0, 14600.707985840278, 2910.4522074156416, 2914.8396838173894, 2953.3650265192978, 2956.216602600313, 2957.3493831357173, 2909.11272062021, 2924.0350684274244, 2927.4291513154944, 2928.4582352264792, 2928.970710292902, 2864.8997285095024, 2895.8407287599175, 2912.956305655406, 2915.5043642580813, 2919.5912572239904, 2374.3589230907733, 2870.598116263725, 2871.3252385689348, 2894.102828123278, 2895.551189565621, 2838.630977972871, 2853.4721076600895, 2859.2137162280333, 2860.75683566895, 2872.482953068447, 13944.302784860818, 14174.92912535441, 14185.574432767033, 14244.359003844851, 14252.429902803875, 7899.05211374633, 8197.385196110316, 8202.0, 8203.30271926887, 8213.466124701876, 4932.8520144395725, 5162.896742065175, 5170.405403378622, 5175.430702622709, 5238.947610523884, 1464.5459907428624, 1483.7625979843174, 1516.8483151684854, 1516.9848301517063, 1523.5200911712775, 19691.21235150592, 19821.801781981994, 21906.561868762696, 21994.34016979493, 22141.66787498189, 6373.458256048288, 6593.3736295051895, 7377.852442951111, 7449.292317229854, 7505.512141710771, 15222.314995825214, 15981.520554383387, 16867.240974155946, 17327.22027508765, 17483.912580437016, 8117.553534555656, 8221.958890205511, 8267.545285009262, 8286.337093032544, 8306.044804847399, 7071.469639777038, 7356.080489938758, 7481.989931359197, 7503.0, 7513.173550909396, 5728.914066289009, 5971.253593300838, 5981.132735753292, 5988.401159884064, 5997.850053748692, 1551.9301631426613, 1797.0232743981517, 1842.3643842874187, 1862.208561361416, 1873.8875667459995, 841.8610929196736, 859.4542465534431, 868.7306934850217, 871.2724874729672, 887.9289656827567, 6059.424354686298, 7827.373810095179, 7963.0046244219475, 8180.386471014698, 8303.543305118277, 717.7129148340601, 728.3735987051132, 731.2687312687312, 750.5871770526189, 756.8902509136144, 3971.7616942983514, 4839.951600484021, 4956.10790057793, 5331.626786124934, 5335.493128152819, 18157.182926768328, 18353.54116147107, 18528.81471185271, 19022.334218302294, 19087.618247635248, 18370.0, 18943.673942824145, 18977.620447590973, 19028.238870445304, 19055.809441905403, 8747.737567872846, 9940.353876997971, 10029.849552256575, 10038.447885366375, 10173.745656358651, 7442.106947166375, 7846.489978151402, 7915.722949696733, 8044.879326809985, 8507.617157227816, 16574.17129143562, 16598.66802663964, 17255.737000214554, 17515.21181546859, 17612.73580896313, 16439.178041098137, 17420.216090275713, 17732.202050907483, 17845.018523981307, 17901.462956111336, 11375.31748095117, 11535.423228838692, 11540.94229528847, 11548.133889958119, 11556.422178890854, 13041.217526948414, 13068.215907045607, 13094.279814610289, 13100.0, 13108.541201057917, 8367.790805229917, 10229.232807539613, 10677.786444270916, 10825.621103261663, 10926.071283940953, 7454.962725186556, 10289.691309260734, 10337.397703355991, 10621.89378106209, 10684.09185219265, 9426.293028022788, 9606.66376676841, 9723.611055557698, 9745.756356091155, 9755.756106097404, 6944.097267355211, 7046.788596342118, 7064.222935477196, 7292.088488938883, 7390.371818395497, 6449.935760599448, 6716.731330746716, 7195.532290401107, 7414.258574142543, 7482.738104166328], "value": 6430.731157486107, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [452.0314032388944, 455.61045306263145, 474.1986043586341, 483.4923330502971, 493.9454265143919, 6287.7799277025315, 6648.204984654147, 6673.833154171142, 6696.196456425224, 6718.899216511756, 5301.973490132544, 6251.749930002795, 6479.384458476437, 6501.609903405787, 6529.90205146922, 3732.4774531565467, 3745.756525825813, 4378.562143785628, 4781.250780801045, 4987.376577927759, 3788.7347885648014, 4300.913981720364, 4942.555170034696, 4955.876103097434, 4984.77568509418, 4115.506139263292, 4558.111168322179, 4819.951800482003, 4830.637702172351, 4920.3111564381015, 4767.856964291077, 4830.661853670244, 4897.191963326047, 4911.673848061004, 4924.630652701062, 3652.433872749728, 3659.6889264412557, 3751.81817727415, 4283.293256612656, 4335.631471324909, 5769.615292329852, 6102.589477156543, 6133.601315914451, 6262.561620686553, 6285.622862628257, 4817.132916075105, 5624.091256576681, 5856.677882716449, 5857.589968702193, 5884.58807883444, 6921.307869213088, 6935.549189302679, 6940.410065144469, 6949.44404447643, 6951.582905025714, 4644.815572029145, 4888.608911287089, 4948.653594248404, 4955.380577427822, 4963.45402005779, 6820.590764554093, 7435.40516758658, 7462.365698915599, 7489.737859174903, 7510.9624451877935, 4184.8744537663915, 5007.599392048627, 5058.797648094109, 5076.949230507647, 5091.210862316347, 4314.546972567898, 5130.486951304914, 5184.585233181337, 5190.195519694454, 5197.58419326453, 4879.4388645305535, 5872.471477567039, 5883.646981181143, 5890.028145355968, 5891.263592050994, 6489.967550162219, 6773.766396002498, 7067.257937916547, 7074.646267686698, 7189.388901943394, 1743.494386627889, 1744.842964133234, 1756.0868348458985, 1756.3238153311067, 1764.0738612228427, 1085.8751243607087, 1167.3637867362138, 1176.311857563318, 1203.3201241298639, 1211.3640338822188, 6176.6911654418, 6481.708323125471, 6548.934510654928, 6662.0, 6730.259671436138, 6775.119234499483, 7270.309320614533, 7301.963490182515, 7318.3047610476915, 7324.780256592204, 6586.0, 6711.899321510181, 6712.395884370332, 6712.731490740413, 6722.092517510171, 3303.9504407433906, 3705.796181210005, 3767.7550959187565, 3787.223619158066, 3791.298609757204, 6618.86762264752, 7497.437692173109, 7507.986421832983, 7516.5114267572435, 7546.396288296923, 5863.73613187399, 6519.706613202417, 7157.4989750718, 7439.851202976019, 7501.437392195608, 6127.724252408563, 7011.754588589477, 7346.228645992094, 7439.888401673979, 7498.725216713063, 6302.936970630235, 7242.565446073252, 7277.3086556777025, 7286.198518162892, 7302.488825782144, 6687.461883766859, 7148.142222933281, 7157.821054473471, 7169.211386747558, 7230.963845180951, 5601.159826026129, 5990.9700451497465, 6706.798796036127, 7222.783316500513, 7511.661975211019, 3787.9242415150993, 4262.296721041118, 4989.376327959005, 4991.875203119951, 5016.322796422436, 6415.0, 7259.310365515267, 7297.708091676486, 7310.159331676925, 7376.926230737624, 6258.718357674006, 6641.169853768279, 7031.824204394931, 7044.718211271492, 7075.363217310467, 5489.64317319373, 6937.965310173417, 7015.824604384727, 7026.208316879163, 7047.647617619201, 5099.847004589868, 5798.883407556296, 6409.7436102555375, 6833.555818871758, 6915.308469153045, 5597.720113994203, 5780.913286300624, 7024.4029257513685, 7053.858922821412, 7080.468964827555, 6405.19935008124, 6417.480966368826, 6841.486888483483, 6844.178698556205, 6876.312368763083], "value": 5110.412831267796, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [859.6131740716675, 885.8272636835816, 887.5562218890555, 897.9910200897989, 907.709532949456, 17877.91061044696, 18777.812221877793, 18820.43538693838, 18821.529461763428, 18833.71749423758, 16681.416150434736, 16766.916165419185, 17307.048112353816, 17587.0, 17716.911415442937, 1019.4138370437009, 1040.4225654761603, 1048.8269435543127, 1049.5382031905974, 1049.774298525817, 1059.1579694143174, 1059.2108878885203, 1061.4427425601543, 1061.9734506637358, 1062.097217365241, 946.30919428817, 1092.9289596176263, 1111.1055600241802, 1111.416506334173, 1114.5151858941385, 1062.2617280989716, 1084.8264277715607, 1096.5668560918432, 1108.3959242213014, 1110.5779803674593, 790.6837265093951, 790.746960972489, 802.743122200896, 805.9274665280153, 809.6235250608437, 15778.76331855023, 15818.841811581851, 15864.603384915354, 15964.680706385923, 15980.76028859568, 14390.424383024667, 14593.905457090654, 14946.850531494654, 15108.848911510853, 15302.617434564114, 12028.63914082579, 12492.188007779465, 13846.10000349974, 13993.72012559743, 14189.645258868508, 3572.5534308211472, 3667.3032123896373, 3708.9814550927344, 3713.7771733695795, 3715.2198038411966, 104023.47988260086, 105694.47152764341, 148442.0, 152707.0, 154226.0, 11914.225575337576, 11953.342566158944, 12166.635000949986, 12235.082368822195, 12815.16701414405, 22052.338429847132, 22441.775582244296, 25474.85363158625, 26037.73962260391, 26040.73959260421, 8505.574721264036, 8583.18459746323, 8586.227239548345, 8623.611937462829, 8643.697470588499, 4136.772477513706, 4203.810828512725, 4235.449391579075, 4237.724547904377, 4243.0241044559925, 4837.685550439211, 4864.464908860023, 4893.584045356185, 4923.089228492669, 4935.703857768545, 895.6014573514724, 903.5256490342623, 1018.8013337399136, 1040.7294103532984, 1047.0942634621, 605.5034871405405, 667.8831204539187, 673.1451057157383, 685.4413652872893, 686.8145600687886, 10342.793144137075, 11961.88038119625, 12589.181703189446, 12807.679808004874, 12979.766922142382, 665.6771465839064, 667.1527160506226, 685.348918527401, 696.9965150174216, 705.0693085127684, 5665.3201615806365, 5800.970995144998, 6080.027195648763, 6108.51131909455, 6645.734170633121, 137969.62030379768, 145789.5421045797, 147544.0, 147745.0, 156347.21826390794, 78426.6078669603, 90288.0, 126110.0, 128294.0, 132036.0, 47831.5216847834, 100901.0, 101539.49230253801, 103145.48427257816, 109179.45410272898, 9368.203702685349, 11044.558217671203, 12699.746005080031, 13239.27184004889, 13462.259575723428, 46951.0, 47775.76112119417, 49196.52410427693, 50017.49982500129, 50146.99706005932, 74674.13314667177, 75135.62432187804, 85599.4320170396, 87496.68754968554, 88187.55906220684, 17843.107844607977, 17907.28370865203, 18225.270989160814, 18228.36200732968, 18355.908220459347, 15829.920850396134, 18486.352977645718, 18629.161687723812, 18914.52713682169, 19283.132259048096, 15211.0, 17465.476035718948, 17900.373486927896, 17959.551011224823, 18153.00158491243, 16879.028631389818, 17583.912080439517, 17841.0, 18197.090145492937, 18252.26990920348, 17316.0, 17674.734878977084, 17749.911250443663, 17782.733259000866, 17938.0, 11573.942130289632, 11705.414729263674, 12020.519179233082, 12471.314077725812, 12589.622311330308, 13573.321333933067, 15042.172680502677, 15129.924350378178, 15160.772588411402, 15187.240637968278], "value": 8950.359016689497, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1516.1000000000004, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 114.0, 104.0, 113.0, 115.0, 145.0, 171.0, 135.0, 116.0, 117.0], "value": 122.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 108.0, 130.0, 182.0, 138.0, 127.0, 113.0, 139.0, 145.0, 107.0], "value": 132.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 198.0, 196.0, 200.0, 195.0, 197.0, 195.0, 196.0, 195.0, 202.0], "value": 196.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 87.0, 79.0, 170.0, 51.0, 85.0, 87.0, 98.0, 158.0, 50.0], "value": 93.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 109.0, 83.0, 102.0, 108.0, 135.0, 69.0, 69.0, 111.0], "value": 92.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 90.0, 89.0, 88.0, 89.0, 88.0, 89.0, 90.0, 89.0, 90.0], "value": 89.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 96.0, 96.0, 96.0, 95.0, 96.0, 95.0, 95.0], "value": 95.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 91.0, 91.0, 99.0, 146.0, 146.0, 90.0, 89.0, 90.0], "value": 102.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 47.0, 49.0, 51.0, 50.0, 50.0, 51.0, 52.0, 52.0, 60.0], "value": 51.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 57.0, 69.0, 81.0, 89.0, 90.0, 57.0, 55.0, 54.0], "value": 66.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 81.0, 78.0, 83.0, 79.0, 80.0, 81.0, 80.0, 80.0, 90.0], "value": 81.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 126.0, 140.0, 149.0, 129.0, 150.0, 131.0, 144.0, 162.0, 179.0], "value": 144.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 178.0, 178.0, 174.0, 172.0, 176.0, 175.0, 179.0, 176.0, 176.0], "value": 175.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 61.0, 58.0, 84.0, 95.0, 85.0, 67.0, 69.0, 85.0], "value": 72.7, "unit": "score"}]}]} 11:09:52 INFO - Return code: 0 11:09:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:09:52 INFO - # TBPL SUCCESS # 11:09:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:09:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:09:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:09:52 INFO - 2017-06-14 11:09:52.578 screenresolution[1672:11280] starting screenresolution argv=screenresolution get 11:09:52 INFO - 2017-06-14 11:09:52.580 screenresolution[1672:11280] Display 0: 1600x1200x32@60 11:09:52 INFO - 2017-06-14 11:09:52.586 screenresolution[1673:11283] starting screenresolution argv=screenresolution list 11:09:52 INFO - Available Modes on Display 0 11:09:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:09:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:09:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:09:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:09:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:09:52 INFO - Intel Iris: 11:09:52 INFO - Chipset Model: Intel Iris 11:09:52 INFO - Type: GPU 11:09:52 INFO - Bus: Built-In 11:09:52 INFO - VRAM (Dynamic, Max): 1536 MB 11:09:52 INFO - Vendor: Intel (0x8086) 11:09:52 INFO - Device ID: 0x0a2e 11:09:52 INFO - Revision ID: 0x0009 11:09:52 INFO - Displays: 11:09:52 INFO - Display: 11:09:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:09:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:09:52 INFO - Display Serial Number: 1600x1200 60 11:09:52 INFO - Main Display: Yes 11:09:52 INFO - Mirror: Off 11:09:52 INFO - Online: Yes 11:09:52 INFO - Rotation: Supported 11:09:52 INFO - Return code: 0 11:09:52 INFO - Running post-action listener: _resource_record_post_action 11:09:52 INFO - [mozharness: 2017-06-14 18:09:52.824191Z] Finished run-tests step (success) 11:09:52 INFO - Running post-run listener: _resource_record_post_run 11:09:52 INFO - Total resource usage - Wall time: 1247s; CPU: 53.0%; Read bytes: 49867264; Write bytes: 467688448; Read time: 545; Write time: 1519 11:09:52 INFO - TinderboxPrint: CPU usage
52.9% 11:09:52 INFO - TinderboxPrint: I/O read bytes / time
49,867,264 / 545 11:09:52 INFO - TinderboxPrint: I/O write bytes / time
467,688,448 / 1,519 11:09:52 INFO - TinderboxPrint: CPU idle
2,352.3 (47.1%) 11:09:52 INFO - TinderboxPrint: CPU system
1,005.1 (20.1%) 11:09:52 INFO - TinderboxPrint: CPU user
1,632.2 (32.7%) 11:09:52 INFO - TinderboxPrint: Swap in / out
195,465,216 / 0 11:09:52 INFO - install - Wall time: 16s; CPU: 54.0%; Read bytes: 154663424; Write bytes: 154464256; Read time: 12116; Write time: 286 11:09:52 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:09:52 INFO - run-tests - Wall time: 1232s; CPU: 53.0%; Read bytes: 44927488; Write bytes: 311806976; Read time: 511; Write time: 1228 11:09:53 INFO - Running post-run listener: _upload_blobber_files 11:09:53 INFO - Blob upload gear active. 11:09:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:09:53 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 11:09:53 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'] 11:09:53 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 11:09:53 INFO - (blobuploader) - INFO - Open directory for files ... 11:09:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:09:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:09:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:09:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:09:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:09:55 INFO - (blobuploader) - INFO - Done attempting. 11:09:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:09:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:09:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:09:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:09:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:09:55 INFO - (blobuploader) - INFO - Done attempting. 11:09:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:09:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:09:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:09:56 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:09:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:09:56 INFO - (blobuploader) - INFO - Done attempting. 11:09:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:09:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:09:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:09:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:09:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:09:58 INFO - (blobuploader) - INFO - Done attempting. 11:09:58 INFO - (blobuploader) - INFO - Iteration through files over. 11:09:58 INFO - Return code: 0 11:09:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:09:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:09:58 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8addec1da1f7a53ecca8c226b8d0a74b0dae4a68cc998eae55504219e880cb97717421a75518d3fb3ec4b57c0463576c3fceedc916c3c873ee3db906efcb7d83", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/287e473feceb23a3e36a675a5f5f09bc5fe5dfa56390dcffbadc02fb7ad1b23c54319c0091ce6816b7fa37b798a89fdf2f6bf2a7e69f3cd4b4b7b25b976545d8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/14f07f98eca2fb1b3b44e3a00bd247becdaa5cf453440e79662e919258b09834153544a81fbed1e193b033e3d632b161b90132fc812b2dd6a8f4e0bd4cc06d06", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/93794244db3159c631dbf9eb2a727a8c1784901af65d8e1bca9487821af0863c2f13fd61192ffae1d1d44facf00644f71baf7a36a925bf6e7c98364ab9e548c0"} 11:09:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:09:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:09:58 INFO - Contents: 11:09:58 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8addec1da1f7a53ecca8c226b8d0a74b0dae4a68cc998eae55504219e880cb97717421a75518d3fb3ec4b57c0463576c3fceedc916c3c873ee3db906efcb7d83", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/287e473feceb23a3e36a675a5f5f09bc5fe5dfa56390dcffbadc02fb7ad1b23c54319c0091ce6816b7fa37b798a89fdf2f6bf2a7e69f3cd4b4b7b25b976545d8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/14f07f98eca2fb1b3b44e3a00bd247becdaa5cf453440e79662e919258b09834153544a81fbed1e193b033e3d632b161b90132fc812b2dd6a8f4e0bd4cc06d06", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/93794244db3159c631dbf9eb2a727a8c1784901af65d8e1bca9487821af0863c2f13fd61192ffae1d1d44facf00644f71baf7a36a925bf6e7c98364ab9e548c0"} 11:09:58 INFO - Running post-run listener: copy_logs_to_upload_dir 11:09:58 INFO - Copying logs to upload dir... 11:09:58 INFO - mkdir: /builds/slave/test/build/upload/logs 11:09:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1330.627883 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 10 secs) (at 2017-06-14 11:09:58.949642) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-14 11:09:58.950825) ========= 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.L2g25tvQRn/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.itMcUCEQZp/Listeners TMPDIR=/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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/8addec1da1f7a53ecca8c226b8d0a74b0dae4a68cc998eae55504219e880cb97717421a75518d3fb3ec4b57c0463576c3fceedc916c3c873ee3db906efcb7d83", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/287e473feceb23a3e36a675a5f5f09bc5fe5dfa56390dcffbadc02fb7ad1b23c54319c0091ce6816b7fa37b798a89fdf2f6bf2a7e69f3cd4b4b7b25b976545d8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/14f07f98eca2fb1b3b44e3a00bd247becdaa5cf453440e79662e919258b09834153544a81fbed1e193b033e3d632b161b90132fc812b2dd6a8f4e0bd4cc06d06", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/93794244db3159c631dbf9eb2a727a8c1784901af65d8e1bca9487821af0863c2f13fd61192ffae1d1d44facf00644f71baf7a36a925bf6e7c98364ab9e548c0"} build_url:https://queue.taskcluster.net/v1/task/dy4mtt07QGyoYZGm18SU3Q/artifacts/public/build/firefox-55.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011007 build_url: 'https://queue.taskcluster.net/v1/task/dy4mtt07QGyoYZGm18SU3Q/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/8addec1da1f7a53ecca8c226b8d0a74b0dae4a68cc998eae55504219e880cb97717421a75518d3fb3ec4b57c0463576c3fceedc916c3c873ee3db906efcb7d83", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/287e473feceb23a3e36a675a5f5f09bc5fe5dfa56390dcffbadc02fb7ad1b23c54319c0091ce6816b7fa37b798a89fdf2f6bf2a7e69f3cd4b4b7b25b976545d8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/14f07f98eca2fb1b3b44e3a00bd247becdaa5cf453440e79662e919258b09834153544a81fbed1e193b033e3d632b161b90132fc812b2dd6a8f4e0bd4cc06d06", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/93794244db3159c631dbf9eb2a727a8c1784901af65d8e1bca9487821af0863c2f13fd61192ffae1d1d44facf00644f71baf7a36a925bf6e7c98364ab9e548c0"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-14 11:09:58.988323) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2017-06-14 11:09:58.988694) ========= 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.L2g25tvQRn/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.itMcUCEQZp/Listeners TMPDIR=/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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.005056 ========= master_lag: 1.15 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2017-06-14 11:10:00.141838) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-14 11:10:00.142282) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-14 11:10:00.147355) ========= ========= Total master_lag: 1.32 =========