builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0014 starttime: 1494280777.12 results: success (0) buildid: 20170508133842 builduid: b030c201c38a49a484e5a658a26e4330 revision: 30a656462d6b7b5b8c897481019b52e2f484b898 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-08 14:59:37.120525) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-08 14:59:37.121347) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-08 14:59:37.121962) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-08 14:59:37.148748) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-08 14:59:37.149472) ========= 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.OdLezxEtZq/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.m94wEDEIpS/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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-05-08 14:59:37-- 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% 8.01M=0.001s 2017-05-08 14:59:37 (8.01 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.226718 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-08 14:59:37.402329) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-08 14:59:37.402709) ========= 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.OdLezxEtZq/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.m94wEDEIpS/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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.020758 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-08 14:59:37.452398) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-08 14:59:37.452834) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 30a656462d6b7b5b8c897481019b52e2f484b898 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 30a656462d6b7b5b8c897481019b52e2f484b898 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.OdLezxEtZq/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.m94wEDEIpS/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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-05-08 14:59:37,530 truncating revision to first 12 chars 2017-05-08 14:59:37,530 Setting DEBUG logging. 2017-05-08 14:59:37,530 attempt 1/10 2017-05-08 14:59:37,530 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/30a656462d6b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-08 14:59:39,833 unpacking tar archive at: mozilla-beta-30a656462d6b/testing/mozharness/ program finished with exit code 0 elapsedTime=2.562980 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-08 14:59:40.041926) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-08 14:59:40.042431) ========= script_repo_revision: 30a656462d6b7b5b8c897481019b52e2f484b898 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-08 14:59:40.042841) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-08 14:59:40.043510) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-08 14:59:40.063894) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2017-05-08 14:59:40.064471) ========= /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.OdLezxEtZq/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.m94wEDEIpS/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 14:59:40 INFO - MultiFileLogger online at 20170508 14:59:40 in /builds/slave/test 14:59:40 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 14:59:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:59:40 INFO - {'append_to_log': False, 14:59:40 INFO - 'base_work_dir': '/builds/slave/test', 14:59:40 INFO - 'blob_upload_branch': 'Mozilla-Beta', 14:59:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:59:40 INFO - 'branch': 'Mozilla-Beta', 14:59:40 INFO - 'buildbot_json_path': 'buildprops.json', 14:59:40 INFO - 'config_files': ('talos/mac_config.py',), 14:59:40 INFO - 'default_actions': ('clobber', 14:59:40 INFO - 'read-buildbot-config', 14:59:40 INFO - 'download-and-extract', 14:59:40 INFO - 'populate-webroot', 14:59:40 INFO - 'create-virtualenv', 14:59:40 INFO - 'install', 14:59:40 INFO - 'run-tests'), 14:59:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:59:40 INFO - 'download_minidump_stackwalk': True, 14:59:40 INFO - 'download_symbols': 'ondemand', 14:59:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:59:40 INFO - 'tooltool.py': '/tools/tooltool.py', 14:59:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:59:40 INFO - '/tools/misc-python/virtualenv.py')}, 14:59:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:59:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:59:40 INFO - 'gecko_profile': False, 14:59:40 INFO - 'gecko_profile_interval': 0, 14:59:40 INFO - 'installer_path': 'installer.exe', 14:59:40 INFO - 'log_level': 'info', 14:59:40 INFO - 'log_name': 'talos', 14:59:40 INFO - 'log_to_console': True, 14:59:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:59:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:59:40 INFO - 'opt_config_files': (), 14:59:40 INFO - 'pip_index': False, 14:59:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:59:40 INFO - 'cmd': ('bash', 14:59:40 INFO - '-c', 14:59:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:59:40 INFO - 'enabled': True, 14:59:40 INFO - 'halt_on_failure': False, 14:59:40 INFO - 'name': 'check_screen_resolution'},), 14:59:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:59:40 INFO - 'cmd': ('bash', 14:59:40 INFO - '-c', 14:59:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:59:40 INFO - 'enabled': True, 14:59:40 INFO - 'halt_on_failure': False, 14:59:40 INFO - 'name': 'check_screen_resolution'},), 14:59:40 INFO - 'run_cmd_checks_enabled': True, 14:59:40 INFO - 'suite': 'dromaeojs-e10s', 14:59:40 INFO - 'system_bits': '32', 14:59:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:59:40 INFO - 'title': 't-yosemite-r7-0014', 14:59:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:59:40 INFO - 'use_talos_json': True, 14:59:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:59:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:59:40 INFO - 'work_dir': 'build'} 14:59:40 INFO - [mozharness: 2017-05-08 21:59:40.265740Z] Running clobber step. 14:59:40 INFO - Running pre-action listener: _resource_record_pre_action 14:59:40 INFO - Running main action method: clobber 14:59:40 INFO - rmtree: /builds/slave/test/build 14:59:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:59:40 INFO - Running post-action listener: _resource_record_post_action 14:59:40 INFO - [mozharness: 2017-05-08 21:59:40.422810Z] Finished clobber step (success) 14:59:40 INFO - [mozharness: 2017-05-08 21:59:40.422934Z] Running read-buildbot-config step. 14:59:40 INFO - Running pre-action listener: _resource_record_pre_action 14:59:40 INFO - Running main action method: read_buildbot_config 14:59:40 INFO - Using buildbot properties: 14:59:40 INFO - { 14:59:40 INFO - "project": "", 14:59:40 INFO - "product": "firefox", 14:59:40 INFO - "script_repo_revision": "production", 14:59:40 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 14:59:40 INFO - "repository": "", 14:59:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 14:59:40 INFO - "buildid": "20170508133842", 14:59:40 INFO - "pgo_build": "False", 14:59:40 INFO - "basedir": "/builds/slave/test", 14:59:40 INFO - "buildnumber": 26, 14:59:40 INFO - "slavename": "t-yosemite-r7-0014", 14:59:40 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 14:59:40 INFO - "platform": "yosemite_r7", 14:59:40 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 14:59:40 INFO - "branch": "mozilla-beta", 14:59:40 INFO - "repo_path": "releases/mozilla-beta", 14:59:40 INFO - "revision": "30a656462d6b7b5b8c897481019b52e2f484b898", 14:59:40 INFO - "stage_platform": "macosx64", 14:59:40 INFO - "builduid": "b030c201c38a49a484e5a658a26e4330", 14:59:40 INFO - "slavebuilddir": "test" 14:59:40 INFO - } 14:59:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/JVq27oAoTKeBCSFyq5sM-Q/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 14:59:40 INFO - Running post-action listener: _resource_record_post_action 14:59:40 INFO - [mozharness: 2017-05-08 21:59:40.426352Z] Finished read-buildbot-config step (success) 14:59:40 INFO - [mozharness: 2017-05-08 21:59:40.426455Z] Running download-and-extract step. 14:59:40 INFO - Running pre-action listener: _resource_record_pre_action 14:59:40 INFO - Running main action method: download_and_extract 14:59:40 INFO - mkdir: /builds/slave/test/build/tests 14:59:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 14:59:40 INFO - trying https://queue.taskcluster.net/v1/task/JVq27oAoTKeBCSFyq5sM-Q/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 14:59:40 INFO - Downloading https://queue.taskcluster.net/v1/task/JVq27oAoTKeBCSFyq5sM-Q/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 14:59:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JVq27oAoTKeBCSFyq5sM-Q/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 14:59:41 INFO - Downloaded 1287 bytes. 14:59:41 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 14:59:41 INFO - Using the following test package requirements: 14:59:41 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:59:41 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 14:59:41 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 14:59:41 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:59:41 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 14:59:41 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:59:41 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 14:59:41 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 14:59:41 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:59:41 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 14:59:41 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 14:59:41 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:59:41 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 14:59:41 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:59:41 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 14:59:41 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:59:41 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 14:59:41 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:59:41 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 14:59:41 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 14:59:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JVq27oAoTKeBCSFyq5sM-Q/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 14:59:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JVq27oAoTKeBCSFyq5sM-Q/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 14:59:41 INFO - Fetch https://queue.taskcluster.net/v1/task/JVq27oAoTKeBCSFyq5sM-Q/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 14:59:42 INFO - Expected file size: 51223821 14:59:42 INFO - Obtained file size: 51223821 14:59:58 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 14:59:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JVq27oAoTKeBCSFyq5sM-Q/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 14:59:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JVq27oAoTKeBCSFyq5sM-Q/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 14:59:58 INFO - Fetch https://queue.taskcluster.net/v1/task/JVq27oAoTKeBCSFyq5sM-Q/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 15:00:00 INFO - Expected file size: 51223821 15:00:00 INFO - Obtained file size: 51223821 15:00:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JVq27oAoTKeBCSFyq5sM-Q/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 15:00:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JVq27oAoTKeBCSFyq5sM-Q/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 15:00:15 INFO - Fetch https://queue.taskcluster.net/v1/task/JVq27oAoTKeBCSFyq5sM-Q/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 15:00:16 INFO - Expected file size: 14404449 15:00:16 INFO - Obtained file size: 14404449 15:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:00:17 INFO - trying https://queue.taskcluster.net/v1/task/JVq27oAoTKeBCSFyq5sM-Q/artifacts/public/build/firefox-54.0.en-US.mac.dmg 15:00:17 INFO - Downloading https://queue.taskcluster.net/v1/task/JVq27oAoTKeBCSFyq5sM-Q/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 15:00:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JVq27oAoTKeBCSFyq5sM-Q/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 15:00:18 INFO - Downloaded 59571245 bytes. 15:00:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JVq27oAoTKeBCSFyq5sM-Q/artifacts/public/build/firefox-54.0.en-US.mac.dmg 15:00:18 INFO - mkdir: /builds/slave/test/properties 15:00:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:00:18 INFO - Writing to file /builds/slave/test/properties/build_url 15:00:18 INFO - Contents: 15:00:18 INFO - build_url:https://queue.taskcluster.net/v1/task/JVq27oAoTKeBCSFyq5sM-Q/artifacts/public/build/firefox-54.0.en-US.mac.dmg 15:00:19 INFO - Running post-action listener: _resource_record_post_action 15:00:19 INFO - Running post-action listener: set_extra_try_arguments 15:00:19 INFO - [mozharness: 2017-05-08 22:00:19.619065Z] Finished download-and-extract step (success) 15:00:19 INFO - [mozharness: 2017-05-08 22:00:19.619218Z] Running populate-webroot step. 15:00:19 INFO - Running pre-action listener: _resource_record_pre_action 15:00:19 INFO - Running main action method: populate_webroot 15:00:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:00:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:00:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:00:19 INFO - '--tppagecycles', 15:00:19 INFO - '7'], 15:00:19 INFO - 'tests': ['tsvgm']}}, 15:00:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:00:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:00:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:00:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:00:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:00:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:00:19 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:00:19 INFO - 'talos_options': ['--disable-e10s'], 15:00:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:00:19 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:00:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:00:19 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:00:19 INFO - 'talos_options': ['--disable-e10s'], 15:00:19 INFO - 'tests': ['damp', 'tps']}, 15:00:19 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:00:19 INFO - 'tests': ['damp', 'tps']}, 15:00:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:00:19 INFO - 'tests': ['dromaeo_dom']}, 15:00:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:00:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:00:19 INFO - 'tests': ['basic_compositor_video']}, 15:00:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:00:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:00:19 INFO - 'tests': ['a11yr', 15:00:19 INFO - 'ts_paint', 15:00:19 INFO - 'tpaint', 15:00:19 INFO - 'sessionrestore', 15:00:19 INFO - 'sessionrestore_no_auto_restore', 15:00:19 INFO - 'tabpaint']}, 15:00:19 INFO - 'other-e10s': {'tests': ['a11yr', 15:00:19 INFO - 'ts_paint', 15:00:19 INFO - 'tpaint', 15:00:19 INFO - 'sessionrestore', 15:00:19 INFO - 'sessionrestore_no_auto_restore', 15:00:19 INFO - 'tabpaint']}, 15:00:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:00:19 INFO - 'ts_paint', 15:00:19 INFO - 'tpaint', 15:00:19 INFO - 'sessionrestore', 15:00:19 INFO - 'sessionrestore_no_auto_restore', 15:00:19 INFO - 'tabpaint']}, 15:00:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:00:19 INFO - 'ts_paint', 15:00:19 INFO - 'tpaint', 15:00:19 INFO - 'sessionrestore', 15:00:19 INFO - 'sessionrestore_no_auto_restore', 15:00:19 INFO - 'tabpaint']}, 15:00:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:00:19 INFO - 'tests': ['a11yr', 15:00:19 INFO - 'ts_paint', 15:00:19 INFO - 'tpaint', 15:00:19 INFO - 'sessionrestore', 15:00:19 INFO - 'sessionrestore_no_auto_restore', 15:00:19 INFO - 'tabpaint']}, 15:00:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:00:19 INFO - 'tests': ['a11yr', 15:00:19 INFO - 'ts_paint', 15:00:19 INFO - 'tpaint', 15:00:19 INFO - 'sessionrestore', 15:00:19 INFO - 'sessionrestore_no_auto_restore', 15:00:19 INFO - 'tabpaint']}, 15:00:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:00:19 INFO - 'tests': ['tsvgx', 15:00:19 INFO - 'tsvgr_opacity', 15:00:19 INFO - 'tart', 15:00:19 INFO - 'tscrollx', 15:00:19 INFO - 'cart', 15:00:19 INFO - 'tsvg_static']}, 15:00:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:00:19 INFO - 'tsvgr_opacity', 15:00:19 INFO - 'tart', 15:00:19 INFO - 'tscrollx', 15:00:19 INFO - 'cart', 15:00:19 INFO - 'tsvg_static']}, 15:00:19 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:00:19 INFO - 'talos_options': ['--disable-e10s'], 15:00:19 INFO - 'tests': ['tp5o']}, 15:00:19 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:00:19 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:00:19 INFO - 'talos_options': ['--disable-e10s', 15:00:19 INFO - '--xperf_path', 15:00:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:00:19 INFO - 'tests': ['tp5n']}, 15:00:19 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:00:19 INFO - 'talos_options': ['--xperf_path', 15:00:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:00:19 INFO - 'tests': ['tp5n']}}, 15:00:19 INFO - 'talos.zip': {'path': '', 15:00:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:00:19 INFO - Running post-action listener: _resource_record_post_action 15:00:19 INFO - [mozharness: 2017-05-08 22:00:19.637563Z] Finished populate-webroot step (success) 15:00:19 INFO - [mozharness: 2017-05-08 22:00:19.637703Z] Running create-virtualenv step. 15:00:19 INFO - Running pre-action listener: _resource_record_pre_action 15:00:19 INFO - Running main action method: create_virtualenv 15:00:19 INFO - Creating virtualenv /builds/slave/test/build/venv 15:00:19 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 15:00:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:00:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:00:19 INFO - Using real prefix '/tools/python27' 15:00:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:00:20 INFO - Installing distribute.............................................................................................................................................................................................done. 15:00:23 INFO - Installing pip.................done. 15:00:23 INFO - Return code: 0 15:00:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:00:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:00:23 INFO - Reading from file tmpfile_stdout 15:00:23 INFO - Output received: 15:00:23 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) 15:00:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:00:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:00:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:00:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:00:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:00:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:00:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:00:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1045bdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045c0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90bada8c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10439bd30>, '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 0x1042c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104390be8>, '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.OdLezxEtZq/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.m94wEDEIpS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 15:00:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 15:00:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:00:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OdLezxEtZq/Render', 15:00:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:00:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:00:23 INFO - 'HOME': '/Users/cltbld', 15:00:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:00:23 INFO - 'LOGNAME': 'cltbld', 15:00:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:00:23 INFO - 'MOZ_NO_REMOTE': '1', 15:00:23 INFO - 'NO_EM_RESTART': '1', 15:00:23 INFO - 'PAGER': '/bin/cat', 15:00:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:00:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:00:23 INFO - 'PWD': '/builds/slave/test', 15:00:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:00:23 INFO - 'SHELL': '/bin/bash', 15:00:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m94wEDEIpS/Listeners', 15:00:23 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 15:00:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:00:23 INFO - 'USER': 'cltbld', 15:00:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:00:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:00:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:00:23 INFO - 'XPC_FLAGS': '0x0', 15:00:23 INFO - 'XPC_SERVICE_NAME': '0', 15:00:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:00:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:00:23 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 15:00:23 INFO - Cleaning up... 15:00:23 INFO - Return code: 0 15:00:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:00:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:00:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:00:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:00:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:00:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:00:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:00:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1045bdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045c0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90bada8c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10439bd30>, '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 0x1042c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104390be8>, '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.OdLezxEtZq/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.m94wEDEIpS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 15:00:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 15:00:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:00:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OdLezxEtZq/Render', 15:00:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:00:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:00:23 INFO - 'HOME': '/Users/cltbld', 15:00:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:00:23 INFO - 'LOGNAME': 'cltbld', 15:00:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:00:23 INFO - 'MOZ_NO_REMOTE': '1', 15:00:23 INFO - 'NO_EM_RESTART': '1', 15:00:23 INFO - 'PAGER': '/bin/cat', 15:00:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:00:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:00:23 INFO - 'PWD': '/builds/slave/test', 15:00:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:00:23 INFO - 'SHELL': '/bin/bash', 15:00:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m94wEDEIpS/Listeners', 15:00:23 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 15:00:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:00:23 INFO - 'USER': 'cltbld', 15:00:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:00:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:00:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:00:23 INFO - 'XPC_FLAGS': '0x0', 15:00:23 INFO - 'XPC_SERVICE_NAME': '0', 15:00:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:00:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:00:24 INFO - Downloading/unpacking psutil>=3.1.1 15:00:24 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 15:00:24 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 15:00:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:00:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:00:24 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 15:00:24 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 15:00:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:00:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:00:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:00:26 INFO - Installing collected packages: psutil 15:00:26 INFO - Running setup.py install for psutil 15:00:26 INFO - building 'psutil._psutil_osx' extension 15:00:26 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 15:00:27 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 15:00:27 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 15:00:27 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 15:00:27 INFO - building 'psutil._psutil_posix' extension 15:00:27 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 15:00:27 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:00:27 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:00:27 INFO - ^ 15:00:27 INFO - 1 warning generated. 15:00:27 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 15:00:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:00:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:00:27 INFO - Successfully installed psutil 15:00:27 INFO - Cleaning up... 15:00:27 INFO - Return code: 0 15:00:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:00:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:00:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:00:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:00:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:00:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:00:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:00:27 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1045bdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045c0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90bada8c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10439bd30>, '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 0x1042c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104390be8>, '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.OdLezxEtZq/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.m94wEDEIpS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 15:00:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 15:00:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:00:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OdLezxEtZq/Render', 15:00:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:00:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:00:27 INFO - 'HOME': '/Users/cltbld', 15:00:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:00:27 INFO - 'LOGNAME': 'cltbld', 15:00:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:00:27 INFO - 'MOZ_NO_REMOTE': '1', 15:00:27 INFO - 'NO_EM_RESTART': '1', 15:00:27 INFO - 'PAGER': '/bin/cat', 15:00:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:00:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:00:27 INFO - 'PWD': '/builds/slave/test', 15:00:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:00:27 INFO - 'SHELL': '/bin/bash', 15:00:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m94wEDEIpS/Listeners', 15:00:27 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 15:00:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:00:27 INFO - 'USER': 'cltbld', 15:00:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:00:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:00:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:00:27 INFO - 'XPC_FLAGS': '0x0', 15:00:27 INFO - 'XPC_SERVICE_NAME': '0', 15:00:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:00:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:00:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:00:27 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 15:00:27 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 15:00:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:00:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:00:27 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 15:00:27 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 15:00:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:00:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:00:29 INFO - Installing collected packages: mozsystemmonitor 15:00:29 INFO - Running setup.py install for mozsystemmonitor 15:00:29 INFO - Successfully installed mozsystemmonitor 15:00:29 INFO - Cleaning up... 15:00:29 INFO - Return code: 0 15:00:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:00:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:00:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:00:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:00:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:00:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:00:29 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1045bdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045c0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90bada8c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10439bd30>, '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 0x1042c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104390be8>, '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.OdLezxEtZq/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.m94wEDEIpS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 15:00:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 15:00:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:00:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OdLezxEtZq/Render', 15:00:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:00:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:00:29 INFO - 'HOME': '/Users/cltbld', 15:00:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:00:29 INFO - 'LOGNAME': 'cltbld', 15:00:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:00:29 INFO - 'MOZ_NO_REMOTE': '1', 15:00:29 INFO - 'NO_EM_RESTART': '1', 15:00:29 INFO - 'PAGER': '/bin/cat', 15:00:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:00:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:00:29 INFO - 'PWD': '/builds/slave/test', 15:00:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:00:29 INFO - 'SHELL': '/bin/bash', 15:00:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m94wEDEIpS/Listeners', 15:00:29 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 15:00:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:00:29 INFO - 'USER': 'cltbld', 15:00:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:00:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:00:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:00:29 INFO - 'XPC_FLAGS': '0x0', 15:00:29 INFO - 'XPC_SERVICE_NAME': '0', 15:00:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:00:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:00:29 INFO - Downloading/unpacking jsonschema==2.5.1 15:00:29 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 15:00:29 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 15:00:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:00:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:00:29 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 15:00:29 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 15:00:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:00:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:00:31 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 15:00:31 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 15:00:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:00:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:00:31 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 15:00:31 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 15:00:31 INFO - Downloading functools32-3.2.3-2.tar.gz 15:00:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:00:31 INFO - warning: no files found matching '*.txt' 15:00:31 INFO - no previously-included directories found matching 'build' 15:00:31 INFO - no previously-included directories found matching 'dist' 15:00:31 INFO - no previously-included directories found matching '.git*' 15:00:31 INFO - Installing collected packages: jsonschema, functools32 15:00:31 INFO - Running setup.py install for functools32 15:00:31 INFO - warning: no files found matching '*.txt' 15:00:31 INFO - no previously-included directories found matching 'build' 15:00:31 INFO - no previously-included directories found matching 'dist' 15:00:31 INFO - no previously-included directories found matching '.git*' 15:00:31 INFO - Successfully installed jsonschema functools32 15:00:31 INFO - Cleaning up... 15:00:31 INFO - Return code: 0 15:00:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:00:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:00:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:00:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:00:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:00:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:00:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:00:31 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1045bdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045c0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90bada8c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10439bd30>, '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 0x1042c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104390be8>, '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.OdLezxEtZq/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.m94wEDEIpS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 15:00:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 15:00:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:00:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OdLezxEtZq/Render', 15:00:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:00:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:00:31 INFO - 'HOME': '/Users/cltbld', 15:00:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:00:31 INFO - 'LOGNAME': 'cltbld', 15:00:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:00:31 INFO - 'MOZ_NO_REMOTE': '1', 15:00:31 INFO - 'NO_EM_RESTART': '1', 15:00:31 INFO - 'PAGER': '/bin/cat', 15:00:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:00:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:00:31 INFO - 'PWD': '/builds/slave/test', 15:00:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:00:31 INFO - 'SHELL': '/bin/bash', 15:00:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m94wEDEIpS/Listeners', 15:00:31 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 15:00:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:00:31 INFO - 'USER': 'cltbld', 15:00:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:00:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:00:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:00:31 INFO - 'XPC_FLAGS': '0x0', 15:00:31 INFO - 'XPC_SERVICE_NAME': '0', 15:00:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:00:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:00:32 INFO - Cleaning up... 15:00:32 INFO - Return code: 0 15:00:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:00:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:00:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:00:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:00:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:00:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:00:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:00:32 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1045bdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045c0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90bada8c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10439bd30>, '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 0x1042c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104390be8>, '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.OdLezxEtZq/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.m94wEDEIpS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 15:00:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 15:00:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:00:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OdLezxEtZq/Render', 15:00:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:00:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:00:32 INFO - 'HOME': '/Users/cltbld', 15:00:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:00:32 INFO - 'LOGNAME': 'cltbld', 15:00:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:00:32 INFO - 'MOZ_NO_REMOTE': '1', 15:00:32 INFO - 'NO_EM_RESTART': '1', 15:00:32 INFO - 'PAGER': '/bin/cat', 15:00:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:00:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:00:32 INFO - 'PWD': '/builds/slave/test', 15:00:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:00:32 INFO - 'SHELL': '/bin/bash', 15:00:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m94wEDEIpS/Listeners', 15:00:32 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 15:00:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:00:32 INFO - 'USER': 'cltbld', 15:00:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:00:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:00:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:00:32 INFO - 'XPC_FLAGS': '0x0', 15:00:32 INFO - 'XPC_SERVICE_NAME': '0', 15:00:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:00:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:00:32 INFO - Downloading/unpacking blobuploader==1.2.4 15:00:32 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 15:00:32 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 15:00:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:00:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:00:32 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 15:00:32 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 15:00:33 INFO - Downloading blobuploader-1.2.4.tar.gz 15:00:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:00:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:00:33 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 15:00:33 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 15:00:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:00:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:00:33 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 15:00:33 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 15:00:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:00:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:00:34 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 15:00:34 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 15:00:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:00:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:00:34 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 15:00:34 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 15:00:34 INFO - Downloading docopt-0.6.1.tar.gz 15:00:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:00:34 INFO - Installing collected packages: blobuploader, requests, docopt 15:00:34 INFO - Running setup.py install for blobuploader 15:00:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:00:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:00:34 INFO - Running setup.py install for requests 15:00:35 INFO - Running setup.py install for docopt 15:00:35 INFO - Successfully installed blobuploader requests docopt 15:00:35 INFO - Cleaning up... 15:00:35 INFO - Return code: 0 15:00:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:00:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:00:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:00:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:00:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:00:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:00:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:00:36 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1045bdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045c0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90bada8c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10439bd30>, '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 0x1042c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104390be8>, '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.OdLezxEtZq/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.m94wEDEIpS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 15:00:36 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 15:00:36 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:00:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OdLezxEtZq/Render', 15:00:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:00:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:00:36 INFO - 'HOME': '/Users/cltbld', 15:00:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:00:36 INFO - 'LOGNAME': 'cltbld', 15:00:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:00:36 INFO - 'MOZ_NO_REMOTE': '1', 15:00:36 INFO - 'NO_EM_RESTART': '1', 15:00:36 INFO - 'PAGER': '/bin/cat', 15:00:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:00:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:00:36 INFO - 'PWD': '/builds/slave/test', 15:00:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:00:36 INFO - 'SHELL': '/bin/bash', 15:00:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m94wEDEIpS/Listeners', 15:00:36 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 15:00:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:00:36 INFO - 'USER': 'cltbld', 15:00:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:00:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:00:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:00:36 INFO - 'XPC_FLAGS': '0x0', 15:00:36 INFO - 'XPC_SERVICE_NAME': '0', 15:00:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:00:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:00:36 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-ngIdXT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:00:36 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-GlKhXQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:00:36 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-j5lQPg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:00:36 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-79J85_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:00:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:00:37 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-bHVDMA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:00:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:00:37 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-4_AQVb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:00:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:00:37 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-o1NmPf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:00:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:00:37 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-9SE7VM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:00:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:00:37 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-b8bwrl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:00:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:00:37 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-FQOYVv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:00:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:00:37 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-u65wG1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:00:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:00:37 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-GJOTGL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:00:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:00:37 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-VL5J6t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:00:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:00:37 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-jtxrLD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:00:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:00:38 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-tI7Nz_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:00:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:00:38 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-PQsMC0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:00:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:00:38 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-UURvPu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:00:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:00:38 INFO - Running setup.py install for manifestparser 15:00:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:00:38 INFO - Running setup.py install for mozcrash 15:00:38 INFO - Running setup.py install for mozdebug 15:00:38 INFO - Running setup.py install for mozdevice 15:00:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:00:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:00:39 INFO - Running setup.py install for mozfile 15:00:39 INFO - Running setup.py install for mozhttpd 15:00:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:00:39 INFO - Running setup.py install for mozinfo 15:00:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:00:39 INFO - Running setup.py install for mozInstall 15:00:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:00:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:00:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:00:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:00:39 INFO - Running setup.py install for mozleak 15:00:39 INFO - Running setup.py install for mozlog 15:00:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:00:39 INFO - Running setup.py install for moznetwork 15:00:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:00:40 INFO - Running setup.py install for mozprocess 15:00:40 INFO - Running setup.py install for mozprofile 15:00:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:00:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:00:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:00:40 INFO - Running setup.py install for mozrunner 15:00:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:00:40 INFO - Running setup.py install for mozscreenshot 15:00:40 INFO - Running setup.py install for moztest 15:00:40 INFO - Running setup.py install for mozversion 15:00:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:00:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:00:40 INFO - Cleaning up... 15:00:40 INFO - Return code: 0 15:00:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:00:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:00:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:00:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:00:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:00:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:00:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:00:41 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1045bdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045c0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90bada8c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10439bd30>, '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 0x1042c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104390be8>, '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.OdLezxEtZq/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.m94wEDEIpS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 15:00:41 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 15:00:41 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:00:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OdLezxEtZq/Render', 15:00:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:00:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:00:41 INFO - 'HOME': '/Users/cltbld', 15:00:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:00:41 INFO - 'LOGNAME': 'cltbld', 15:00:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:00:41 INFO - 'MOZ_NO_REMOTE': '1', 15:00:41 INFO - 'NO_EM_RESTART': '1', 15:00:41 INFO - 'PAGER': '/bin/cat', 15:00:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:00:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:00:41 INFO - 'PWD': '/builds/slave/test', 15:00:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:00:41 INFO - 'SHELL': '/bin/bash', 15:00:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m94wEDEIpS/Listeners', 15:00:41 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 15:00:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:00:41 INFO - 'USER': 'cltbld', 15:00:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:00:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:00:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:00:41 INFO - 'XPC_FLAGS': '0x0', 15:00:41 INFO - 'XPC_SERVICE_NAME': '0', 15:00:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:00:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:00:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:00:41 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-ZGwaeU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:00:41 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)) 15:00:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:00:41 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-pHcGnt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:00:41 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)) 15:00:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:00:41 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-NBm_L6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:00:41 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)) 15:00:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:00:41 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-u8EcMm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:00:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 15:00:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:00:42 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-BdOVij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:00:42 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)) 15:00:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:00:42 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-CE4p1e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:00:42 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)) 15:00:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:00:42 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-zstLmm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:00:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 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)) 15:00:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:00:42 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-O4rOcE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:00:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:00:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:00:42 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-WAed0M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:00:42 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)) 15:00:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:00:42 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-7ohvsD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:00:42 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)) 15:00:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:00:42 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-Cr_aTf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:00:42 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)) 15:00:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:00:42 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-EeVHtB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:00:42 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)) 15:00:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:00:42 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-fIwQSj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:00:43 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)) 15:00:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:00:43 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-ITcdxr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:00:43 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)) 15:00:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:00:43 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-HUv8VB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:00:43 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)) 15:00:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:00:43 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-Ld_8V6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:00:43 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)) 15:00:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:00:43 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-pBS9DJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:00:43 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)) 15:00:43 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)) 15:00:43 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)) 15:00:43 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)) 15:00:43 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:00:43 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:00:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:00:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:00:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:00:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:00:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:00:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:00:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:00:45 INFO - Downloading blessings-1.6.tar.gz 15:00:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:00:45 INFO - Installing collected packages: blessings 15:00:45 INFO - Running setup.py install for blessings 15:00:45 INFO - Successfully installed blessings 15:00:45 INFO - Cleaning up... 15:00:45 INFO - Return code: 0 15:00:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:00:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:00:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:00:45 INFO - Reading from file tmpfile_stdout 15:00:45 INFO - Current package versions: 15:00:45 INFO - blessings == 1.6 15:00:45 INFO - blobuploader == 1.2.4 15:00:45 INFO - docopt == 0.6.1 15:00:45 INFO - functools32 == 3.2.3-2 15:00:45 INFO - jsonschema == 2.5.1 15:00:45 INFO - manifestparser == 1.1 15:00:45 INFO - mozInstall == 1.12 15:00:45 INFO - mozcrash == 1.0 15:00:45 INFO - mozdebug == 0.1 15:00:45 INFO - mozdevice == 0.49 15:00:45 INFO - mozfile == 1.2 15:00:45 INFO - mozhttpd == 0.7 15:00:45 INFO - mozinfo == 0.9 15:00:45 INFO - mozleak == 0.1 15:00:45 INFO - mozlog == 3.4 15:00:45 INFO - moznetwork == 0.27 15:00:45 INFO - mozprocess == 0.25 15:00:45 INFO - mozprofile == 0.28 15:00:45 INFO - mozrunner == 6.13 15:00:45 INFO - mozscreenshot == 0.1 15:00:45 INFO - mozsystemmonitor == 0.3 15:00:45 INFO - moztest == 0.8 15:00:45 INFO - mozversion == 1.4 15:00:45 INFO - psutil == 3.1.1 15:00:45 INFO - requests == 1.2.3 15:00:45 INFO - wsgiref == 0.1.2 15:00:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:00:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:00:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:00:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:00:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:00:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:00:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:00:45 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1045bdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045c0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90bada8c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10439bd30>, '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 0x1042c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104390be8>, '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.OdLezxEtZq/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.m94wEDEIpS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 15:00:45 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 15:00:45 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:00:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OdLezxEtZq/Render', 15:00:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:00:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:00:45 INFO - 'HOME': '/Users/cltbld', 15:00:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:00:45 INFO - 'LOGNAME': 'cltbld', 15:00:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:00:45 INFO - 'MOZ_NO_REMOTE': '1', 15:00:45 INFO - 'NO_EM_RESTART': '1', 15:00:45 INFO - 'PAGER': '/bin/cat', 15:00:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:00:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:00:45 INFO - 'PWD': '/builds/slave/test', 15:00:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:00:45 INFO - 'SHELL': '/bin/bash', 15:00:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m94wEDEIpS/Listeners', 15:00:45 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 15:00:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:00:45 INFO - 'USER': 'cltbld', 15:00:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:00:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:00:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:00:45 INFO - 'XPC_FLAGS': '0x0', 15:00:45 INFO - 'XPC_SERVICE_NAME': '0', 15:00:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:00:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:00:46 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)) 15:00:46 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)) 15:00:46 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)) 15:00:46 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)) 15:00:46 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)) 15:00:46 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)) 15:00:46 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)) 15:00:46 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)) 15:00:46 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)) 15:00:46 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)) 15:00:46 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)) 15:00:46 INFO - Cleaning up... 15:00:46 INFO - Return code: 0 15:00:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:00:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:00:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:00:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:00:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:00:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:00:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:00:46 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1045bdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045c0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90bada8c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10439bd30>, '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 0x1042c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104390be8>, '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.OdLezxEtZq/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.m94wEDEIpS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 15:00:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:00:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:00:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OdLezxEtZq/Render', 15:00:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:00:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:00:46 INFO - 'HOME': '/Users/cltbld', 15:00:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:00:46 INFO - 'LOGNAME': 'cltbld', 15:00:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:00:46 INFO - 'MOZ_NO_REMOTE': '1', 15:00:46 INFO - 'NO_EM_RESTART': '1', 15:00:46 INFO - 'PAGER': '/bin/cat', 15:00:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:00:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:00:46 INFO - 'PWD': '/builds/slave/test', 15:00:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:00:46 INFO - 'SHELL': '/bin/bash', 15:00:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m94wEDEIpS/Listeners', 15:00:46 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 15:00:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:00:46 INFO - 'USER': 'cltbld', 15:00:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:00:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:00:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:00:46 INFO - 'XPC_FLAGS': '0x0', 15:00:46 INFO - 'XPC_SERVICE_NAME': '0', 15:00:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:00:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:00:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:00:46 INFO - Cleaning up... 15:00:46 INFO - Return code: 0 15:00:46 INFO - Running post-action listener: _resource_record_post_action 15:00:46 INFO - Running post-action listener: _start_resource_monitoring 15:00:46 INFO - Starting resource monitoring. 15:00:46 INFO - [mozharness: 2017-05-08 22:00:46.380342Z] Finished create-virtualenv step (success) 15:00:46 INFO - [mozharness: 2017-05-08 22:00:46.381327Z] Running install step. 15:00:46 INFO - Running pre-action listener: _resource_record_pre_action 15:00:46 INFO - Running main action method: install 15:00:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:00:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:00:46 INFO - Reading from file tmpfile_stdout 15:00:46 INFO - Detecting whether we're running mozinstall >=1.0... 15:00:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:00:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:00:46 INFO - Reading from file tmpfile_stdout 15:00:46 INFO - Output received: 15:00:46 INFO - Usage: mozinstall [options] installer 15:00:46 INFO - Options: 15:00:46 INFO - -h, --help show this help message and exit 15:00:46 INFO - -d DEST, --destination=DEST 15:00:46 INFO - Directory to install application into. [default: 15:00:46 INFO - "/builds/slave/test"] 15:00:46 INFO - --app=APP Application being installed. [default: firefox] 15:00:46 INFO - mkdir: /builds/slave/test/build/application 15:00:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:00:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 15:01:01 INFO - Reading from file tmpfile_stdout 15:01:01 INFO - Output received: 15:01:01 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 15:01:01 INFO - Running post-action listener: _resource_record_post_action 15:01:01 INFO - [mozharness: 2017-05-08 22:01:01.236601Z] Finished install step (success) 15:01:01 INFO - [mozharness: 2017-05-08 22:01:01.236744Z] Running run-tests step. 15:01:01 INFO - Running pre-action listener: _resource_record_pre_action 15:01:01 INFO - Running main action method: run_tests 15:01:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:01:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:01:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:01:01 INFO - 2017-05-08 15:01:01.249 screenresolution[1883:8661] starting screenresolution argv=screenresolution get 15:01:01 INFO - 2017-05-08 15:01:01.252 screenresolution[1883:8661] Display 0: 1600x1200x32@60 15:01:01 INFO - 2017-05-08 15:01:01.257 screenresolution[1884:8663] starting screenresolution argv=screenresolution list 15:01:01 INFO - Available Modes on Display 0 15:01:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:01:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:01:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:01:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:01:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:01:01 INFO - Intel Iris: 15:01:01 INFO - Chipset Model: Intel Iris 15:01:01 INFO - Type: GPU 15:01:01 INFO - Bus: Built-In 15:01:01 INFO - VRAM (Dynamic, Max): 1536 MB 15:01:01 INFO - Vendor: Intel (0x8086) 15:01:01 INFO - Device ID: 0x0a2e 15:01:01 INFO - Revision ID: 0x0009 15:01:01 INFO - Displays: 15:01:01 INFO - Display: 15:01:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:01:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:01:01 INFO - Display Serial Number: 1600x1200 60 15:01:01 INFO - Main Display: Yes 15:01:01 INFO - Mirror: Off 15:01:01 INFO - Online: Yes 15:01:01 INFO - Rotation: Supported 15:01:01 INFO - Return code: 0 15:01:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:01:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:01:01 INFO - Python 2.7.3 15:01:01 INFO - Return code: 0 15:01:01 INFO - grabbing minidump binary from tooltool 15:01:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:01:01 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 0x10439bd30>, '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 0x1042c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104390be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:01:01 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 15:01:01 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 15:01:01 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 15:01:01 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:01:01 INFO - Return code: 0 15:01:01 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:01:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:01:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:01:01 INFO - ENV: RUST_BACKTRACE is now 1 15:01:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:01:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:01:01 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/JVq27oAoTKeBCSFyq5sM-Q/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0014', '--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 15:01:01 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/JVq27oAoTKeBCSFyq5sM-Q/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0014 --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 15:01:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OdLezxEtZq/Render', 15:01:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:01:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:01:01 INFO - 'HOME': '/Users/cltbld', 15:01:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:01:01 INFO - 'LOGNAME': 'cltbld', 15:01:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:01:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:01:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:01:01 INFO - 'MOZ_NO_REMOTE': '1', 15:01:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:01:01 INFO - 'NO_EM_RESTART': '1', 15:01:01 INFO - 'PAGER': '/bin/cat', 15:01:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:01:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:01:01 INFO - 'PWD': '/builds/slave/test', 15:01:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:01:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:01:01 INFO - 'RUST_BACKTRACE': '1', 15:01:01 INFO - 'SHELL': '/bin/bash', 15:01:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m94wEDEIpS/Listeners', 15:01:01 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 15:01:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:01:01 INFO - 'USER': 'cltbld', 15:01:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:01:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:01:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:01:01 INFO - 'XPC_FLAGS': '0x0', 15:01:01 INFO - 'XPC_SERVICE_NAME': '0', 15:01:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:01:01 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/JVq27oAoTKeBCSFyq5sM-Q/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0014', '--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 15:01:01 INFO - mozversion application_buildid: 20170508133842 15:01:01 INFO - mozversion application_changeset: 30a656462d6b7b5b8c897481019b52e2f484b898 15:01:01 INFO - mozversion application_display_name: Firefox 15:01:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:01:01 INFO - mozversion application_name: Firefox 15:01:01 INFO - mozversion application_remotingname: firefox 15:01:01 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:01:01 INFO - mozversion application_vendor: Mozilla 15:01:01 INFO - mozversion application_version: 54.0 15:01:01 INFO - mozversion platform_buildid: 20170508133842 15:01:01 INFO - mozversion platform_changeset: 30a656462d6b7b5b8c897481019b52e2f484b898 15:01:01 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:01:01 INFO - mozversion platform_version: 54.0 15:01:01 INFO - using testdate: 1494280861 15:01:01 INFO - actual date: 1494280861 15:01:01 INFO - starting webserver on 'localhost:49375' 15:01:01 INFO - SUITE-START | Running 2 tests 15:01:01 INFO - TEST-START | dromaeo_css 15:01:01 INFO - operating with platform_type : mac_ 15:01:01 INFO - Initialising browser for dromaeo_css test... 15:01:01 INFO - TEST-INFO | started process 1894 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmpuufV5x/profile http://localhost:49375/getInfo.html) 15:01:04 INFO - PROCESS | 1894 | 2017-05-08 15:01:04.378 plugin-container[1895:8801] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a4f, name = 'com.apple.tsm.portname' 15:01:04 INFO - PROCESS | 1894 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:01:04 INFO - PROCESS | 1894 | 2017-05-08 15:01:04.378 plugin-container[1895:8801] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d1b, name = 'com.apple.CFPasteboardClient' 15:01:04 INFO - PROCESS | 1894 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:01:04 INFO - PROCESS | 1894 | 2017-05-08 15:01:04.378 plugin-container[1895:8801] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:01:04 INFO - PROCESS | 1894 | __metrics Screen width/height:1600/1200 15:01:04 INFO - PROCESS | 1894 | colorDepth:24 15:01:04 INFO - PROCESS | 1894 | Browser inner width/height: 1024/689 15:01:04 INFO - PROCESS | 1894 | __metrics 15:01:04 ERROR - PROCESS | 1894 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 15:01:05 INFO - TEST-INFO | 1894: exit 0 15:01:05 INFO - Browser initialized. 15:01:05 INFO - Running cycle 1/1 for dromaeo_css test... 15:01:05 INFO - TEST-INFO | started process 1897 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmpuufV5x/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:01:07 INFO - PROCESS | 1897 | 2017-05-08 15:01:07.407 plugin-container[1898:8943] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 15:01:07 INFO - PROCESS | 1897 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:01:07 INFO - PROCESS | 1897 | 2017-05-08 15:01:07.407 plugin-container[1898:8943] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 15:01:07 INFO - PROCESS | 1897 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:01:07 INFO - PROCESS | 1897 | 2017-05-08 15:01:07.407 plugin-container[1898:8943] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:01:17 INFO - PROCESS | 1897 | RSS: Main: 174522368 15:01:17 INFO - PROCESS | 1897 | 15:04:19 INFO - PROCESS | 1897 | Cycle 1(1): loaded http://localhost:49375/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49375/tests/dromaeo/cssquery-ext.html) 15:04:20 INFO - PROCESS | 1897 | RSS: Main: 195735552 15:04:20 INFO - PROCESS | 1897 | 15:07:23 INFO - PROCESS | 1897 | Cycle 1(1): loaded http://localhost:49375/tests/dromaeo/cssquery-ext.html (next: http://localhost:49375/tests/dromaeo/cssquery-jquery.html) 15:07:23 INFO - PROCESS | 1897 | RSS: Main: 203976704 15:07:23 INFO - PROCESS | 1897 | 15:10:25 INFO - PROCESS | 1897 | Cycle 1(1): loaded http://localhost:49375/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49375/tests/dromaeo/cssquery-mootools.html) 15:10:26 INFO - PROCESS | 1897 | RSS: Main: 205570048 15:10:26 INFO - PROCESS | 1897 | 15:13:28 INFO - PROCESS | 1897 | Cycle 1(1): loaded http://localhost:49375/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49375/tests/dromaeo/cssquery-prototype.html) 15:13:28 INFO - PROCESS | 1897 | RSS: Main: 208687104 15:13:28 INFO - PROCESS | 1897 | 15:16:31 INFO - PROCESS | 1897 | Cycle 1(1): loaded http://localhost:49375/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49375/tests/dromaeo/cssquery-yui.html) 15:16:32 INFO - PROCESS | 1897 | RSS: Main: 211075072 15:16:32 INFO - PROCESS | 1897 | 15:19:35 INFO - PROCESS | 1897 | Cycle 1(1): loaded http://localhost:49375/tests/dromaeo/cssquery-yui.html (next: http://localhost:49375/tests/dromaeo/cssquery-dojo.html) 15:19:35 INFO - PROCESS | 1897 | RSS: Main: 209334272 15:19:35 INFO - PROCESS | 1897 | 15:19:35 INFO - PROCESS | 1897 | __start_tp_report 15:19:35 INFO - PROCESS | 1897 | _x_x_mozilla_page_load 15:19:35 INFO - PROCESS | 1897 | _x_x_mozilla_page_load_details 15:19:35 INFO - PROCESS | 1897 | |i|pagename|runs| 15:19:35 INFO - PROCESS | 1897 | |0;dojo.html;2984.552317152427;3534.045807631938;3548.8403021864015;3565.251297227582;3590.4255319148942;5962.4037596240405;5985.431384018522;6009.549283803711;6017.789377371793;6028.186194863692;5938.910916336258;5976.462118409342;6043.969780151104;6049.576529642927;6087.969560152204;4457.487388950263;5332.200169974496;5376.99999999998;5382.784688612451;5414.458554144593;5417.133258678612;5429.077056900336;5443.374011988612;5456.427075157111;5467.0705979983295;4096.446979657752;5316.362036555619;5434.83695489134;5438.157085651711;5480.3971563128225;5421.756020979065;5423.186522021689;5423.430539793324;5424.430434804368;5465.316835395575;5285.2600635911;5389.353277606673;5413.918791218135;5416.620836541444;5456.645318054353;5659.886802263932;5964.821055368346;6045.00257457519;6123.4488895999575;6133.78531751391;6243.531735119885;6253.906191407133;6278.246610406734;6292.842678933018;6294.433500984934;5324.92012619811;6120.969395152996;6285.591436556655;6296.6536840473755;6350.079238510436;6081.635101893857;6094.573379863411;6114.480269177081;6119.9902016167725;6124.724387402578;5139.100657384943;5993.700314984233;6288.371162883766;6426.2931077581425;6485.870282594322;5353.518183363438;6295.937040629627;6312.810615681537;6331.778387756499;6354.237491500957;6312.051093293003;6343.492520598341;6416.262129855127;6423.100765892778;6444.645544495098;6150.077488376779;6174.9073763893575;6198.907016394758;6302.527310451735;6317.305096439388;6185.165002724664;6193.225846769154;6268.811935641938;6282.874342513124;6286.18279623646;5053.039922414803;5091.389033315952;5117.641765076483;5172.120739474299;5172.379314482287;4612.838550650711;4615.284630882219;4638.049199914077;4660.370849935283;4718.8584342469785;5917.911231331534;5976.940230597638;6034.818955431344;6175.197224360804;6222.564420490522;5006.4492905780335;5637.26715526987;6250.15622890913;6285.5600107992;6299.401556852092;6157.076438534255;6192.535559833031;6202.72087756052;6203.565750397519;6204.906926396107;5201.557867581298;5658.151277308354;5672.177534257592;5678.545716342683;5679.346875109332;6332.841678958063;6419.4864410846085;6479.643619600967;6504.837379065561;6517.706703198272;5055.494450554915;6318.336574659595;6340.9048864268;6356.682165891594;6377.043443483514;6484.351564843667;6548.607083575001;6581.2102547694585;6592.077109204811;6641.468682505485;6172.537059720449;6229.626222426669;6272.341404152498;6286.18279623646;6353.523485738496;6343.873122537616;6353.856305864804;6355.650439225887;6361.01404282337;6368.617882927039;4983.651144419856;6217.906731399123;6303.842403939938;6316.905246421399;6355.078513615592;6090.878182436415;6133.969330153499;6187.783427580013;6236.968815155895;6247.156633854554;6144.1398204250545;6162.722677479434;6222.62664240147;6226.906596401148;6252.062190671436;6315.652639104894;6319.494440444845;6323.747050117944;6348.873022539616;6357.586756860789;5948.315943666534;5963.940360596512;5997.100434934795;6019.337872834072;6112.898653789679 15:19:35 INFO - PROCESS | 1897 | |1;ext.html;746.6901235987066;775.7982924439644;845.8012367093734;978.149010360986;986.3835103060592;42089;42809.57190428095;42887.57112428875;42957.78521107398;42991.57008429915;39677.60322396775;39719.80840574788;39738.403923941165;39785.602143978555;39814;8329.292010179142;8458.450200736963;11306.208565400426;11551.960323570876;11732.53069877204;11379.715507112305;11476.311421314706;11476.770464590703;11485.770284594302;11501.539938402453;11445;11475.770484590303;11481.483333250024;11482.311061336304;11488.36813975231;11439.19925605208;11457.427128643576;11461.656150315504;11469.770604587946;11476.081913446946;11209.327440353523;11259.831102533468;11271.774564508663;11274.774504509945;11321.547138114465;36240.09399765027;36327.54689812404;36421.725239616484;36461.81769091164;36523.452148217795;3902.843886244547;3926.2736393767245;3944.4280579316123;3997.5802540733384;4010.358342665189;26463.867680661668;26658.800353983923;26883.46233075347;28218.87124515017;28234.717652823416;3184.586003819512;3273.770836041478;3276.262840860811;3293.5883014623173;3421.674940880612;361851;381559;384445;386405;438563;45047.099058018655;45247.86880328018;45332.64002079943;45381;45504.317435238496;46310.53689463062;46434.53565464302;46551.06897862092;46612;46656.533434665216;14542.636434089232;14591.854081459262;14607.561773146823;14623;14628.561143165722;12555.372231388394;12829.422675979416;12836.743265134644;12852.48590056406;12879.098463107492;12330.691732706755;12351.814722778985;12377.566785162477;12447.128700990843;12472.87527124717;4807.711537307773;5199.506046925536;5722.2561067061015;5741.770329186869;5760.8190320984095;1133.7052366384798;1159.8260260960924;1162.5233654201907;1181.8463599732152;1218.2020776391478;23584.64623030656;26597.46805063888;27054.05310814112;27168.320791979964;27189.86405067962;1259.5402678022456;1274.139955530032;1286.9292188929699;1318.4528420705492;1331.760283149023;14712.411503539739;16489.587760305847;16667.583310417092;17026;17235.22441490136;511562;518282;520022;521160;521737;48285.758571207625;60665;61997.690011549654;62135.6893215531;62157.689211553654;1488.9627759305888;1511.2066165263104;1522.3986525322437;1524.7407940650116;1525.9623456049771;30473.23816904595;31940.36119277648;31998;31998.52002219922;32474;49039.26441103315;52188.739056304476;52283.73858130685;52377;52420.47579524309;496685;500793;500896;501029;502850.48574756895;27643.44713105767;28108;33653.326933461685;34095.82952085224;34552;47041.52958470509;47502.52497475119;47540;48154.75922620364;48563;27464.862675686494;31502.684973149975;32056.35887282288;32178.83910580432;32348.345289979767;30743.846280769347;32052.51921221137;32575;33019;33101.33797324088;37592.06019849526;38342.23315533729;38726.80636596799;38753.612463876125;38774.80612597032;32119;32417.038999249125;32708.34583308368;33133;33545.49681754824;23980.76019239855;24598.754012459645;25688.486230275663;25734.227973160836;26349.73650263473 15:19:35 INFO - PROCESS | 1897 | |2;jquery.html;431.1140606054557;439.95600439955996;442.40054725846477;451.0640421126164;452.5791014356649;20155.496112597193;21270;22305.665415018746;22477.887610561964;22585.435364115907;21602.89198554009;22366.776332236674;22858.542829143407;23089.538209235805;23406.180783672575;6927.688254028581;6955.339242771928;6976.5116441849095;7061.788146355618;7087.3267039631155;6697.8995315070315;6863.073485079525;6904.689288982008;6947.895781563256;6953.200381956064;6579.835504112388;6884.483663725216;6930.064441300436;6973.163220413558;6979.267176946424;5634;6455.2253729552285;6932.237453880044;7019.578825270474;7182.712691492334;6765.661716914184;6769.289224631392;6769.7292108315605;6778.22050464198;6790.558613690144;26287.34281642955;27667.308317292227;27890.86054569714;28321.72552235134;28381.858090709415;2917.416516696648;2944.749696275819;2995.5356919677483;3318.2202182487094;3420.504026916108;15751.739860811222;15998.200089995453;16073.598160045976;16882.662346752997;16972.575685607957;1850.5928695686928;2110.0610228448345;2116.608427440928;2122.9150833966623;2126.8192203662707;151785;171333.14333428378;176672;178279;179410;18579.44261672152;18604.69767116289;19402.8059719401;19598.118084686223;21089.78910210909;33392.1651958703;33761.662383376344;33939;34043.82978085094;34166.658333416344;10876.456177191109;10920.453977301104;10986.615468458565;11155.498002589902;11343.149263805082;7593;9129.269658427309;9205.263578913804;9266.907330926604;9296.070392960784;9610.759731006641;9837.311388202757;9902.455364954996;9950.900490994998;10013.949930250303;3966.900827479278;4174.812133454002;4280.743155410685;4289.356596510486;4294.506131794821;843.2747836860295;931.4411353188168;934.7336009237334;935.9204467620192;970.2771435280761;21088.683669744965;21471.926403679754;21513.56972860534;21542.892285538786;21636.35090947274;954.4941181173989;958.8226178157063;982.111189373618;984.3798407003685;1002.904724051214;10960.671179864617;11301.717457063474;11458.484368203452;11570.305781653127;12614.936925315315;436049;594104;765105;768956;809530;119566.4021679886;139015.2196956055;159233;159602;163564;1318.2947123289186;1319.4458327502473;1344.37486568746;1352.0805852020721;1354.1671871798774;19283.614327713087;20459.795402046384;20669.689954650392;20722.689159662918;20726.481837953568;64146;64914;65451.690966179456;65734.35664108435;66826.66586667036;92342;92378.53810731172;92872;93258.53370733102;94453.11093778223;11259.605913792979;11536.480858361561;11545.249558778652;11658.551704142224;11697.649070527897;12244.938775306067;12717.87282127204;12855.807162892752;13301.46794128224;13575.253361065235;5671.149327600728;11410.14423918226;11473.598424055119;11724.179307448398;11785.646430607096;10374.688759337232;10605.045545900906;10653.77481589596;10804.459777011276;10836.945815270874;11760.82358764602;11870.940645296718;11901.226420282654;11970.22193557416;12043.698907527381;9109.817803644022;10673.8932610676;11700.70748231301;11713.590024349107;11802;11181.217314787888;11306.660800176009;11310.095192384424;11398.316101033965;11621.593244236412 15:19:35 INFO - PROCESS | 1897 | |3;mootools.html;1591.4032237910783;1594.418037416343;1640.270079814483;1653.958651033724;1655.155870506042;10844.240903136773;11292.717682057953;11432.542698292069;11437.256578322414;11687.29876207428;11349.659510214708;11406.54373825046;11500.3099814011;11526.769464610703;11551.768964620702;2465.7287698353166;2509.598464245721;2512.6733524641772;2517.0057827158353;2520.760527749861;2440.73151953285;2469.753024697534;2499.6375525548783;2517.0561039610147;2517.1315896015844;2451.6199989001734;2478.5166892455977;2484.291976786616;2511.183865243794;2515.72327044025;1906.6758651029359;2148.1836901977304;2469.8271121021535;2472.6538284640164;2473.938151546217;2381.5951288281035;2409.7831195192516;2416.830821842454;2420.189236605739;2424.87875606219;11203.383813890232;11479.426028698532;11493.712657183554;11497.367644779533;11575;4831.371921650198;6073.119397687311;6135.601185922946;6141.692915354214;6143.262808462969;3411.3177364526946;3476.1657202271526;3562.1272788166807;3711.5546134463766;3735.925281494382;1235.6910772306924;1236.1347057060093;1273.4269578689627;1282.660095074806;1290.2194172525674;13956.790648140184;14200.928995354958;14948.476803311833;15658.68682626341;15772.921135394365;4023.7183397162503;4678.111158879802;4780.880477988008;4815.210886222473;4924.96575719103;12030.578929737418;12046.397680115959;12055.75888482243;12062.69843253908;12109;6075.544334174954;6077.969610151921;6085.96957015221;6087.54343424245;6110.144579758838;5403.13549832025;5448.128299472065;5451.64564303319;5470.64440811354;5491.066518691832;4010.478637777129;4063.6139566741113;4086.1419101988913;4136.193442278787;4162.500499940026;1093.748437859297;1097.1223021582703;1099.587654629514;1106.623747925709;1108.7172770943357;943.6319835264252;966.2801213096288;969.6606187834202;978.7993461340408;1007.3905287301224;6465.903011454832;6544.509161812883;6577.243616984012;6630;6695.866082678319;1001.9899801002042;1002.4336250018716;1032.3805716570148;1038.7558923652934;1054.2567489919588;3827.3275364625847;4207.852725154605;4270.914581708411;4335.7615331157085;4369.169857727022;13558.254296013616;14101.71796564063;14183.503577374742;14201.78697319541;14230;10557.313774604627;13888.236147011808;14006.509772157926;14150.64623384403;14255.78616320756;8610.569471526398;10389.84415233772;10448.164146868232;10455.84316235257;10568.207384446197;6591.967040164768;6599.208095028627;6734.528582999143;6793.592384456949;6870.62211578368;12276.938615306866;12493.875061249271;12631.81052284198;12636.30500322491;12682.746345073232;12908.806367904677;13008.8048679268;13042.673933151747;13203.801942970671;13217.273049982343;7314.268573142643;8679.60941757635;8828.823423531621;8839.16027977341;8844.292456603327;10414.218933580163;10503.422311772925;10631.787364252827;10643.095336896451;10650.881657425858;7492.40060795146;9066.90933090687;9095.545222738705;9130.543472826464;9136.543172841199;8754.781130471534;8825.470471771458;8921.018687944192;8931.151540603632;8939.374243803131;8100.4329696923005;8111;8139.389545783972;8160.469569477965;8268.503889766633;5474.808381706781;5518.944810551843;5938.524918006463;5987.491063259672;6242.188515493139;5998;6026.367231440811;6120.143179954899;6130.5402094842175;6201.689915504297 15:19:35 INFO - PROCESS | 1897 | |4;prototype.html;332.3386460942722;380.30783973168826;396.4390387601546;399.123922989039;411.9032027473544;5417.431169727181;5459.399466058735;5490.945090549093;5556.555475561955;5624.521915637166;5041.798328066873;5455.809046683366;5466.715321899351;5480.6437581557175;5536.778528858851;3958.604139586033;4199.3281075028;4512.842050528232;4514.729116253019;4542.341360502725;3683.876417692603;3738.3084129436006;4142.5028996520305;4477.10457908418;4543.728135932034;3829.7127715421316;4134.842244171637;4224.662027037845;4427.937295049182;4510.105755582663;3593.2633810068874;3635.800030998294;4434.007045244447;4547.522510136454;4565.817367305303;3646.9635303647156;3707.703383729295;4109.239790638741;4139.923619858829;4160.7087503874745;4220.409142720022;4665.507037747922;4969.931464735099;5080.79676812927;5390.703511306877;4308.849190278325;5104.489551044903;5210.947890521084;5213.244079608474;5237.554807841338;5190.662606930538;5834.4165583441745;5844.970775146139;5857.648541087506;5867.501262392701;3923.431102490123;4628.6991345562765;4697.67116301859;4700.882477938045;4709.987352719182;5468.890622187534;5556.360439033412;5732.684702341369;6168.938310616882;6222.346653601352;4449.7997590108525;4738.905221895543;4773.976130119397;4782.976085119552;4786.2581299898575;4668.743219122981;4860.635452341088;4890.755462226945;4907.779149938261;4922.163232250526;4660.650451216173;4863.586595139453;5151.356080489939;5174.741262936838;5217.738913179263;4997.401351297305;5186.6369354144845;5805.535557155417;5822.359540450547;5860.736266868002;1491.5823569400586;1492.940282388714;1651.6118712102646;1655.42887703742;1661.8450177127022;1063.1547919403938;1182.5447202826974;1223.7736018836545;1240.9379531023594;1261.035307989389;5039.97480012605;5318;5468.316460442445;5509.862253443696;5547.889042219133;5125.5136010556525;5536.889262214733;5975.52195824333;6061.848453788691;6095.35998720128;4723.48041715411;4862.82980095702;5432.266644003088;5503.807366742145;5539.002979463655;2632.815233145054;3140.4661207594763;3163.430582495127;3173.333599944038;3179.9296899968076;5628.605997580212;6010.278766548042;6088.4824789893355;6223.719932603044;6258.624482530971;5545.611807173459;5804.448577385142;6183.567150299526;6219.844503887349;6238.192813234063;5678.921005009035;5809.215755872988;6197.5351848611535;6334.936650633527;6362.178606574932;5444.863878403072;5891.705414729332;6089.725962331617;6134.809898852483;6157.6613286269685;5115.283860259492;5445.727713614383;5664.433556644302;5806.622569533136;6061.242029941464;5379.973100134474;5489.483069515111;6020.39796020412;6396.48828093742;6409.839754006187;4820.662553621213;4821.614270858253;4830.464006396529;4843.782029808596;4863.927041094315;5409.024367090265;5416.864578385572;5675.120356344773;5807.593468457168;6090.025595904634;4863.902721945612;4887.657863949489;5320.866978325418;5964.940350596438;5982.012967860254;5110.770015349392;5605.859853503695;5673.716314184192;5830;5900.586958912835;5507.284053073075;5540.307461567304;5754.6547207167705;5796.333421656564;5817.301923769175;5512.5589952804485;5787.508061814793;5891.381404952418;5933.584649074523;5960.58596268512;4962.70969547913;5091.541761241506;5686.857828554319;5788.536917046542;5878.3540608629655 15:19:35 INFO - PROCESS | 1897 | |5;yui.html;724.9746258880937;751.872181729106;789.3645615279701;809.8785182222666;867.8481265778487;16044.598885027866;16862.409815656454;16934.661306773887;17061.7440738389;17132;15563.84436155641;15687.921560392211;15689.686206275868;15708.921455392707;15716.528504144893;943.415082648757;971.4171497101718;1008.9949550252238;1012.3015119567475;1018.5060245780791;1003.9498025098752;1013.9188864890791;1017.5624481473001;1019.2050200843354;1019.2101121630722;1051.4584988730771;1068.983965240522;1069.1393428290223;1069.647016484562;1070.855434516342;1057.428988346292;1064.1380481809758;1064.925455218135;1065.1265961911236;1065.5897479470382;782.6321628834439;785.8939043229148;786.6695987685185;787.0712559180165;788.5347644889486;16250.18749062554;16755.748663770053;17279.395221167324;17342.56643583908;17424.738628920575;14478.85521144796;14625.853741462663;14651.560453186421;14707.19110448925;14709.852901470955;9523.809523809554;10926.945365273203;12001.51993920242;12034.217775844541;12219.083568732382;3773.4528493368307;3773.8867833965023;3914.725969182158;3970.06703424695;4035.051762835731;114477;117542.8245717549;119487;120496.39751801184;122531.77468225293;10486.63296784624;11555;11613.883861161281;11680.357580333164;11724.237924534878;23406.7659323408;23465.88267058654;23532.29403117909;23538.882305588362;23891.40271493192;7322.890156647654;8105.473144245605;8377.49735015901;8446.767559717624;8733.30133589324;4461.933071003938;4528.547145285511;4564.726116433025;4574.405327307429;4709.011107667359;3621.3843646580144;3684.7236457265276;3685.299793039314;3716.8141592920247;3734.9439758403646;688.5627626457135;689.3279052923359;696.571608460793;703.145678001231;706.2972342519226;700.3311837195466;728.9088863892014;735.9300866417682;747.234085062815;788.9211078892065;10547.57809687604;10802.67591972242;10954.56181752721;11063.612773552886;11233.887661123448;713.678844519968;715.5706576054325;727.6465773660987;760.2967255288836;760.9429292802952;4664.883377915579;4709.95290047102;4720.33915251865;4769.499202583748;4772.266751844117;105786.47106764418;108257;109650.45174774075;113127;114469.28296075565;95030;96036;96786.06427871402;98418;98738.50630746943;77805.61097194478;78154.99999999886;80008;80019;80859.78710319349;9005.369624126248;9059.184673379428;9072.773180670536;9073.546322683707;9076.546172691207;43069.784651077796;47112.29331559961;52450.16424425127;53391.46608533865;54160.187597185286;67147.3285267141;68820.6558967202;69457.65271173611;69765;70461.29538704548;10814.945925270324;10884.836727448936;10904.836427453753;11015;11089.88910110921;13069.215847049207;13175.945126814759;13401.994850386065;13490.52783152585;13595.252261125734;12330.445129969352;12575;12649.494020239088;12732.299723515296;13154.60536183916;12232.266064036186;12516.499340026661;12606.55877044299;12907.967362611156;13070;12000.939995299968;12019.75960480803;12394.070444716503;12755.553555625509;12859.16415432994;9046.864297035681;9243.21432678203;9400.200982916254;9481.051894810464;9644.373115747454;10148.847767283645;10751.6236931707;10761.73095672583;10794;10803.135749139892 15:19:35 INFO - PROCESS | 1897 | __end_tp_report 15:19:35 INFO - PROCESS | 1897 | __start_cc_report 15:19:35 INFO - PROCESS | 1897 | _x_x_mozilla_cycle_collect,394 15:19:35 INFO - PROCESS | 1897 | __end_cc_report 15:19:35 INFO - PROCESS | 1897 | __startTimestamp1494281975301__endTimestamp 15:19:35 INFO - PROCESS | 1897 | 15:19:35 INFO - PROCESS | 1897 | ------- Summary: start ------- 15:19:35 INFO - PROCESS | 1897 | Number of tests: 6 15:19:35 INFO - PROCESS | 1897 | 15:19:35 INFO - PROCESS | 1897 | [#0] dojo.html Cycles:165 Average:5865.56 Median:6133.79 stddev:661.76 (10.8%) stddev-sans-first:624.01 15:19:35 INFO - PROCESS | 1897 | Values: 2984.6 3534.0 3548.8 3565.3 3590.4 5962.4 5985.4 6009.5 6017.8 6028.2 5938.9 5976.5 6044.0 6049.6 6088.0 4457.5 5332.2 5377.0 5382.8 5414.5 5417.1 5429.1 5443.4 5456.4 5467.1 4096.4 5316.4 5434.8 5438.2 5480.4 5421.8 5423.2 5423.4 5424.4 5465.3 5285.3 5389.4 5413.9 5416.6 5456.6 5659.9 5964.8 6045.0 6123.4 6133.8 6243.5 6253.9 6278.2 6292.8 6294.4 5324.9 6121.0 6285.6 6296.7 6350.1 6081.6 6094.6 6114.5 6120.0 6124.7 5139.1 5993.7 6288.4 6426.3 6485.9 5353.5 6295.9 6312.8 6331.8 6354.2 6312.1 6343.5 6416.3 6423.1 6444.6 6150.1 6174.9 6198.9 6302.5 6317.3 6185.2 6193.2 6268.8 6282.9 6286.2 5053.0 5091.4 5117.6 5172.1 5172.4 4612.8 4615.3 4638.0 4660.4 4718.9 5917.9 5976.9 6034.8 6175.2 6222.6 5006.4 5637.3 6250.2 6285.6 6299.4 6157.1 6192.5 6202.7 6203.6 6204.9 5201.6 5658.2 5672.2 5678.5 5679.3 6332.8 6419.5 6479.6 6504.8 6517.7 5055.5 6318.3 6340.9 6356.7 6377.0 6484.4 6548.6 6581.2 6592.1 6641.5 6172.5 6229.6 6272.3 6286.2 6353.5 6343.9 6353.9 6355.7 6361.0 6368.6 4983.7 6217.9 6303.8 6316.9 6355.1 6090.9 6134.0 6187.8 6237.0 6247.2 6144.1 6162.7 6222.6 6226.9 6252.1 6315.7 6319.5 6323.7 6348.9 6357.6 5948.3 5963.9 5997.1 6019.3 6112.9 15:19:35 INFO - PROCESS | 1897 | 15:19:35 INFO - PROCESS | 1897 | [#1] ext.html Cycles:180 Average:60703.78 Median:32641.67 stddev:125901.82 (385.7%) stddev-sans-first:126174.53 15:19:35 INFO - PROCESS | 1897 | Values: 746.7 775.8 845.8 978.1 986.4 42089.0 42809.6 42887.6 42957.8 42991.6 39677.6 39719.8 39738.4 39785.6 39814.0 8329.3 8458.5 11306.2 11552.0 11732.5 11379.7 11476.3 11476.8 11485.8 11501.5 11445.0 11475.8 11481.5 11482.3 11488.4 11439.2 11457.4 11461.7 11469.8 11476.1 11209.3 11259.8 11271.8 11274.8 11321.5 36240.1 36327.5 36421.7 36461.8 36523.5 3902.8 3926.3 3944.4 3997.6 4010.4 26463.9 26658.8 26883.5 28218.9 28234.7 3184.6 3273.8 3276.3 3293.6 3421.7 361851.0 381559.0 384445.0 386405.0 438563.0 45047.1 45247.9 45332.6 45381.0 45504.3 46310.5 46434.5 46551.1 46612.0 46656.5 14542.6 14591.9 14607.6 14623.0 14628.6 12555.4 12829.4 12836.7 12852.5 12879.1 12330.7 12351.8 12377.6 12447.1 12472.9 4807.7 5199.5 5722.3 5741.8 5760.8 1133.7 1159.8 1162.5 1181.8 1218.2 23584.6 26597.5 27054.1 27168.3 27189.9 1259.5 1274.1 1286.9 1318.5 1331.8 14712.4 16489.6 16667.6 17026.0 17235.2 511562.0 518282.0 520022.0 521160.0 521737.0 48285.8 60665.0 61997.7 62135.7 62157.7 1489.0 1511.2 1522.4 1524.7 1526.0 30473.2 31940.4 31998.0 31998.5 32474.0 49039.3 52188.7 52283.7 52377.0 52420.5 496685.0 500793.0 500896.0 501029.0 502850.5 27643.4 28108.0 33653.3 34095.8 34552.0 47041.5 47502.5 47540.0 48154.8 48563.0 27464.9 31502.7 32056.4 32178.8 32348.3 30743.8 32052.5 32575.0 33019.0 33101.3 37592.1 38342.2 38726.8 38753.6 38774.8 32119.0 32417.0 32708.3 33133.0 33545.5 23980.8 24598.8 25688.5 25734.2 26349.7 15:19:35 INFO - PROCESS | 1897 | 15:19:35 INFO - PROCESS | 1897 | [#2] jquery.html Cycles:180 Average:41804.81 Median:22531.66 stddev:116135.98 (515.4%) stddev-sans-first:116420.22 15:19:35 INFO - PROCESS | 1897 | Values: 431.1 440.0 442.4 451.1 452.6 20155.5 21270.0 22305.7 22477.9 22585.4 21602.9 22366.8 22858.5 23089.5 23406.2 6927.7 6955.3 6976.5 7061.8 7087.3 6697.9 6863.1 6904.7 6947.9 6953.2 6579.8 6884.5 6930.1 6973.2 6979.3 5634.0 6455.2 6932.2 7019.6 7182.7 6765.7 6769.3 6769.7 6778.2 6790.6 26287.3 27667.3 27890.9 28321.7 28381.9 2917.4 2944.7 2995.5 3318.2 3420.5 15751.7 15998.2 16073.6 16882.7 16972.6 1850.6 2110.1 2116.6 2122.9 2126.8 151785.0 171333.1 176672.0 178279.0 179410.0 18579.4 18604.7 19402.8 19598.1 21089.8 33392.2 33761.7 33939.0 34043.8 34166.7 10876.5 10920.5 10986.6 11155.5 11343.1 7593.0 9129.3 9205.3 9266.9 9296.1 9610.8 9837.3 9902.5 9950.9 10013.9 3966.9 4174.8 4280.7 4289.4 4294.5 843.3 931.4 934.7 935.9 970.3 21088.7 21471.9 21513.6 21542.9 21636.4 954.5 958.8 982.1 984.4 1002.9 10960.7 11301.7 11458.5 11570.3 12614.9 436049.0 594104.0 765105.0 768956.0 809530.0 119566.4 139015.2 159233.0 159602.0 163564.0 1318.3 1319.4 1344.4 1352.1 1354.2 19283.6 20459.8 20669.7 20722.7 20726.5 64146.0 64914.0 65451.7 65734.4 66826.7 92342.0 92378.5 92872.0 93258.5 94453.1 11259.6 11536.5 11545.2 11658.6 11697.6 12244.9 12717.9 12855.8 13301.5 13575.3 5671.1 11410.1 11473.6 11724.2 11785.6 10374.7 10605.0 10653.8 10804.5 10836.9 11760.8 11870.9 11901.2 11970.2 12043.7 9109.8 10673.9 11700.7 11713.6 11802.0 11181.2 11306.7 11310.1 11398.3 11621.6 15:19:35 INFO - PROCESS | 1897 | 15:19:35 INFO - PROCESS | 1897 | [#3] mootools.html Cycles:180 Average:6849.46 Median:2491.96 stddev:4275.43 (171.6%) stddev-sans-first:4269.17 15:19:35 INFO - PROCESS | 1897 | Values: 1591.4 1594.4 1640.3 1654.0 1655.2 10844.2 11292.7 11432.5 11437.3 11687.3 11349.7 11406.5 11500.3 11526.8 11551.8 2465.7 2509.6 2512.7 2517.0 2520.8 2440.7 2469.8 2499.6 2517.1 2517.1 2451.6 2478.5 2484.3 2511.2 2515.7 1906.7 2148.2 2469.8 2472.7 2473.9 2381.6 2409.8 2416.8 2420.2 2424.9 11203.4 11479.4 11493.7 11497.4 11575.0 4831.4 6073.1 6135.6 6141.7 6143.3 3411.3 3476.2 3562.1 3711.6 3735.9 1235.7 1236.1 1273.4 1282.7 1290.2 13956.8 14200.9 14948.5 15658.7 15772.9 4023.7 4678.1 4780.9 4815.2 4925.0 12030.6 12046.4 12055.8 12062.7 12109.0 6075.5 6078.0 6086.0 6087.5 6110.1 5403.1 5448.1 5451.6 5470.6 5491.1 4010.5 4063.6 4086.1 4136.2 4162.5 1093.7 1097.1 1099.6 1106.6 1108.7 943.6 966.3 969.7 978.8 1007.4 6465.9 6544.5 6577.2 6630.0 6695.9 1002.0 1002.4 1032.4 1038.8 1054.3 3827.3 4207.9 4270.9 4335.8 4369.2 13558.3 14101.7 14183.5 14201.8 14230.0 10557.3 13888.2 14006.5 14150.6 14255.8 8610.6 10389.8 10448.2 10455.8 10568.2 6592.0 6599.2 6734.5 6793.6 6870.6 12276.9 12493.9 12631.8 12636.3 12682.7 12908.8 13008.8 13042.7 13203.8 13217.3 7314.3 8679.6 8828.8 8839.2 8844.3 10414.2 10503.4 10631.8 10643.1 10650.9 7492.4 9066.9 9095.5 9130.5 9136.5 8754.8 8825.5 8921.0 8931.2 8939.4 8100.4 8111.0 8139.4 8160.5 8268.5 5474.8 5518.9 5938.5 5987.5 6242.2 5998.0 6026.4 6120.1 6130.5 6201.7 15:19:35 INFO - PROCESS | 1897 | 15:19:35 INFO - PROCESS | 1897 | [#4] prototype.html Cycles:180 Average:4846.88 Median:5215.49 stddev:1373.68 (26.3%) stddev-sans-first:1335.09 15:19:35 INFO - PROCESS | 1897 | Values: 332.3 380.3 396.4 399.1 411.9 5417.4 5459.4 5490.9 5556.6 5624.5 5041.8 5455.8 5466.7 5480.6 5536.8 3958.6 4199.3 4512.8 4514.7 4542.3 3683.9 3738.3 4142.5 4477.1 4543.7 3829.7 4134.8 4224.7 4427.9 4510.1 3593.3 3635.8 4434.0 4547.5 4565.8 3647.0 3707.7 4109.2 4139.9 4160.7 4220.4 4665.5 4969.9 5080.8 5390.7 4308.8 5104.5 5210.9 5213.2 5237.6 5190.7 5834.4 5845.0 5857.6 5867.5 3923.4 4628.7 4697.7 4700.9 4710.0 5468.9 5556.4 5732.7 6168.9 6222.3 4449.8 4738.9 4774.0 4783.0 4786.3 4668.7 4860.6 4890.8 4907.8 4922.2 4660.7 4863.6 5151.4 5174.7 5217.7 4997.4 5186.6 5805.5 5822.4 5860.7 1491.6 1492.9 1651.6 1655.4 1661.8 1063.2 1182.5 1223.8 1240.9 1261.0 5040.0 5318.0 5468.3 5509.9 5547.9 5125.5 5536.9 5975.5 6061.8 6095.4 4723.5 4862.8 5432.3 5503.8 5539.0 2632.8 3140.5 3163.4 3173.3 3179.9 5628.6 6010.3 6088.5 6223.7 6258.6 5545.6 5804.4 6183.6 6219.8 6238.2 5678.9 5809.2 6197.5 6334.9 6362.2 5444.9 5891.7 6089.7 6134.8 6157.7 5115.3 5445.7 5664.4 5806.6 6061.2 5380.0 5489.5 6020.4 6396.5 6409.8 4820.7 4821.6 4830.5 4843.8 4863.9 5409.0 5416.9 5675.1 5807.6 6090.0 4863.9 4887.7 5320.9 5964.9 5982.0 5110.8 5605.9 5673.7 5830.0 5900.6 5507.3 5540.3 5754.7 5796.3 5817.3 5512.6 5787.5 5891.4 5933.6 5960.6 4962.7 5091.5 5686.9 5788.5 5878.4 15:19:35 INFO - PROCESS | 1897 | 15:19:35 INFO - PROCESS | 1897 | [#5] yui.html Cycles:180 Average:21393.02 Median:17383.65 stddev:31720.71 (182.5%) stddev-sans-first:31771.73 15:19:35 INFO - PROCESS | 1897 | Values: 725.0 751.9 789.4 809.9 867.8 16044.6 16862.4 16934.7 17061.7 17132.0 15563.8 15687.9 15689.7 15708.9 15716.5 943.4 971.4 1009.0 1012.3 1018.5 1003.9 1013.9 1017.6 1019.2 1019.2 1051.5 1069.0 1069.1 1069.6 1070.9 1057.4 1064.1 1064.9 1065.1 1065.6 782.6 785.9 786.7 787.1 788.5 16250.2 16755.7 17279.4 17342.6 17424.7 14478.9 14625.9 14651.6 14707.2 14709.9 9523.8 10926.9 12001.5 12034.2 12219.1 3773.5 3773.9 3914.7 3970.1 4035.1 114477.0 117542.8 119487.0 120496.4 122531.8 10486.6 11555.0 11613.9 11680.4 11724.2 23406.8 23465.9 23532.3 23538.9 23891.4 7322.9 8105.5 8377.5 8446.8 8733.3 4461.9 4528.5 4564.7 4574.4 4709.0 3621.4 3684.7 3685.3 3716.8 3734.9 688.6 689.3 696.6 703.1 706.3 700.3 728.9 735.9 747.2 788.9 10547.6 10802.7 10954.6 11063.6 11233.9 713.7 715.6 727.6 760.3 760.9 4664.9 4710.0 4720.3 4769.5 4772.3 105786.5 108257.0 109650.5 113127.0 114469.3 95030.0 96036.0 96786.1 98418.0 98738.5 77805.6 78155.0 80008.0 80019.0 80859.8 9005.4 9059.2 9072.8 9073.5 9076.5 43069.8 47112.3 52450.2 53391.5 54160.2 67147.3 68820.7 69457.7 69765.0 70461.3 10814.9 10884.8 10904.8 11015.0 11089.9 13069.2 13175.9 13402.0 13490.5 13595.3 12330.4 12575.0 12649.5 12732.3 13154.6 12232.3 12516.5 12606.6 12908.0 13070.0 12000.9 12019.8 12394.1 12755.6 12859.2 9046.9 9243.2 9400.2 9481.1 9644.4 10148.8 10751.6 10761.7 10794.0 10803.1 15:19:35 INFO - PROCESS | 1897 | -------- Summary: end -------- 15:19:35 INFO - PROCESS | 1897 | 15:19:35 INFO - TEST-INFO | 1897: exit 0 15:19:35 INFO - TEST-OK | dromaeo_css | took 1114044ms 15:19:35 INFO - TEST-START | kraken 15:19:35 INFO - operating with platform_type : mac_ 15:19:35 INFO - Initialising browser for kraken test... 15:19:35 INFO - TEST-INFO | started process 1903 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmpP3drjE/profile http://localhost:49375/getInfo.html) 15:19:38 INFO - PROCESS | 1903 | 2017-05-08 15:19:38.298 plugin-container[1905:11037] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 15:19:38 INFO - PROCESS | 1903 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:19:38 INFO - PROCESS | 1903 | 2017-05-08 15:19:38.299 plugin-container[1905:11037] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 15:19:38 INFO - PROCESS | 1903 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:19:38 INFO - PROCESS | 1903 | 2017-05-08 15:19:38.299 plugin-container[1905:11037] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:19:38 INFO - PROCESS | 1903 | __metrics Screen width/height:1600/1200 15:19:38 INFO - PROCESS | 1903 | colorDepth:24 15:19:38 INFO - PROCESS | 1903 | Browser inner width/height: 1024/689 15:19:38 INFO - PROCESS | 1903 | __metrics 15:19:38 ERROR - PROCESS | 1903 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 15:19:39 INFO - TEST-INFO | 1903: exit 0 15:19:39 INFO - Browser initialized. 15:19:39 INFO - Running cycle 1/1 for kraken test... 15:19:39 INFO - TEST-INFO | started process 1906 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmpP3drjE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:19:41 INFO - PROCESS | 1906 | 2017-05-08 15:19:41.174 plugin-container[1907:11164] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x854b, name = 'com.apple.tsm.portname' 15:19:41 INFO - PROCESS | 1906 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:19:41 INFO - PROCESS | 1906 | 2017-05-08 15:19:41.174 plugin-container[1907:11164] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x431b, name = 'com.apple.CFPasteboardClient' 15:19:41 INFO - PROCESS | 1906 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:19:41 INFO - PROCESS | 1906 | 2017-05-08 15:19:41.174 plugin-container[1907:11164] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:19:51 INFO - PROCESS | 1906 | RSS: Main: 174211072 15:19:51 INFO - PROCESS | 1906 | 15:19:53 INFO - PROCESS | 1906 | Cycle 1(1): loaded http://localhost:49375/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49375/tests/kraken/driver.html?testName=audio-beat-detection) 15:19:54 INFO - PROCESS | 1906 | RSS: Main: 187768832 15:19:54 INFO - PROCESS | 1906 | 15:19:57 INFO - PROCESS | 1906 | Cycle 1(1): loaded http://localhost:49375/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49375/tests/kraken/driver.html?testName=audio-dft) 15:19:58 INFO - PROCESS | 1906 | RSS: Main: 195911680 15:19:58 INFO - PROCESS | 1906 | 15:20:01 INFO - PROCESS | 1906 | Cycle 1(1): loaded http://localhost:49375/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49375/tests/kraken/driver.html?testName=audio-fft) 15:20:02 INFO - PROCESS | 1906 | RSS: Main: 199647232 15:20:02 INFO - PROCESS | 1906 | 15:20:04 INFO - PROCESS | 1906 | Cycle 1(1): loaded http://localhost:49375/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49375/tests/kraken/driver.html?testName=audio-oscillator) 15:20:04 INFO - PROCESS | 1906 | RSS: Main: 199970816 15:20:04 INFO - PROCESS | 1906 | 15:20:06 INFO - PROCESS | 1906 | Cycle 1(1): loaded http://localhost:49375/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49375/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:20:07 INFO - PROCESS | 1906 | RSS: Main: 200011776 15:20:07 INFO - PROCESS | 1906 | 15:20:10 INFO - PROCESS | 1906 | Cycle 1(1): loaded http://localhost:49375/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49375/tests/kraken/driver.html?testName=imaging-darkroom) 15:20:10 INFO - PROCESS | 1906 | RSS: Main: 204021760 15:20:10 INFO - PROCESS | 1906 | 15:20:14 INFO - PROCESS | 1906 | Cycle 1(1): loaded http://localhost:49375/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49375/tests/kraken/driver.html?testName=imaging-desaturate) 15:20:14 INFO - PROCESS | 1906 | RSS: Main: 204959744 15:20:14 INFO - PROCESS | 1906 | 15:20:17 INFO - PROCESS | 1906 | Cycle 1(1): loaded http://localhost:49375/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49375/tests/kraken/driver.html?testName=json-parse-financial) 15:20:18 INFO - PROCESS | 1906 | RSS: Main: 205099008 15:20:18 INFO - PROCESS | 1906 | 15:20:20 INFO - PROCESS | 1906 | Cycle 1(1): loaded http://localhost:49375/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49375/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:20:20 INFO - PROCESS | 1906 | RSS: Main: 204881920 15:20:20 INFO - PROCESS | 1906 | 15:20:22 INFO - PROCESS | 1906 | Cycle 1(1): loaded http://localhost:49375/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49375/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:20:23 INFO - PROCESS | 1906 | RSS: Main: 204869632 15:20:23 INFO - PROCESS | 1906 | 15:20:25 INFO - PROCESS | 1906 | Cycle 1(1): loaded http://localhost:49375/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49375/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:20:25 INFO - PROCESS | 1906 | RSS: Main: 204898304 15:20:25 INFO - PROCESS | 1906 | 15:20:28 INFO - PROCESS | 1906 | Cycle 1(1): loaded http://localhost:49375/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49375/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:20:29 INFO - PROCESS | 1906 | RSS: Main: 205299712 15:20:29 INFO - PROCESS | 1906 | 15:20:32 INFO - PROCESS | 1906 | Cycle 1(1): loaded http://localhost:49375/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49375/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:20:33 INFO - PROCESS | 1906 | RSS: Main: 205373440 15:20:33 INFO - PROCESS | 1906 | 15:20:35 INFO - PROCESS | 1906 | Cycle 1(1): loaded http://localhost:49375/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49375/tests/kraken/driver.html?testName=ai-astar) 15:20:35 INFO - PROCESS | 1906 | RSS: Main: 207114240 15:20:35 INFO - PROCESS | 1906 | 15:20:35 INFO - PROCESS | 1906 | __start_tp_report 15:20:35 INFO - PROCESS | 1906 | _x_x_mozilla_page_load 15:20:35 INFO - PROCESS | 1906 | _x_x_mozilla_page_load_details 15:20:35 INFO - PROCESS | 1906 | |i|pagename|runs| 15:20:35 INFO - PROCESS | 1906 | |0;ai-astar;153;140;118;119;119;120;119;119;122;118 15:20:35 INFO - PROCESS | 1906 | |1;audio-beat-detection;158;138;113;146;112;99;92;139;160;137 15:20:35 INFO - PROCESS | 1906 | |2;audio-dft;197;197;179;278;273;203;196;197;195;195 15:20:35 INFO - PROCESS | 1906 | |3;audio-fft;79;59;62;92;61;60;60;58;74;62 15:20:35 INFO - PROCESS | 1906 | |4;audio-oscillator;89;70;85;71;71;71;82;71;70;83 15:20:35 INFO - PROCESS | 1906 | |5;imaging-gaussian-blur;89;89;91;133;119;87;104;87;88;89 15:20:35 INFO - PROCESS | 1906 | |6;imaging-darkroom;142;93;152;156;95;95;96;101;155;155 15:20:35 INFO - PROCESS | 1906 | |7;imaging-desaturate;94;92;91;97;143;91;92;92;90;94 15:20:35 INFO - PROCESS | 1906 | |8;json-parse-financial;64;81;83;66;61;66;97;107;108;88 15:20:35 INFO - PROCESS | 1906 | |9;json-stringify-tinderbox;57;64;61;62;62;58;57;59;57;59 15:20:35 INFO - PROCESS | 1906 | |10;stanford-crypto-aes;82;84;82;84;83;84;83;87;85;93 15:20:35 INFO - PROCESS | 1906 | |11;stanford-crypto-ccm;153;151;133;135;142;121;153;154;203;151 15:20:35 INFO - PROCESS | 1906 | |12;stanford-crypto-pbkdf2;227;227;208;227;176;181;162;161;167;164 15:20:35 INFO - PROCESS | 1906 | |13;stanford-crypto-sha256-iterative;65;65;67;57;60;83;80;56;69;85 15:20:35 INFO - PROCESS | 1906 | __end_tp_report 15:20:35 INFO - PROCESS | 1906 | __start_cc_report 15:20:35 INFO - PROCESS | 1906 | _x_x_mozilla_cycle_collect,969 15:20:35 INFO - PROCESS | 1906 | __end_cc_report 15:20:35 INFO - PROCESS | 1906 | __startTimestamp1494282035290__endTimestamp 15:20:35 INFO - PROCESS | 1906 | 15:20:35 INFO - PROCESS | 1906 | ------- Summary: start ------- 15:20:35 INFO - PROCESS | 1906 | Number of tests: 14 15:20:35 INFO - PROCESS | 1906 | 15:20:35 INFO - PROCESS | 1906 | [#0] ai-astar Cycles:10 Average:124.70 Median:119.50 stddev:11.94 (10.0%) stddev-sans-first:7.02 15:20:35 INFO - PROCESS | 1906 | Values: 153.0 140.0 118.0 119.0 119.0 120.0 119.0 119.0 122.0 118.0 15:20:35 INFO - PROCESS | 1906 | 15:20:35 INFO - PROCESS | 1906 | [#1] audio-beat-detection Cycles:10 Average:129.40 Median:152.00 stddev:23.92 (15.7%) stddev-sans-first:23.02 15:20:35 INFO - PROCESS | 1906 | Values: 158.0 138.0 113.0 146.0 112.0 99.0 92.0 139.0 160.0 137.0 15:20:35 INFO - PROCESS | 1906 | 15:20:35 INFO - PROCESS | 1906 | [#2] audio-dft Cycles:10 Average:211.00 Median:197.00 stddev:34.55 (17.5%) stddev-sans-first:36.28 15:20:35 INFO - PROCESS | 1906 | Values: 197.0 197.0 179.0 278.0 273.0 203.0 196.0 197.0 195.0 195.0 15:20:35 INFO - PROCESS | 1906 | 15:20:35 INFO - PROCESS | 1906 | [#3] audio-fft Cycles:10 Average:66.70 Median:62.00 stddev:11.28 (18.2%) stddev-sans-first:11.06 15:20:35 INFO - PROCESS | 1906 | Values: 79.0 59.0 62.0 92.0 61.0 60.0 60.0 58.0 74.0 62.0 15:20:35 INFO - PROCESS | 1906 | 15:20:35 INFO - PROCESS | 1906 | [#4] audio-oscillator Cycles:10 Average:76.30 Median:76.50 stddev:7.50 (9.8%) stddev-sans-first:6.39 15:20:35 INFO - PROCESS | 1906 | Values: 89.0 70.0 85.0 71.0 71.0 71.0 82.0 71.0 70.0 83.0 15:20:35 INFO - PROCESS | 1906 | 15:20:35 INFO - PROCESS | 1906 | [#5] imaging-gaussian-blur Cycles:10 Average:97.60 Median:88.50 stddev:16.11 (18.2%) stddev-sans-first:16.78 15:20:35 INFO - PROCESS | 1906 | Values: 89.0 89.0 91.0 133.0 119.0 87.0 104.0 87.0 88.0 89.0 15:20:35 INFO - PROCESS | 1906 | 15:20:35 INFO - PROCESS | 1906 | [#6] imaging-darkroom Cycles:10 Average:124.00 Median:124.50 stddev:29.83 (24.0%) stddev-sans-first:30.92 15:20:35 INFO - PROCESS | 1906 | Values: 142.0 93.0 152.0 156.0 95.0 95.0 96.0 101.0 155.0 155.0 15:20:35 INFO - PROCESS | 1906 | 15:20:35 INFO - PROCESS | 1906 | [#7] imaging-desaturate Cycles:10 Average:97.60 Median:92.00 stddev:16.08 (17.5%) stddev-sans-first:17.00 15:20:35 INFO - PROCESS | 1906 | Values: 94.0 92.0 91.0 97.0 143.0 91.0 92.0 92.0 90.0 94.0 15:20:35 INFO - PROCESS | 1906 | 15:20:35 INFO - PROCESS | 1906 | [#8] json-parse-financial Cycles:10 Average:82.10 Median:73.50 stddev:17.77 (24.2%) stddev-sans-first:17.60 15:20:35 INFO - PROCESS | 1906 | Values: 64.0 81.0 83.0 66.0 61.0 66.0 97.0 107.0 108.0 88.0 15:20:35 INFO - PROCESS | 1906 | 15:20:35 INFO - PROCESS | 1906 | [#9] json-stringify-tinderbox Cycles:10 Average:59.60 Median:60.00 stddev:2.50 (4.2%) stddev-sans-first:2.47 15:20:35 INFO - PROCESS | 1906 | Values: 57.0 64.0 61.0 62.0 62.0 58.0 57.0 59.0 57.0 59.0 15:20:35 INFO - PROCESS | 1906 | 15:20:35 INFO - PROCESS | 1906 | [#10] stanford-crypto-aes Cycles:10 Average:84.70 Median:84.00 stddev:3.27 (3.9%) stddev-sans-first:3.32 15:20:35 INFO - PROCESS | 1906 | Values: 82.0 84.0 82.0 84.0 83.0 84.0 83.0 87.0 85.0 93.0 15:20:35 INFO - PROCESS | 1906 | 15:20:35 INFO - PROCESS | 1906 | [#11] stanford-crypto-ccm Cycles:10 Average:149.60 Median:152.00 stddev:21.76 (14.3%) stddev-sans-first:23.05 15:20:35 INFO - PROCESS | 1906 | Values: 153.0 151.0 133.0 135.0 142.0 121.0 153.0 154.0 203.0 151.0 15:20:35 INFO - PROCESS | 1906 | 15:20:35 INFO - PROCESS | 1906 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.00 Median:194.50 stddev:28.94 (14.9%) stddev-sans-first:27.42 15:20:35 INFO - PROCESS | 1906 | Values: 227.0 227.0 208.0 227.0 176.0 181.0 162.0 161.0 167.0 164.0 15:20:35 INFO - PROCESS | 1906 | 15:20:35 INFO - PROCESS | 1906 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:68.00 stddev:10.55 (15.5%) stddev-sans-first:11.11 15:20:35 INFO - PROCESS | 1906 | Values: 65.0 65.0 67.0 57.0 60.0 83.0 80.0 56.0 69.0 85.0 15:20:35 INFO - PROCESS | 1906 | -------- Summary: end -------- 15:20:35 INFO - PROCESS | 1906 | 15:20:36 INFO - TEST-INFO | 1906: exit 0 15:20:36 INFO - TEST-OK | kraken | took 60233ms 15:20:36 INFO - SUITE-END | took 1174s 15:20:36 INFO - Completed test suite (00:19:34) 15:20:36 INFO - javascript benchmark 15:20:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7921.588824943254, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2984.552317152427, 3534.045807631938, 3548.8403021864015, 3565.251297227582, 3590.4255319148942, 5962.4037596240405, 5985.431384018522, 6009.549283803711, 6017.789377371793, 6028.186194863692, 5938.910916336258, 5976.462118409342, 6043.969780151104, 6049.576529642927, 6087.969560152204, 4457.487388950263, 5332.200169974496, 5376.99999999998, 5382.784688612451, 5414.458554144593, 5417.133258678612, 5429.077056900336, 5443.374011988612, 5456.427075157111, 5467.0705979983295, 4096.446979657752, 5316.362036555619, 5434.83695489134, 5438.157085651711, 5480.3971563128225, 5421.756020979065, 5423.186522021689, 5423.430539793324, 5424.430434804368, 5465.316835395575, 5285.2600635911, 5389.353277606673, 5413.918791218135, 5416.620836541444, 5456.645318054353, 5659.886802263932, 5964.821055368346, 6045.00257457519, 6123.4488895999575, 6133.78531751391, 6243.531735119885, 6253.906191407133, 6278.246610406734, 6292.842678933018, 6294.433500984934, 5324.92012619811, 6120.969395152996, 6285.591436556655, 6296.6536840473755, 6350.079238510436, 6081.635101893857, 6094.573379863411, 6114.480269177081, 6119.9902016167725, 6124.724387402578, 5139.100657384943, 5993.700314984233, 6288.371162883766, 6426.2931077581425, 6485.870282594322, 5353.518183363438, 6295.937040629627, 6312.810615681537, 6331.778387756499, 6354.237491500957, 6312.051093293003, 6343.492520598341, 6416.262129855127, 6423.100765892778, 6444.645544495098, 6150.077488376779, 6174.9073763893575, 6198.907016394758, 6302.527310451735, 6317.305096439388, 6185.165002724664, 6193.225846769154, 6268.811935641938, 6282.874342513124, 6286.18279623646, 5053.039922414803, 5091.389033315952, 5117.641765076483, 5172.120739474299, 5172.379314482287, 4612.838550650711, 4615.284630882219, 4638.049199914077, 4660.370849935283, 4718.8584342469785, 5917.911231331534, 5976.940230597638, 6034.818955431344, 6175.197224360804, 6222.564420490522, 5006.4492905780335, 5637.26715526987, 6250.15622890913, 6285.5600107992, 6299.401556852092, 6157.076438534255, 6192.535559833031, 6202.72087756052, 6203.565750397519, 6204.906926396107, 5201.557867581298, 5658.151277308354, 5672.177534257592, 5678.545716342683, 5679.346875109332, 6332.841678958063, 6419.4864410846085, 6479.643619600967, 6504.837379065561, 6517.706703198272, 5055.494450554915, 6318.336574659595, 6340.9048864268, 6356.682165891594, 6377.043443483514, 6484.351564843667, 6548.607083575001, 6581.2102547694585, 6592.077109204811, 6641.468682505485, 6172.537059720449, 6229.626222426669, 6272.341404152498, 6286.18279623646, 6353.523485738496, 6343.873122537616, 6353.856305864804, 6355.650439225887, 6361.01404282337, 6368.617882927039, 4983.651144419856, 6217.906731399123, 6303.842403939938, 6316.905246421399, 6355.078513615592, 6090.878182436415, 6133.969330153499, 6187.783427580013, 6236.968815155895, 6247.156633854554, 6144.1398204250545, 6162.722677479434, 6222.62664240147, 6226.906596401148, 6252.062190671436, 6315.652639104894, 6319.494440444845, 6323.747050117944, 6348.873022539616, 6357.586756860789, 5948.315943666534, 5963.940360596512, 5997.100434934795, 6019.337872834072, 6112.898653789679], "value": 5826.795900787599, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [746.6901235987066, 775.7982924439644, 845.8012367093734, 978.149010360986, 986.3835103060592, 42089.0, 42809.57190428095, 42887.57112428875, 42957.78521107398, 42991.57008429915, 39677.60322396775, 39719.80840574788, 39738.403923941165, 39785.602143978555, 39814.0, 8329.292010179142, 8458.450200736963, 11306.208565400426, 11551.960323570876, 11732.53069877204, 11379.715507112305, 11476.311421314706, 11476.770464590703, 11485.770284594302, 11501.539938402453, 11445.0, 11475.770484590303, 11481.483333250024, 11482.311061336304, 11488.36813975231, 11439.19925605208, 11457.427128643576, 11461.656150315504, 11469.770604587946, 11476.081913446946, 11209.327440353523, 11259.831102533468, 11271.774564508663, 11274.774504509945, 11321.547138114465, 36240.09399765027, 36327.54689812404, 36421.725239616484, 36461.81769091164, 36523.452148217795, 3902.843886244547, 3926.2736393767245, 3944.4280579316123, 3997.5802540733384, 4010.358342665189, 26463.867680661668, 26658.800353983923, 26883.46233075347, 28218.87124515017, 28234.717652823416, 3184.586003819512, 3273.770836041478, 3276.262840860811, 3293.5883014623173, 3421.674940880612, 361851.0, 381559.0, 384445.0, 386405.0, 438563.0, 45047.099058018655, 45247.86880328018, 45332.64002079943, 45381.0, 45504.317435238496, 46310.53689463062, 46434.53565464302, 46551.06897862092, 46612.0, 46656.533434665216, 14542.636434089232, 14591.854081459262, 14607.561773146823, 14623.0, 14628.561143165722, 12555.372231388394, 12829.422675979416, 12836.743265134644, 12852.48590056406, 12879.098463107492, 12330.691732706755, 12351.814722778985, 12377.566785162477, 12447.128700990843, 12472.87527124717, 4807.711537307773, 5199.506046925536, 5722.2561067061015, 5741.770329186869, 5760.8190320984095, 1133.7052366384798, 1159.8260260960924, 1162.5233654201907, 1181.8463599732152, 1218.2020776391478, 23584.64623030656, 26597.46805063888, 27054.05310814112, 27168.320791979964, 27189.86405067962, 1259.5402678022456, 1274.139955530032, 1286.9292188929699, 1318.4528420705492, 1331.760283149023, 14712.411503539739, 16489.587760305847, 16667.583310417092, 17026.0, 17235.22441490136, 511562.0, 518282.0, 520022.0, 521160.0, 521737.0, 48285.758571207625, 60665.0, 61997.690011549654, 62135.6893215531, 62157.689211553654, 1488.9627759305888, 1511.2066165263104, 1522.3986525322437, 1524.7407940650116, 1525.9623456049771, 30473.23816904595, 31940.36119277648, 31998.0, 31998.52002219922, 32474.0, 49039.26441103315, 52188.739056304476, 52283.73858130685, 52377.0, 52420.47579524309, 496685.0, 500793.0, 500896.0, 501029.0, 502850.48574756895, 27643.44713105767, 28108.0, 33653.326933461685, 34095.82952085224, 34552.0, 47041.52958470509, 47502.52497475119, 47540.0, 48154.75922620364, 48563.0, 27464.862675686494, 31502.684973149975, 32056.35887282288, 32178.83910580432, 32348.345289979767, 30743.846280769347, 32052.51921221137, 32575.0, 33019.0, 33101.33797324088, 37592.06019849526, 38342.23315533729, 38726.80636596799, 38753.612463876125, 38774.80612597032, 32119.0, 32417.038999249125, 32708.34583308368, 33133.0, 33545.49681754824, 23980.76019239855, 24598.754012459645, 25688.486230275663, 25734.227973160836, 26349.73650263473], "value": 19724.79140957359, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [431.1140606054557, 439.95600439955996, 442.40054725846477, 451.0640421126164, 452.5791014356649, 20155.496112597193, 21270.0, 22305.665415018746, 22477.887610561964, 22585.435364115907, 21602.89198554009, 22366.776332236674, 22858.542829143407, 23089.538209235805, 23406.180783672575, 6927.688254028581, 6955.339242771928, 6976.5116441849095, 7061.788146355618, 7087.3267039631155, 6697.8995315070315, 6863.073485079525, 6904.689288982008, 6947.895781563256, 6953.200381956064, 6579.835504112388, 6884.483663725216, 6930.064441300436, 6973.163220413558, 6979.267176946424, 5634.0, 6455.2253729552285, 6932.237453880044, 7019.578825270474, 7182.712691492334, 6765.661716914184, 6769.289224631392, 6769.7292108315605, 6778.22050464198, 6790.558613690144, 26287.34281642955, 27667.308317292227, 27890.86054569714, 28321.72552235134, 28381.858090709415, 2917.416516696648, 2944.749696275819, 2995.5356919677483, 3318.2202182487094, 3420.504026916108, 15751.739860811222, 15998.200089995453, 16073.598160045976, 16882.662346752997, 16972.575685607957, 1850.5928695686928, 2110.0610228448345, 2116.608427440928, 2122.9150833966623, 2126.8192203662707, 151785.0, 171333.14333428378, 176672.0, 178279.0, 179410.0, 18579.44261672152, 18604.69767116289, 19402.8059719401, 19598.118084686223, 21089.78910210909, 33392.1651958703, 33761.662383376344, 33939.0, 34043.82978085094, 34166.658333416344, 10876.456177191109, 10920.453977301104, 10986.615468458565, 11155.498002589902, 11343.149263805082, 7593.0, 9129.269658427309, 9205.263578913804, 9266.907330926604, 9296.070392960784, 9610.759731006641, 9837.311388202757, 9902.455364954996, 9950.900490994998, 10013.949930250303, 3966.900827479278, 4174.812133454002, 4280.743155410685, 4289.356596510486, 4294.506131794821, 843.2747836860295, 931.4411353188168, 934.7336009237334, 935.9204467620192, 970.2771435280761, 21088.683669744965, 21471.926403679754, 21513.56972860534, 21542.892285538786, 21636.35090947274, 954.4941181173989, 958.8226178157063, 982.111189373618, 984.3798407003685, 1002.904724051214, 10960.671179864617, 11301.717457063474, 11458.484368203452, 11570.305781653127, 12614.936925315315, 436049.0, 594104.0, 765105.0, 768956.0, 809530.0, 119566.4021679886, 139015.2196956055, 159233.0, 159602.0, 163564.0, 1318.2947123289186, 1319.4458327502473, 1344.37486568746, 1352.0805852020721, 1354.1671871798774, 19283.614327713087, 20459.795402046384, 20669.689954650392, 20722.689159662918, 20726.481837953568, 64146.0, 64914.0, 65451.690966179456, 65734.35664108435, 66826.66586667036, 92342.0, 92378.53810731172, 92872.0, 93258.53370733102, 94453.11093778223, 11259.605913792979, 11536.480858361561, 11545.249558778652, 11658.551704142224, 11697.649070527897, 12244.938775306067, 12717.87282127204, 12855.807162892752, 13301.46794128224, 13575.253361065235, 5671.149327600728, 11410.14423918226, 11473.598424055119, 11724.179307448398, 11785.646430607096, 10374.688759337232, 10605.045545900906, 10653.77481589596, 10804.459777011276, 10836.945815270874, 11760.82358764602, 11870.940645296718, 11901.226420282654, 11970.22193557416, 12043.698907527381, 9109.817803644022, 10673.8932610676, 11700.70748231301, 11713.590024349107, 11802.0, 11181.217314787888, 11306.660800176009, 11310.095192384424, 11398.316101033965, 11621.593244236412], "value": 11812.900441282152, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1591.4032237910783, 1594.418037416343, 1640.270079814483, 1653.958651033724, 1655.155870506042, 10844.240903136773, 11292.717682057953, 11432.542698292069, 11437.256578322414, 11687.29876207428, 11349.659510214708, 11406.54373825046, 11500.3099814011, 11526.769464610703, 11551.768964620702, 2465.7287698353166, 2509.598464245721, 2512.6733524641772, 2517.0057827158353, 2520.760527749861, 2440.73151953285, 2469.753024697534, 2499.6375525548783, 2517.0561039610147, 2517.1315896015844, 2451.6199989001734, 2478.5166892455977, 2484.291976786616, 2511.183865243794, 2515.72327044025, 1906.6758651029359, 2148.1836901977304, 2469.8271121021535, 2472.6538284640164, 2473.938151546217, 2381.5951288281035, 2409.7831195192516, 2416.830821842454, 2420.189236605739, 2424.87875606219, 11203.383813890232, 11479.426028698532, 11493.712657183554, 11497.367644779533, 11575.0, 4831.371921650198, 6073.119397687311, 6135.601185922946, 6141.692915354214, 6143.262808462969, 3411.3177364526946, 3476.1657202271526, 3562.1272788166807, 3711.5546134463766, 3735.925281494382, 1235.6910772306924, 1236.1347057060093, 1273.4269578689627, 1282.660095074806, 1290.2194172525674, 13956.790648140184, 14200.928995354958, 14948.476803311833, 15658.68682626341, 15772.921135394365, 4023.7183397162503, 4678.111158879802, 4780.880477988008, 4815.210886222473, 4924.96575719103, 12030.578929737418, 12046.397680115959, 12055.75888482243, 12062.69843253908, 12109.0, 6075.544334174954, 6077.969610151921, 6085.96957015221, 6087.54343424245, 6110.144579758838, 5403.13549832025, 5448.128299472065, 5451.64564303319, 5470.64440811354, 5491.066518691832, 4010.478637777129, 4063.6139566741113, 4086.1419101988913, 4136.193442278787, 4162.500499940026, 1093.748437859297, 1097.1223021582703, 1099.587654629514, 1106.623747925709, 1108.7172770943357, 943.6319835264252, 966.2801213096288, 969.6606187834202, 978.7993461340408, 1007.3905287301224, 6465.903011454832, 6544.509161812883, 6577.243616984012, 6630.0, 6695.866082678319, 1001.9899801002042, 1002.4336250018716, 1032.3805716570148, 1038.7558923652934, 1054.2567489919588, 3827.3275364625847, 4207.852725154605, 4270.914581708411, 4335.7615331157085, 4369.169857727022, 13558.254296013616, 14101.71796564063, 14183.503577374742, 14201.78697319541, 14230.0, 10557.313774604627, 13888.236147011808, 14006.509772157926, 14150.64623384403, 14255.78616320756, 8610.569471526398, 10389.84415233772, 10448.164146868232, 10455.84316235257, 10568.207384446197, 6591.967040164768, 6599.208095028627, 6734.528582999143, 6793.592384456949, 6870.62211578368, 12276.938615306866, 12493.875061249271, 12631.81052284198, 12636.30500322491, 12682.746345073232, 12908.806367904677, 13008.8048679268, 13042.673933151747, 13203.801942970671, 13217.273049982343, 7314.268573142643, 8679.60941757635, 8828.823423531621, 8839.16027977341, 8844.292456603327, 10414.218933580163, 10503.422311772925, 10631.787364252827, 10643.095336896451, 10650.881657425858, 7492.40060795146, 9066.90933090687, 9095.545222738705, 9130.543472826464, 9136.543172841199, 8754.781130471534, 8825.470471771458, 8921.018687944192, 8931.151540603632, 8939.374243803131, 8100.4329696923005, 8111.0, 8139.389545783972, 8160.469569477965, 8268.503889766633, 5474.808381706781, 5518.944810551843, 5938.524918006463, 5987.491063259672, 6242.188515493139, 5998.0, 6026.367231440811, 6120.143179954899, 6130.5402094842175, 6201.689915504297], "value": 5244.192272549226, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.3386460942722, 380.30783973168826, 396.4390387601546, 399.123922989039, 411.9032027473544, 5417.431169727181, 5459.399466058735, 5490.945090549093, 5556.555475561955, 5624.521915637166, 5041.798328066873, 5455.809046683366, 5466.715321899351, 5480.6437581557175, 5536.778528858851, 3958.604139586033, 4199.3281075028, 4512.842050528232, 4514.729116253019, 4542.341360502725, 3683.876417692603, 3738.3084129436006, 4142.5028996520305, 4477.10457908418, 4543.728135932034, 3829.7127715421316, 4134.842244171637, 4224.662027037845, 4427.937295049182, 4510.105755582663, 3593.2633810068874, 3635.800030998294, 4434.007045244447, 4547.522510136454, 4565.817367305303, 3646.9635303647156, 3707.703383729295, 4109.239790638741, 4139.923619858829, 4160.7087503874745, 4220.409142720022, 4665.507037747922, 4969.931464735099, 5080.79676812927, 5390.703511306877, 4308.849190278325, 5104.489551044903, 5210.947890521084, 5213.244079608474, 5237.554807841338, 5190.662606930538, 5834.4165583441745, 5844.970775146139, 5857.648541087506, 5867.501262392701, 3923.431102490123, 4628.6991345562765, 4697.67116301859, 4700.882477938045, 4709.987352719182, 5468.890622187534, 5556.360439033412, 5732.684702341369, 6168.938310616882, 6222.346653601352, 4449.7997590108525, 4738.905221895543, 4773.976130119397, 4782.976085119552, 4786.2581299898575, 4668.743219122981, 4860.635452341088, 4890.755462226945, 4907.779149938261, 4922.163232250526, 4660.650451216173, 4863.586595139453, 5151.356080489939, 5174.741262936838, 5217.738913179263, 4997.401351297305, 5186.6369354144845, 5805.535557155417, 5822.359540450547, 5860.736266868002, 1491.5823569400586, 1492.940282388714, 1651.6118712102646, 1655.42887703742, 1661.8450177127022, 1063.1547919403938, 1182.5447202826974, 1223.7736018836545, 1240.9379531023594, 1261.035307989389, 5039.97480012605, 5318.0, 5468.316460442445, 5509.862253443696, 5547.889042219133, 5125.5136010556525, 5536.889262214733, 5975.52195824333, 6061.848453788691, 6095.35998720128, 4723.48041715411, 4862.82980095702, 5432.266644003088, 5503.807366742145, 5539.002979463655, 2632.815233145054, 3140.4661207594763, 3163.430582495127, 3173.333599944038, 3179.9296899968076, 5628.605997580212, 6010.278766548042, 6088.4824789893355, 6223.719932603044, 6258.624482530971, 5545.611807173459, 5804.448577385142, 6183.567150299526, 6219.844503887349, 6238.192813234063, 5678.921005009035, 5809.215755872988, 6197.5351848611535, 6334.936650633527, 6362.178606574932, 5444.863878403072, 5891.705414729332, 6089.725962331617, 6134.809898852483, 6157.6613286269685, 5115.283860259492, 5445.727713614383, 5664.433556644302, 5806.622569533136, 6061.242029941464, 5379.973100134474, 5489.483069515111, 6020.39796020412, 6396.48828093742, 6409.839754006187, 4820.662553621213, 4821.614270858253, 4830.464006396529, 4843.782029808596, 4863.927041094315, 5409.024367090265, 5416.864578385572, 5675.120356344773, 5807.593468457168, 6090.025595904634, 4863.902721945612, 4887.657863949489, 5320.866978325418, 5964.940350596438, 5982.012967860254, 5110.770015349392, 5605.859853503695, 5673.716314184192, 5830.0, 5900.586958912835, 5507.284053073075, 5540.307461567304, 5754.6547207167705, 5796.333421656564, 5817.301923769175, 5512.5589952804485, 5787.508061814793, 5891.381404952418, 5933.584649074523, 5960.58596268512, 4962.70969547913, 5091.541761241506, 5686.857828554319, 5788.536917046542, 5878.3540608629655], "value": 4442.090687113793, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [724.9746258880937, 751.872181729106, 789.3645615279701, 809.8785182222666, 867.8481265778487, 16044.598885027866, 16862.409815656454, 16934.661306773887, 17061.7440738389, 17132.0, 15563.84436155641, 15687.921560392211, 15689.686206275868, 15708.921455392707, 15716.528504144893, 943.415082648757, 971.4171497101718, 1008.9949550252238, 1012.3015119567475, 1018.5060245780791, 1003.9498025098752, 1013.9188864890791, 1017.5624481473001, 1019.2050200843354, 1019.2101121630722, 1051.4584988730771, 1068.983965240522, 1069.1393428290223, 1069.647016484562, 1070.855434516342, 1057.428988346292, 1064.1380481809758, 1064.925455218135, 1065.1265961911236, 1065.5897479470382, 782.6321628834439, 785.8939043229148, 786.6695987685185, 787.0712559180165, 788.5347644889486, 16250.18749062554, 16755.748663770053, 17279.395221167324, 17342.56643583908, 17424.738628920575, 14478.85521144796, 14625.853741462663, 14651.560453186421, 14707.19110448925, 14709.852901470955, 9523.809523809554, 10926.945365273203, 12001.51993920242, 12034.217775844541, 12219.083568732382, 3773.4528493368307, 3773.8867833965023, 3914.725969182158, 3970.06703424695, 4035.051762835731, 114477.0, 117542.8245717549, 119487.0, 120496.39751801184, 122531.77468225293, 10486.63296784624, 11555.0, 11613.883861161281, 11680.357580333164, 11724.237924534878, 23406.7659323408, 23465.88267058654, 23532.29403117909, 23538.882305588362, 23891.40271493192, 7322.890156647654, 8105.473144245605, 8377.49735015901, 8446.767559717624, 8733.30133589324, 4461.933071003938, 4528.547145285511, 4564.726116433025, 4574.405327307429, 4709.011107667359, 3621.3843646580144, 3684.7236457265276, 3685.299793039314, 3716.8141592920247, 3734.9439758403646, 688.5627626457135, 689.3279052923359, 696.571608460793, 703.145678001231, 706.2972342519226, 700.3311837195466, 728.9088863892014, 735.9300866417682, 747.234085062815, 788.9211078892065, 10547.57809687604, 10802.67591972242, 10954.56181752721, 11063.612773552886, 11233.887661123448, 713.678844519968, 715.5706576054325, 727.6465773660987, 760.2967255288836, 760.9429292802952, 4664.883377915579, 4709.95290047102, 4720.33915251865, 4769.499202583748, 4772.266751844117, 105786.47106764418, 108257.0, 109650.45174774075, 113127.0, 114469.28296075565, 95030.0, 96036.0, 96786.06427871402, 98418.0, 98738.50630746943, 77805.61097194478, 78154.99999999886, 80008.0, 80019.0, 80859.78710319349, 9005.369624126248, 9059.184673379428, 9072.773180670536, 9073.546322683707, 9076.546172691207, 43069.784651077796, 47112.29331559961, 52450.16424425127, 53391.46608533865, 54160.187597185286, 67147.3285267141, 68820.6558967202, 69457.65271173611, 69765.0, 70461.29538704548, 10814.945925270324, 10884.836727448936, 10904.836427453753, 11015.0, 11089.88910110921, 13069.215847049207, 13175.945126814759, 13401.994850386065, 13490.52783152585, 13595.252261125734, 12330.445129969352, 12575.0, 12649.494020239088, 12732.299723515296, 13154.60536183916, 12232.266064036186, 12516.499340026661, 12606.55877044299, 12907.967362611156, 13070.0, 12000.939995299968, 12019.75960480803, 12394.070444716503, 12755.553555625509, 12859.16415432994, 9046.864297035681, 9243.21432678203, 9400.200982916254, 9481.051894810464, 9644.373115747454, 10148.847767283645, 10751.6236931707, 10761.73095672583, 10794.0, 10803.135749139892], "value": 7812.111735022974, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1562.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 140.0, 118.0, 119.0, 119.0, 120.0, 119.0, 119.0, 122.0, 118.0], "value": 124.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 138.0, 113.0, 146.0, 112.0, 99.0, 92.0, 139.0, 160.0, 137.0], "value": 129.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 197.0, 179.0, 278.0, 273.0, 203.0, 196.0, 197.0, 195.0, 195.0], "value": 211.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 62.0, 92.0, 61.0, 60.0, 60.0, 58.0, 74.0, 62.0], "value": 66.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 70.0, 85.0, 71.0, 71.0, 71.0, 82.0, 71.0, 70.0, 83.0], "value": 76.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 91.0, 133.0, 119.0, 87.0, 104.0, 87.0, 88.0, 89.0], "value": 97.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 93.0, 152.0, 156.0, 95.0, 95.0, 96.0, 101.0, 155.0, 155.0], "value": 124.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 92.0, 91.0, 97.0, 143.0, 91.0, 92.0, 92.0, 90.0, 94.0], "value": 97.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 81.0, 83.0, 66.0, 61.0, 66.0, 97.0, 107.0, 108.0, 88.0], "value": 82.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 64.0, 61.0, 62.0, 62.0, 58.0, 57.0, 59.0, 57.0, 59.0], "value": 59.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 82.0, 84.0, 83.0, 84.0, 83.0, 87.0, 85.0, 93.0], "value": 84.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 151.0, 133.0, 135.0, 142.0, 121.0, 153.0, 154.0, 203.0, 151.0], "value": 149.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [227.0, 227.0, 208.0, 227.0, 176.0, 181.0, 162.0, 161.0, 167.0, 164.0], "value": 190.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 67.0, 57.0, 60.0, 83.0, 80.0, 56.0, 69.0, 85.0], "value": 68.7, "unit": "score"}]}]} 15:20:36 INFO - Return code: 0 15:20:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:20:36 ERROR - # TBPL SUCCESS # 15:20:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:20:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:20:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:20:36 INFO - 2017-05-08 15:20:36.586 screenresolution[1911:11531] starting screenresolution argv=screenresolution get 15:20:36 INFO - 2017-05-08 15:20:36.589 screenresolution[1911:11531] Display 0: 1600x1200x32@60 15:20:36 INFO - 2017-05-08 15:20:36.595 screenresolution[1912:11534] starting screenresolution argv=screenresolution list 15:20:36 INFO - Available Modes on Display 0 15:20:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:20:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:20:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:20:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:20:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:20:36 INFO - Intel Iris: 15:20:36 INFO - Chipset Model: Intel Iris 15:20:36 INFO - Type: GPU 15:20:36 INFO - Bus: Built-In 15:20:36 INFO - VRAM (Dynamic, Max): 1536 MB 15:20:36 INFO - Vendor: Intel (0x8086) 15:20:36 INFO - Device ID: 0x0a2e 15:20:36 INFO - Revision ID: 0x0009 15:20:36 INFO - Displays: 15:20:36 INFO - Display: 15:20:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:20:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:20:36 INFO - Display Serial Number: 1600x1200 60 15:20:36 INFO - Main Display: Yes 15:20:36 INFO - Mirror: Off 15:20:36 INFO - Online: Yes 15:20:36 INFO - Rotation: Supported 15:20:36 INFO - Return code: 0 15:20:36 INFO - Running post-action listener: _resource_record_post_action 15:20:36 INFO - [mozharness: 2017-05-08 22:20:36.829178Z] Finished run-tests step (success) 15:20:36 INFO - Running post-run listener: _resource_record_post_run 15:20:36 INFO - Total resource usage - Wall time: 1189s; CPU: 55.0%; Read bytes: 3589120; Write bytes: 422410752; Read time: 33; Write time: 1819 15:20:36 INFO - TinderboxPrint: CPU usage
54.6% 15:20:36 INFO - TinderboxPrint: I/O read bytes / time
3,589,120 / 33 15:20:36 INFO - TinderboxPrint: I/O write bytes / time
422,410,752 / 1,819 15:20:36 INFO - TinderboxPrint: CPU idle
2,160.3 (45.4%) 15:20:36 INFO - TinderboxPrint: CPU system
955.5 (20.1%) 15:20:36 INFO - TinderboxPrint: CPU user
1,641.9 (34.5%) 15:20:36 INFO - TinderboxPrint: Swap in / out
126,111,744 / 0 15:20:36 INFO - install - Wall time: 15s; CPU: 53.0%; Read bytes: 112599552; Write bytes: 108332544; Read time: 9967; Write time: 287 15:20:36 INFO - run-tests - Wall time: 1176s; CPU: 55.0%; Read bytes: 3589120; Write bytes: 296387584; Read time: 33; Write time: 1478 15:20:37 INFO - Running post-run listener: _upload_blobber_files 15:20:37 INFO - Blob upload gear active. 15:20:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:20:37 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 15:20:37 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'] 15:20:37 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 15:20:37 INFO - (blobuploader) - INFO - Open directory for files ... 15:20:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:20:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:20:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:20:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:20:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:20:39 INFO - (blobuploader) - INFO - Done attempting. 15:20:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:20:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:20:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:20:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:20:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:20:40 INFO - (blobuploader) - INFO - Done attempting. 15:20:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:20:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:20:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:20:42 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:20:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:20:42 INFO - (blobuploader) - INFO - Done attempting. 15:20:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:20:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:20:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:20:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:20:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:20:44 INFO - (blobuploader) - INFO - Done attempting. 15:20:44 INFO - (blobuploader) - INFO - Iteration through files over. 15:20:44 INFO - Return code: 0 15:20:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:20:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:20:44 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/20b64adb73f287d6fb2568905206bf26fd99379d2420cadf22a7e25f8b0f54aa5c2ee0ea4c73428b76054d022d78f51bcd48b1cc2ae6f3c3fdad62f99fef7b95", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ec9d7311a80f892aa0421b5281eee1518cc06d77008f3a9f15d9eafa6a882d8e77a15f8a5b326c9ed7163e4ea5bd703e30479f57fa37c63b6c65f1f921d3be2d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7f1eca34532db077301769fd6e0f088d58e9ec2e942f57c5eb9c5f1b85bc1e06254b4b45e2412629f8bff282fbc0863bc5e8fb197c5c041a762d87729f7c9251", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5f904c9f787604d00a2f4726b2b0f94dc4f2069cca10323150693e6fdcb2bc426c690476980ef07605de974c8d5a2757a52772d0e09a8be9dffbaab2bed7a9b2"} 15:20:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:20:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:20:44 INFO - Contents: 15:20:44 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/20b64adb73f287d6fb2568905206bf26fd99379d2420cadf22a7e25f8b0f54aa5c2ee0ea4c73428b76054d022d78f51bcd48b1cc2ae6f3c3fdad62f99fef7b95", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ec9d7311a80f892aa0421b5281eee1518cc06d77008f3a9f15d9eafa6a882d8e77a15f8a5b326c9ed7163e4ea5bd703e30479f57fa37c63b6c65f1f921d3be2d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7f1eca34532db077301769fd6e0f088d58e9ec2e942f57c5eb9c5f1b85bc1e06254b4b45e2412629f8bff282fbc0863bc5e8fb197c5c041a762d87729f7c9251", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5f904c9f787604d00a2f4726b2b0f94dc4f2069cca10323150693e6fdcb2bc426c690476980ef07605de974c8d5a2757a52772d0e09a8be9dffbaab2bed7a9b2"} 15:20:44 INFO - Running post-run listener: copy_logs_to_upload_dir 15:20:44 INFO - Copying logs to upload dir... 15:20:44 INFO - mkdir: /builds/slave/test/build/upload/logs 15:20:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.558306 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2017-05-08 15:20:44.664350) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-08 15:20:44.669711) ========= 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.OdLezxEtZq/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.m94wEDEIpS/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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/20b64adb73f287d6fb2568905206bf26fd99379d2420cadf22a7e25f8b0f54aa5c2ee0ea4c73428b76054d022d78f51bcd48b1cc2ae6f3c3fdad62f99fef7b95", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ec9d7311a80f892aa0421b5281eee1518cc06d77008f3a9f15d9eafa6a882d8e77a15f8a5b326c9ed7163e4ea5bd703e30479f57fa37c63b6c65f1f921d3be2d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7f1eca34532db077301769fd6e0f088d58e9ec2e942f57c5eb9c5f1b85bc1e06254b4b45e2412629f8bff282fbc0863bc5e8fb197c5c041a762d87729f7c9251", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5f904c9f787604d00a2f4726b2b0f94dc4f2069cca10323150693e6fdcb2bc426c690476980ef07605de974c8d5a2757a52772d0e09a8be9dffbaab2bed7a9b2"} build_url:https://queue.taskcluster.net/v1/task/JVq27oAoTKeBCSFyq5sM-Q/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010622 build_url: 'https://queue.taskcluster.net/v1/task/JVq27oAoTKeBCSFyq5sM-Q/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/20b64adb73f287d6fb2568905206bf26fd99379d2420cadf22a7e25f8b0f54aa5c2ee0ea4c73428b76054d022d78f51bcd48b1cc2ae6f3c3fdad62f99fef7b95", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ec9d7311a80f892aa0421b5281eee1518cc06d77008f3a9f15d9eafa6a882d8e77a15f8a5b326c9ed7163e4ea5bd703e30479f57fa37c63b6c65f1f921d3be2d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7f1eca34532db077301769fd6e0f088d58e9ec2e942f57c5eb9c5f1b85bc1e06254b4b45e2412629f8bff282fbc0863bc5e8fb197c5c041a762d87729f7c9251", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5f904c9f787604d00a2f4726b2b0f94dc4f2069cca10323150693e6fdcb2bc426c690476980ef07605de974c8d5a2757a52772d0e09a8be9dffbaab2bed7a9b2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-08 15:20:44.704001) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-08 15:20:44.704392) ========= 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.OdLezxEtZq/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.m94wEDEIpS/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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.005188 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-08 15:20:44.753269) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-08 15:20:44.753586) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-08 15:20:44.753915) ========= ========= Total master_lag: 0.19 =========