builder: graphics_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0011 starttime: 1482294631.7 results: success (0) buildid: 20161220181453 builduid: 910dc4cca4444dee88740c9f6d3a8296 revision: 64fc4a634e30bca2a9fb672c97b36e46162572c4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-20 20:30:31.700463) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-20 20:30:31.700943) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-20 20:30:31.701221) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-20 20:30:31.727864) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 20:30:31.728246) ========= 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.7LOscDetMk/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.XTqUNlknZQ/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 --2016-12-20 20:30:31-- 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.33M=0.001s 2016-12-20 20:30:31 (8.33 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.139660 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 20:30:31.892677) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 20:30:31.892985) ========= 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.7LOscDetMk/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.XTqUNlknZQ/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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.020700 ========= master_lag: 0.31 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 20:30:32.218703) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-20 20:30:32.219074) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 64fc4a634e30bca2a9fb672c97b36e46162572c4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 64fc4a634e30bca2a9fb672c97b36e46162572c4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7LOscDetMk/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.XTqUNlknZQ/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 2016-12-20 20:30:32,297 truncating revision to first 12 chars 2016-12-20 20:30:32,297 Setting DEBUG logging. 2016-12-20 20:30:32,297 attempt 1/10 2016-12-20 20:30:32,297 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/64fc4a634e30?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-20 20:30:33,115 unpacking tar archive at: graphics-64fc4a634e30/testing/mozharness/ program finished with exit code 0 elapsedTime=1.055349 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-20 20:30:33.297744) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-20 20:30:33.298035) ========= script_repo_revision: 64fc4a634e30bca2a9fb672c97b36e46162572c4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-20 20:30:33.298431) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-20 20:30:33.298750) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-20 20:30:33.318552) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-12-20 20:30:33.318965) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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', 'Graphics', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7LOscDetMk/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.XTqUNlknZQ/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 20:30:33 INFO - MultiFileLogger online at 20161220 20:30:33 in /builds/slave/test 20:30:33 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 20:30:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:30:33 INFO - {'append_to_log': False, 20:30:33 INFO - 'base_work_dir': '/builds/slave/test', 20:30:33 INFO - 'blob_upload_branch': 'Graphics', 20:30:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:30:33 INFO - 'branch': 'Graphics', 20:30:33 INFO - 'buildbot_json_path': 'buildprops.json', 20:30:33 INFO - 'config_files': ('talos/mac_config.py',), 20:30:33 INFO - 'default_actions': ('clobber', 20:30:33 INFO - 'read-buildbot-config', 20:30:33 INFO - 'download-and-extract', 20:30:33 INFO - 'populate-webroot', 20:30:33 INFO - 'create-virtualenv', 20:30:33 INFO - 'install', 20:30:33 INFO - 'run-tests'), 20:30:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:30:33 INFO - 'download_minidump_stackwalk': True, 20:30:33 INFO - 'download_symbols': 'ondemand', 20:30:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:30:33 INFO - 'tooltool.py': '/tools/tooltool.py', 20:30:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:30:33 INFO - '/tools/misc-python/virtualenv.py')}, 20:30:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:30:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:30:33 INFO - 'installer_path': 'installer.exe', 20:30:33 INFO - 'log_level': 'info', 20:30:33 INFO - 'log_name': 'talos', 20:30:33 INFO - 'log_to_console': True, 20:30:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:30:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:30:33 INFO - 'opt_config_files': (), 20:30:33 INFO - 'pip_index': False, 20:30:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:30:33 INFO - 'cmd': ('bash', 20:30:33 INFO - '-c', 20:30:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:30:33 INFO - 'enabled': True, 20:30:33 INFO - 'halt_on_failure': False, 20:30:33 INFO - 'name': 'check_screen_resolution'},), 20:30:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:30:33 INFO - 'cmd': ('bash', 20:30:33 INFO - '-c', 20:30:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:30:33 INFO - 'enabled': True, 20:30:33 INFO - 'halt_on_failure': False, 20:30:33 INFO - 'name': 'check_screen_resolution'},), 20:30:33 INFO - 'run_cmd_checks_enabled': True, 20:30:33 INFO - 'sps_profile': False, 20:30:33 INFO - 'sps_profile_interval': 0, 20:30:33 INFO - 'suite': 'dromaeojs-e10s', 20:30:33 INFO - 'system_bits': '32', 20:30:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:30:33 INFO - 'title': 't-yosemite-r7-0011', 20:30:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:30:33 INFO - 'use_talos_json': True, 20:30:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:30:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:30:33 INFO - 'work_dir': 'build'} 20:30:33 INFO - [mozharness: 2016-12-21 04:30:33.525012Z] Running clobber step. 20:30:33 INFO - Running pre-action listener: _resource_record_pre_action 20:30:33 INFO - Running main action method: clobber 20:30:33 INFO - rmtree: /builds/slave/test/build 20:30:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:30:35 INFO - Running post-action listener: _resource_record_post_action 20:30:35 INFO - [mozharness: 2016-12-21 04:30:35.132824Z] Finished clobber step (success) 20:30:35 INFO - [mozharness: 2016-12-21 04:30:35.132954Z] Running read-buildbot-config step. 20:30:35 INFO - Running pre-action listener: _resource_record_pre_action 20:30:35 INFO - Running main action method: read_buildbot_config 20:30:35 INFO - Using buildbot properties: 20:30:35 INFO - { 20:30:35 INFO - "project": "", 20:30:35 INFO - "product": "firefox", 20:30:35 INFO - "script_repo_revision": "default", 20:30:35 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs-e10s", 20:30:35 INFO - "repository": "", 20:30:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs-e10s", 20:30:35 INFO - "buildid": "20161220181453", 20:30:35 INFO - "pgo_build": "False", 20:30:35 INFO - "basedir": "/builds/slave/test", 20:30:35 INFO - "buildnumber": 0, 20:30:35 INFO - "slavename": "t-yosemite-r7-0011", 20:30:35 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 20:30:35 INFO - "platform": "yosemite_r7", 20:30:35 INFO - "scheduler": "tests-graphics-macosx64-talos-8-3600", 20:30:35 INFO - "branch": "graphics", 20:30:35 INFO - "repo_path": "projects/graphics", 20:30:35 INFO - "revision": "64fc4a634e30bca2a9fb672c97b36e46162572c4", 20:30:35 INFO - "stage_platform": "macosx64", 20:30:35 INFO - "builduid": "910dc4cca4444dee88740c9f6d3a8296", 20:30:35 INFO - "slavebuilddir": "test" 20:30:35 INFO - } 20:30:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/MK8ByD-8S8Ke-Ab9Sr7Vqg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 20:30:35 INFO - Running post-action listener: _resource_record_post_action 20:30:35 INFO - [mozharness: 2016-12-21 04:30:35.136195Z] Finished read-buildbot-config step (success) 20:30:35 INFO - [mozharness: 2016-12-21 04:30:35.136296Z] Running download-and-extract step. 20:30:35 INFO - Running pre-action listener: _resource_record_pre_action 20:30:35 INFO - Running main action method: download_and_extract 20:30:35 INFO - mkdir: /builds/slave/test/build/tests 20:30: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')]} 20:30:35 INFO - trying https://queue.taskcluster.net/v1/task/MK8ByD-8S8Ke-Ab9Sr7Vqg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 20:30:35 INFO - Downloading https://queue.taskcluster.net/v1/task/MK8ByD-8S8Ke-Ab9Sr7Vqg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 20:30:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MK8ByD-8S8Ke-Ab9Sr7Vqg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 20:30:36 INFO - Downloaded 1199 bytes. 20:30:36 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 20:30:36 INFO - Using the following test package requirements: 20:30:36 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 20:30:36 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:30:36 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 20:30:36 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:30:36 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 20:30:36 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:30:36 INFO - u'jsshell-mac.zip'], 20:30:36 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:30:36 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 20:30:36 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 20:30:36 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:30:36 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 20:30:36 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:30:36 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 20:30:36 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:30:36 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 20:30:36 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:30:36 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 20:30:36 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:30:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MK8ByD-8S8Ke-Ab9Sr7Vqg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 20:30:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MK8ByD-8S8Ke-Ab9Sr7Vqg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:30:36 INFO - Fetch https://queue.taskcluster.net/v1/task/MK8ByD-8S8Ke-Ab9Sr7Vqg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 20:30:37 INFO - Expected file size: 16276036 20:30:37 INFO - Obtained file size: 16276036 20:30:44 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 20:30:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MK8ByD-8S8Ke-Ab9Sr7Vqg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 20:30:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MK8ByD-8S8Ke-Ab9Sr7Vqg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:30:44 INFO - Fetch https://queue.taskcluster.net/v1/task/MK8ByD-8S8Ke-Ab9Sr7Vqg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 20:30:45 INFO - Expected file size: 16276036 20:30:45 INFO - Obtained file size: 16276036 20:30:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MK8ByD-8S8Ke-Ab9Sr7Vqg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 20:30:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MK8ByD-8S8Ke-Ab9Sr7Vqg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 20:30:51 INFO - Fetch https://queue.taskcluster.net/v1/task/MK8ByD-8S8Ke-Ab9Sr7Vqg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 20:30:52 INFO - Expected file size: 14409734 20:30:52 INFO - Obtained file size: 14409734 20:30:53 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')]} 20:30:53 INFO - trying https://queue.taskcluster.net/v1/task/MK8ByD-8S8Ke-Ab9Sr7Vqg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 20:30:53 INFO - Downloading https://queue.taskcluster.net/v1/task/MK8ByD-8S8Ke-Ab9Sr7Vqg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 20:30:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MK8ByD-8S8Ke-Ab9Sr7Vqg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 20:30:56 INFO - Downloaded 69658828 bytes. 20:30:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MK8ByD-8S8Ke-Ab9Sr7Vqg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 20:30:56 INFO - mkdir: /builds/slave/test/properties 20:30:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:30:56 INFO - Writing to file /builds/slave/test/properties/build_url 20:30:56 INFO - Contents: 20:30:56 INFO - build_url:https://queue.taskcluster.net/v1/task/MK8ByD-8S8Ke-Ab9Sr7Vqg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 20:30:57 INFO - Running post-action listener: _resource_record_post_action 20:30:57 INFO - Running post-action listener: set_extra_try_arguments 20:30:57 INFO - [mozharness: 2016-12-21 04:30:57.179701Z] Finished download-and-extract step (success) 20:30:57 INFO - [mozharness: 2016-12-21 04:30:57.179843Z] Running populate-webroot step. 20:30:57 INFO - Running pre-action listener: _resource_record_pre_action 20:30:57 INFO - Running main action method: populate_webroot 20:30:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:30:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:30:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:30:57 INFO - '--tppagecycles', 20:30:57 INFO - '7'], 20:30:57 INFO - 'tests': ['tsvgm']}}, 20:30:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:30:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:30:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:30:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:30:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:30:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:30:57 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 20:30:57 INFO - 'talos_options': ['--disable-e10s'], 20:30:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:30:57 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:30:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:30:57 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 20:30:57 INFO - 'talos_options': ['--disable-e10s'], 20:30:57 INFO - 'tests': ['damp', 'tps']}, 20:30:57 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:30:57 INFO - 'tests': ['damp', 'tps']}, 20:30:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:30:57 INFO - 'tests': ['dromaeo_dom']}, 20:30:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:30:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:30:57 INFO - 'tests': ['basic_compositor_video']}, 20:30:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:30:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:30:57 INFO - 'tests': ['a11yr', 20:30:57 INFO - 'ts_paint', 20:30:57 INFO - 'tpaint', 20:30:57 INFO - 'sessionrestore', 20:30:57 INFO - 'sessionrestore_no_auto_restore', 20:30:57 INFO - 'tabpaint']}, 20:30:57 INFO - 'other-e10s': {'tests': ['a11yr', 20:30:57 INFO - 'ts_paint', 20:30:57 INFO - 'tpaint', 20:30:57 INFO - 'sessionrestore', 20:30:57 INFO - 'sessionrestore_no_auto_restore', 20:30:57 INFO - 'tabpaint']}, 20:30:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:30:57 INFO - 'ts_paint', 20:30:57 INFO - 'tpaint', 20:30:57 INFO - 'sessionrestore', 20:30:57 INFO - 'sessionrestore_no_auto_restore', 20:30:57 INFO - 'tabpaint']}, 20:30:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:30:57 INFO - 'ts_paint', 20:30:57 INFO - 'tpaint', 20:30:57 INFO - 'sessionrestore', 20:30:57 INFO - 'sessionrestore_no_auto_restore', 20:30:57 INFO - 'tabpaint']}, 20:30:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:30:57 INFO - 'tests': ['a11yr', 20:30:57 INFO - 'ts_paint', 20:30:57 INFO - 'tpaint', 20:30:57 INFO - 'sessionrestore', 20:30:57 INFO - 'sessionrestore_no_auto_restore', 20:30:57 INFO - 'tabpaint']}, 20:30:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:30:57 INFO - 'tests': ['a11yr', 20:30:57 INFO - 'ts_paint', 20:30:57 INFO - 'tpaint', 20:30:57 INFO - 'sessionrestore', 20:30:57 INFO - 'sessionrestore_no_auto_restore', 20:30:57 INFO - 'tabpaint']}, 20:30:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:30:57 INFO - 'tests': ['tsvgx', 20:30:57 INFO - 'tsvgr_opacity', 20:30:57 INFO - 'tart', 20:30:57 INFO - 'tscrollx', 20:30:57 INFO - 'cart', 20:30:57 INFO - 'tsvg_static']}, 20:30:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:30:57 INFO - 'tsvgr_opacity', 20:30:57 INFO - 'tart', 20:30:57 INFO - 'tscrollx', 20:30:57 INFO - 'cart', 20:30:57 INFO - 'tsvg_static']}, 20:30:57 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 20:30:57 INFO - 'talos_options': ['--disable-e10s'], 20:30:57 INFO - 'tests': ['tp5o']}, 20:30:57 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:30:57 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 20:30:57 INFO - 'talos_options': ['--disable-e10s', 20:30:57 INFO - '--xperf_path', 20:30:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:30:57 INFO - 'tests': ['tp5n']}, 20:30:57 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:30:57 INFO - 'talos_options': ['--xperf_path', 20:30:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:30:57 INFO - 'tests': ['tp5n']}}, 20:30:57 INFO - 'talos.zip': {'path': '', 20:30:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:30:57 INFO - Running post-action listener: _resource_record_post_action 20:30:57 INFO - [mozharness: 2016-12-21 04:30:57.198734Z] Finished populate-webroot step (success) 20:30:57 INFO - [mozharness: 2016-12-21 04:30:57.198871Z] Running create-virtualenv step. 20:30:57 INFO - Running pre-action listener: _resource_record_pre_action 20:30:57 INFO - Running main action method: create_virtualenv 20:30:57 INFO - Creating virtualenv /builds/slave/test/build/venv 20:30:57 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 20:30:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:30:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:30:57 INFO - Using real prefix '/tools/python27' 20:30:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:30:58 INFO - Installing distribute.............................................................................................................................................................................................done. 20:31:00 INFO - Installing pip.................done. 20:31:00 INFO - Return code: 0 20:31:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:31:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:31:01 INFO - Reading from file tmpfile_stdout 20:31:01 INFO - Output received: 20:31:01 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) 20:31:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:31: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')]} 20:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:31:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:31: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')]} 20:31:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:31:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:31:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10d782b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d785768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9da1c645d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d560d30>, '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 0x10d487db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d555be8>, '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.7LOscDetMk/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.XTqUNlknZQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 20:31:01 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 20:31:01 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 20:31:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7LOscDetMk/Render', 20:31:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:31:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:31:01 INFO - 'HOME': '/Users/cltbld', 20:31:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:31:01 INFO - 'LOGNAME': 'cltbld', 20:31:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:31:01 INFO - 'MOZ_NO_REMOTE': '1', 20:31:01 INFO - 'NO_EM_RESTART': '1', 20:31:01 INFO - 'PAGER': '/bin/cat', 20:31:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:31:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:31:01 INFO - 'PWD': '/builds/slave/test', 20:31:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:31:01 INFO - 'SHELL': '/bin/bash', 20:31:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XTqUNlknZQ/Listeners', 20:31:01 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 20:31:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:31:01 INFO - 'USER': 'cltbld', 20:31:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:31:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:31:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:31:01 INFO - 'XPC_FLAGS': '0x0', 20:31:01 INFO - 'XPC_SERVICE_NAME': '0', 20:31:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:31:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:31:01 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 20:31:01 INFO - Cleaning up... 20:31:01 INFO - Return code: 0 20:31:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:31: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')]} 20:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:31:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:31: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')]} 20:31:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:31:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:31:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10d782b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d785768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9da1c645d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d560d30>, '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 0x10d487db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d555be8>, '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.7LOscDetMk/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.XTqUNlknZQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 20:31:01 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 20:31:01 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 20:31:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7LOscDetMk/Render', 20:31:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:31:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:31:01 INFO - 'HOME': '/Users/cltbld', 20:31:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:31:01 INFO - 'LOGNAME': 'cltbld', 20:31:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:31:01 INFO - 'MOZ_NO_REMOTE': '1', 20:31:01 INFO - 'NO_EM_RESTART': '1', 20:31:01 INFO - 'PAGER': '/bin/cat', 20:31:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:31:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:31:01 INFO - 'PWD': '/builds/slave/test', 20:31:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:31:01 INFO - 'SHELL': '/bin/bash', 20:31:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XTqUNlknZQ/Listeners', 20:31:01 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 20:31:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:31:01 INFO - 'USER': 'cltbld', 20:31:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:31:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:31:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:31:01 INFO - 'XPC_FLAGS': '0x0', 20:31:01 INFO - 'XPC_SERVICE_NAME': '0', 20:31:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:31:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:31:01 INFO - Downloading/unpacking psutil>=3.1.1 20:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:31:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:31:01 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 20:31:01 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 20:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:31:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:31:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:31:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:31:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:31:05 INFO - Installing collected packages: psutil 20:31:05 INFO - Running setup.py install for psutil 20:31:05 INFO - building 'psutil._psutil_osx' extension 20:31:05 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 20:31:05 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 20:31:05 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 20:31:05 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 20:31:05 INFO - building 'psutil._psutil_posix' extension 20:31:05 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 20:31:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:31:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:31:05 INFO - ^ 20:31:05 INFO - 1 warning generated. 20:31:05 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 20:31:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:31:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:31:06 INFO - Successfully installed psutil 20:31:06 INFO - Cleaning up... 20:31:06 INFO - Return code: 0 20:31:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:31:06 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')]} 20:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:31:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:31:06 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')]} 20:31:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:31:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:31:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10d782b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d785768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9da1c645d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d560d30>, '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 0x10d487db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d555be8>, '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.7LOscDetMk/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.XTqUNlknZQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 20:31:06 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 20:31:06 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 20:31:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7LOscDetMk/Render', 20:31:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:31:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:31:06 INFO - 'HOME': '/Users/cltbld', 20:31:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:31:06 INFO - 'LOGNAME': 'cltbld', 20:31:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:31:06 INFO - 'MOZ_NO_REMOTE': '1', 20:31:06 INFO - 'NO_EM_RESTART': '1', 20:31:06 INFO - 'PAGER': '/bin/cat', 20:31:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:31:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:31:06 INFO - 'PWD': '/builds/slave/test', 20:31:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:31:06 INFO - 'SHELL': '/bin/bash', 20:31:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XTqUNlknZQ/Listeners', 20:31:06 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 20:31:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:31:06 INFO - 'USER': 'cltbld', 20:31:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:31:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:31:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:31:06 INFO - 'XPC_FLAGS': '0x0', 20:31:06 INFO - 'XPC_SERVICE_NAME': '0', 20:31:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:31:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:31:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:31:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:31:06 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 20:31:06 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 20:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:31:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:31:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:31:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:31:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:31:09 INFO - Installing collected packages: mozsystemmonitor 20:31:09 INFO - Running setup.py install for mozsystemmonitor 20:31:09 INFO - Successfully installed mozsystemmonitor 20:31:09 INFO - Cleaning up... 20:31:09 INFO - Return code: 0 20:31:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:31:09 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')]} 20:31:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:31:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:31:09 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')]} 20:31:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:31:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:31:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10d782b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d785768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9da1c645d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d560d30>, '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 0x10d487db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d555be8>, '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.7LOscDetMk/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.XTqUNlknZQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 20:31:09 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 20:31:09 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 20:31:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7LOscDetMk/Render', 20:31:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:31:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:31:09 INFO - 'HOME': '/Users/cltbld', 20:31:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:31:09 INFO - 'LOGNAME': 'cltbld', 20:31:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:31:09 INFO - 'MOZ_NO_REMOTE': '1', 20:31:09 INFO - 'NO_EM_RESTART': '1', 20:31:09 INFO - 'PAGER': '/bin/cat', 20:31:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:31:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:31:09 INFO - 'PWD': '/builds/slave/test', 20:31:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:31:09 INFO - 'SHELL': '/bin/bash', 20:31:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XTqUNlknZQ/Listeners', 20:31:09 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 20:31:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:31:09 INFO - 'USER': 'cltbld', 20:31:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:31:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:31:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:31:09 INFO - 'XPC_FLAGS': '0x0', 20:31:09 INFO - 'XPC_SERVICE_NAME': '0', 20:31:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:31:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:31:09 INFO - Downloading/unpacking jsonschema==2.5.1 20:31:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:31:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:31:09 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 20:31:09 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 20:31:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:31:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:31:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:31:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:31:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:31:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:31:12 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 20:31:12 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 20:31:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:31:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:31:12 INFO - Downloading functools32-3.2.3-2.tar.gz 20:31:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:31:12 INFO - warning: no files found matching '*.txt' 20:31:12 INFO - no previously-included directories found matching 'build' 20:31:12 INFO - no previously-included directories found matching 'dist' 20:31:12 INFO - no previously-included directories found matching '.git*' 20:31:12 INFO - Installing collected packages: jsonschema, functools32 20:31:12 INFO - Running setup.py install for functools32 20:31:12 INFO - warning: no files found matching '*.txt' 20:31:12 INFO - no previously-included directories found matching 'build' 20:31:12 INFO - no previously-included directories found matching 'dist' 20:31:12 INFO - no previously-included directories found matching '.git*' 20:31:12 INFO - Successfully installed jsonschema functools32 20:31:12 INFO - Cleaning up... 20:31:13 INFO - Return code: 0 20:31:13 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:31:13 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')]} 20:31:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:31:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:31:13 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')]} 20:31:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:31:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:31:13 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 0x10d782b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d785768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9da1c645d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d560d30>, '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 0x10d487db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d555be8>, '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.7LOscDetMk/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.XTqUNlknZQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 20:31:13 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 20:31:13 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 20:31:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7LOscDetMk/Render', 20:31:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:31:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:31:13 INFO - 'HOME': '/Users/cltbld', 20:31:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:31:13 INFO - 'LOGNAME': 'cltbld', 20:31:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:31:13 INFO - 'MOZ_NO_REMOTE': '1', 20:31:13 INFO - 'NO_EM_RESTART': '1', 20:31:13 INFO - 'PAGER': '/bin/cat', 20:31:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:31:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:31:13 INFO - 'PWD': '/builds/slave/test', 20:31:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:31:13 INFO - 'SHELL': '/bin/bash', 20:31:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XTqUNlknZQ/Listeners', 20:31:13 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 20:31:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:31:13 INFO - 'USER': 'cltbld', 20:31:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:31:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:31:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:31:13 INFO - 'XPC_FLAGS': '0x0', 20:31:13 INFO - 'XPC_SERVICE_NAME': '0', 20:31:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:31:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:31:14 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:31:14 INFO - Cleaning up... 20:31:14 INFO - Return code: 0 20:31:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:31:14 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')]} 20:31:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:31:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:31:14 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')]} 20:31:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:31:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:31:14 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 0x10d782b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d785768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9da1c645d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d560d30>, '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 0x10d487db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d555be8>, '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.7LOscDetMk/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.XTqUNlknZQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 20:31:14 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 20:31:14 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 20:31:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7LOscDetMk/Render', 20:31:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:31:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:31:14 INFO - 'HOME': '/Users/cltbld', 20:31:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:31:14 INFO - 'LOGNAME': 'cltbld', 20:31:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:31:14 INFO - 'MOZ_NO_REMOTE': '1', 20:31:14 INFO - 'NO_EM_RESTART': '1', 20:31:14 INFO - 'PAGER': '/bin/cat', 20:31:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:31:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:31:14 INFO - 'PWD': '/builds/slave/test', 20:31:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:31:14 INFO - 'SHELL': '/bin/bash', 20:31:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XTqUNlknZQ/Listeners', 20:31:14 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 20:31:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:31:14 INFO - 'USER': 'cltbld', 20:31:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:31:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:31:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:31:14 INFO - 'XPC_FLAGS': '0x0', 20:31:14 INFO - 'XPC_SERVICE_NAME': '0', 20:31:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:31:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:31:14 INFO - Downloading/unpacking blobuploader==1.2.4 20:31:14 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 20:31:14 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 20:31:14 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 20:31:14 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 20:31:14 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 20:31:14 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 20:31:17 INFO - Downloading blobuploader-1.2.4.tar.gz 20:31:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:31:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:31:18 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 20:31:18 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 20:31:18 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 20:31:18 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 20:31:18 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 20:31:18 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 20:31:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:31:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:31:18 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 20:31:18 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 20:31:18 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 20:31:18 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 20:31:18 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 20:31:18 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 20:31:19 INFO - Downloading docopt-0.6.1.tar.gz 20:31:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:31:19 INFO - Installing collected packages: blobuploader, requests, docopt 20:31:19 INFO - Running setup.py install for blobuploader 20:31:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:31:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:31:19 INFO - Running setup.py install for requests 20:31:19 INFO - Running setup.py install for docopt 20:31:20 INFO - Successfully installed blobuploader requests docopt 20:31:20 INFO - Cleaning up... 20:31:20 INFO - Return code: 0 20:31:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:31:20 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')]} 20:31:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:31:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:31:20 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')]} 20:31:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:31:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:31:20 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 0x10d782b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d785768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9da1c645d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d560d30>, '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 0x10d487db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d555be8>, '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.7LOscDetMk/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.XTqUNlknZQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 20:31:20 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 20:31:20 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 20:31:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7LOscDetMk/Render', 20:31:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:31:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:31:20 INFO - 'HOME': '/Users/cltbld', 20:31:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:31:20 INFO - 'LOGNAME': 'cltbld', 20:31:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:31:20 INFO - 'MOZ_NO_REMOTE': '1', 20:31:20 INFO - 'NO_EM_RESTART': '1', 20:31:20 INFO - 'PAGER': '/bin/cat', 20:31:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:31:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:31:20 INFO - 'PWD': '/builds/slave/test', 20:31:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:31:20 INFO - 'SHELL': '/bin/bash', 20:31:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XTqUNlknZQ/Listeners', 20:31:20 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 20:31:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:31:20 INFO - 'USER': 'cltbld', 20:31:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:31:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:31:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:31:20 INFO - 'XPC_FLAGS': '0x0', 20:31:20 INFO - 'XPC_SERVICE_NAME': '0', 20:31:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:31:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:31:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:31:20 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-zKqFSG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:31:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:31:20 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-kIzTyS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:31:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:31:20 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-vtj72d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:31:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:31:20 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-jaM3qT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:31:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:31:20 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-NGGSht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:31:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:31:20 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-MNRPpW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:31:21 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-0lQyuU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:31:21 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-O_KWuF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:31:21 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-3USBrC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:31:21 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-uBLDen-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:31:21 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-BCcFb4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:31:21 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-GcMnIC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:31:21 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-8gyhwT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:31:21 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-oa3G9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:31:21 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-uA8mVo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:31:21 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-641ybF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:31:22 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-HoS6uN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:31:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:31:22 INFO - Running setup.py install for manifestparser 20:31:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:31:22 INFO - Running setup.py install for mozcrash 20:31:22 INFO - Running setup.py install for mozdebug 20:31:22 INFO - Running setup.py install for mozdevice 20:31:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:31:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:31:22 INFO - Running setup.py install for mozfile 20:31:22 INFO - Running setup.py install for mozhttpd 20:31:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:31:23 INFO - Running setup.py install for mozinfo 20:31:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:31:23 INFO - Running setup.py install for mozInstall 20:31:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:31:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:31:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:31:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:31:23 INFO - Running setup.py install for mozleak 20:31:23 INFO - Running setup.py install for mozlog 20:31:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:31:23 INFO - Running setup.py install for moznetwork 20:31:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:31:23 INFO - Running setup.py install for mozprocess 20:31:24 INFO - Running setup.py install for mozprofile 20:31:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:31:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:31:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:31:24 INFO - Running setup.py install for mozrunner 20:31:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:31:24 INFO - Running setup.py install for mozscreenshot 20:31:24 INFO - Running setup.py install for moztest 20:31:24 INFO - Running setup.py install for mozversion 20:31:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:31:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:31:24 INFO - Cleaning up... 20:31:24 INFO - Return code: 0 20:31:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:31:24 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')]} 20:31:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:31:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:31:24 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')]} 20:31:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:31:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:31:24 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 0x10d782b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d785768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9da1c645d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d560d30>, '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 0x10d487db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d555be8>, '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.7LOscDetMk/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.XTqUNlknZQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 20:31:24 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 20:31:24 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 20:31:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7LOscDetMk/Render', 20:31:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:31:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:31:24 INFO - 'HOME': '/Users/cltbld', 20:31:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:31:24 INFO - 'LOGNAME': 'cltbld', 20:31:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:31:24 INFO - 'MOZ_NO_REMOTE': '1', 20:31:24 INFO - 'NO_EM_RESTART': '1', 20:31:24 INFO - 'PAGER': '/bin/cat', 20:31:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:31:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:31:24 INFO - 'PWD': '/builds/slave/test', 20:31:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:31:24 INFO - 'SHELL': '/bin/bash', 20:31:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XTqUNlknZQ/Listeners', 20:31:24 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 20:31:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:31:24 INFO - 'USER': 'cltbld', 20:31:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:31:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:31:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:31:24 INFO - 'XPC_FLAGS': '0x0', 20:31:24 INFO - 'XPC_SERVICE_NAME': '0', 20:31:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:31:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:31:25 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-8y9hEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:31:25 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)) 20:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:31:25 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-2hbBEU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:31:25 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)) 20:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:31:25 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-1YetwB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:31:25 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)) 20:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:31:25 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-VjCYV9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:31:25 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)) 20:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:31:25 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-5lRtnl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:31:25 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)) 20:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:31:25 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-Jv4t2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:31:25 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)) 20:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:31:25 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-iCIYr5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:31:25 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)) 20:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:31:25 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-gaooNu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:31:25 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)) 20:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:31:25 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-bGjM5Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:31:26 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)) 20:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:31:26 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-i2dchc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:31:26 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)) 20:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:31:26 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-vK7mBr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:31:26 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)) 20:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:31:26 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-NtaMq4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:31:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 20:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:31:26 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-He_Z30-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:31:26 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)) 20:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:31:26 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-dnB4VY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:31:26 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)) 20:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:31:26 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-Qjl6wb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:31:26 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)) 20:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:31:26 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-X89n7w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:31:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 20:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:31:26 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-I_jNpQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:31:26 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)) 20:31:26 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)) 20:31:26 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)) 20:31:26 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)) 20:31:26 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)) 20:31:26 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)) 20:31:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:31:26 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 20:31:26 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 20:31:26 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 20:31:26 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 20:31:26 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 20:31:26 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 20:31:29 INFO - Downloading blessings-1.6.tar.gz 20:31:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:31:29 INFO - Installing collected packages: blessings 20:31:29 INFO - Running setup.py install for blessings 20:31:29 INFO - Successfully installed blessings 20:31:29 INFO - Cleaning up... 20:31:29 INFO - Return code: 0 20:31:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:31:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:31:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:31:30 INFO - Reading from file tmpfile_stdout 20:31:30 INFO - Current package versions: 20:31:30 INFO - blessings == 1.6 20:31:30 INFO - blobuploader == 1.2.4 20:31:30 INFO - docopt == 0.6.1 20:31:30 INFO - functools32 == 3.2.3-2 20:31:30 INFO - jsonschema == 2.5.1 20:31:30 INFO - manifestparser == 1.1 20:31:30 INFO - mozInstall == 1.12 20:31:30 INFO - mozcrash == 1.0 20:31:30 INFO - mozdebug == 0.1 20:31:30 INFO - mozdevice == 0.49 20:31:30 INFO - mozfile == 1.2 20:31:30 INFO - mozhttpd == 0.7 20:31:30 INFO - mozinfo == 0.9 20:31:30 INFO - mozleak == 0.1 20:31:30 INFO - mozlog == 3.4 20:31:30 INFO - moznetwork == 0.27 20:31:30 INFO - mozprocess == 0.23 20:31:30 INFO - mozprofile == 0.28 20:31:30 INFO - mozrunner == 6.13 20:31:30 INFO - mozscreenshot == 0.1 20:31:30 INFO - mozsystemmonitor == 0.3 20:31:30 INFO - moztest == 0.7 20:31:30 INFO - mozversion == 1.4 20:31:30 INFO - psutil == 3.1.1 20:31:30 INFO - requests == 1.2.3 20:31:30 INFO - wsgiref == 0.1.2 20:31:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:31:30 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')]} 20:31:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:31:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:31:30 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')]} 20:31:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:31:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:31:30 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 0x10d782b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d785768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9da1c645d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d560d30>, '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 0x10d487db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d555be8>, '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.7LOscDetMk/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.XTqUNlknZQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 20:31:30 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 20:31:30 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 20:31:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7LOscDetMk/Render', 20:31:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:31:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:31:30 INFO - 'HOME': '/Users/cltbld', 20:31:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:31:30 INFO - 'LOGNAME': 'cltbld', 20:31:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:31:30 INFO - 'MOZ_NO_REMOTE': '1', 20:31:30 INFO - 'NO_EM_RESTART': '1', 20:31:30 INFO - 'PAGER': '/bin/cat', 20:31:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:31:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:31:30 INFO - 'PWD': '/builds/slave/test', 20:31:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:31:30 INFO - 'SHELL': '/bin/bash', 20:31:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XTqUNlknZQ/Listeners', 20:31:30 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 20:31:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:31:30 INFO - 'USER': 'cltbld', 20:31:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:31:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:31:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:31:30 INFO - 'XPC_FLAGS': '0x0', 20:31:30 INFO - 'XPC_SERVICE_NAME': '0', 20:31:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:31:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:31:30 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)) 20:31:30 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)) 20:31:30 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)) 20:31:30 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)) 20:31:30 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)) 20:31:30 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)) 20:31:30 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)) 20:31:30 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)) 20:31:30 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)) 20:31:30 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)) 20:31:30 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)) 20:31:30 INFO - Cleaning up... 20:31:30 INFO - Return code: 0 20:31:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:31:30 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')]} 20:31:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:31:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:31:30 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')]} 20:31:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:31:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:31:30 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 0x10d782b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d785768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9da1c645d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d560d30>, '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 0x10d487db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d555be8>, '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.7LOscDetMk/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.XTqUNlknZQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 20:31:30 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 20:31:30 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 20:31:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7LOscDetMk/Render', 20:31:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:31:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:31:30 INFO - 'HOME': '/Users/cltbld', 20:31:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:31:30 INFO - 'LOGNAME': 'cltbld', 20:31:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:31:30 INFO - 'MOZ_NO_REMOTE': '1', 20:31:30 INFO - 'NO_EM_RESTART': '1', 20:31:30 INFO - 'PAGER': '/bin/cat', 20:31:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:31:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:31:30 INFO - 'PWD': '/builds/slave/test', 20:31:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:31:30 INFO - 'SHELL': '/bin/bash', 20:31:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XTqUNlknZQ/Listeners', 20:31:30 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 20:31:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:31:30 INFO - 'USER': 'cltbld', 20:31:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:31:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:31:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:31:30 INFO - 'XPC_FLAGS': '0x0', 20:31:30 INFO - 'XPC_SERVICE_NAME': '0', 20:31:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:31:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:31:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:31:30 INFO - Cleaning up... 20:31:30 INFO - Return code: 0 20:31:30 INFO - Running post-action listener: _resource_record_post_action 20:31:30 INFO - Running post-action listener: _start_resource_monitoring 20:31:30 INFO - Starting resource monitoring. 20:31:30 INFO - [mozharness: 2016-12-21 04:31:30.756430Z] Finished create-virtualenv step (success) 20:31:30 INFO - [mozharness: 2016-12-21 04:31:30.757496Z] Running install step. 20:31:30 INFO - Running pre-action listener: _resource_record_pre_action 20:31:30 INFO - Running main action method: install 20:31:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:31:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:31:31 INFO - Reading from file tmpfile_stdout 20:31:31 INFO - Detecting whether we're running mozinstall >=1.0... 20:31:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:31:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:31:31 INFO - Reading from file tmpfile_stdout 20:31:31 INFO - Output received: 20:31:31 INFO - Usage: mozinstall [options] installer 20:31:31 INFO - Options: 20:31:31 INFO - -h, --help show this help message and exit 20:31:31 INFO - -d DEST, --destination=DEST 20:31:31 INFO - Directory to install application into. [default: 20:31:31 INFO - "/builds/slave/test"] 20:31:31 INFO - --app=APP Application being installed. [default: firefox] 20:31:31 INFO - mkdir: /builds/slave/test/build/application 20:31:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 20:31:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 20:31:53 INFO - Reading from file tmpfile_stdout 20:31:53 INFO - Output received: 20:31:53 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:31:53 INFO - Running post-action listener: _resource_record_post_action 20:31:53 INFO - [mozharness: 2016-12-21 04:31:53.003892Z] Finished install step (success) 20:31:53 INFO - [mozharness: 2016-12-21 04:31:53.004051Z] Running run-tests step. 20:31:53 INFO - Running pre-action listener: _resource_record_pre_action 20:31:53 INFO - Running main action method: run_tests 20:31:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:31:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:31:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:31:53 INFO - 2016-12-20 20:31:53.018 screenresolution[1914:19606] starting screenresolution argv=screenresolution get 20:31:53 INFO - 2016-12-20 20:31:53.021 screenresolution[1914:19606] Display 0: 1600x1200x32@50 20:31:53 INFO - 2016-12-20 20:31:53.027 screenresolution[1915:19609] starting screenresolution argv=screenresolution list 20:31:53 INFO - Available Modes on Display 0 20:31:53 INFO - 1600x1200x32@50 1152x870x32@75 1280x1024x32@75 20:31:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:31:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:31:53 INFO - 640x480x32@60 1280x1024x32@60 1600x1200x32@60 20:31:53 INFO - 1280x960x32@60 1280x960x32@75 1344x1008x32@50 20:31:53 INFO - Graphics/Displays: 20:31:53 INFO - Intel Iris: 20:31:53 INFO - Chipset Model: Intel Iris 20:31:53 INFO - Type: GPU 20:31:53 INFO - Bus: Built-In 20:31:53 INFO - VRAM (Dynamic, Max): 1536 MB 20:31:53 INFO - Vendor: Intel (0x8086) 20:31:53 INFO - Device ID: 0x0a2e 20:31:53 INFO - Revision ID: 0x0009 20:31:53 INFO - Displays: 20:31:53 INFO - Display: 20:31:53 INFO - Resolution: 1600 x 1200 @ 50 Hz 20:31:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:31:53 INFO - Display Serial Number: 1600x1200 50 20:31:53 INFO - Main Display: Yes 20:31:53 INFO - Mirror: Off 20:31:53 INFO - Online: Yes 20:31:53 INFO - Rotation: Supported 20:31:53 INFO - Return code: 0 20:31:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:31:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:31:53 INFO - Python 2.7.3 20:31:53 INFO - Return code: 0 20:31:53 INFO - grabbing minidump binary from tooltool 20:31:53 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')]} 20:31:53 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 0x10d560d30>, '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 0x10d487db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d555be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:31:53 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 20:31:53 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 20:31:53 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 20:31:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:31:53 INFO - Return code: 0 20:31:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:31:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:31:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:31:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:31:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:31:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MK8ByD-8S8Ke-Ab9Sr7Vqg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0011', '--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 20:31:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MK8ByD-8S8Ke-Ab9Sr7Vqg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0011 --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 20:31:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7LOscDetMk/Render', 20:31:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:31:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:31:53 INFO - 'HOME': '/Users/cltbld', 20:31:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:31:53 INFO - 'LOGNAME': 'cltbld', 20:31:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:31:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:31:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:31:53 INFO - 'MOZ_NO_REMOTE': '1', 20:31:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:31:53 INFO - 'NO_EM_RESTART': '1', 20:31:53 INFO - 'PAGER': '/bin/cat', 20:31:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:31:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:31:53 INFO - 'PWD': '/builds/slave/test', 20:31:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:31:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:31:53 INFO - 'SHELL': '/bin/bash', 20:31:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XTqUNlknZQ/Listeners', 20:31:53 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 20:31:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:31:53 INFO - 'USER': 'cltbld', 20:31:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:31:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:31:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:31:53 INFO - 'XPC_FLAGS': '0x0', 20:31:53 INFO - 'XPC_SERVICE_NAME': '0', 20:31:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:31:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MK8ByD-8S8Ke-Ab9Sr7Vqg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0011', '--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 20:31:53 INFO - mozversion application_buildid: 20161220181453 20:31:53 INFO - mozversion application_changeset: 64fc4a634e30bca2a9fb672c97b36e46162572c4 20:31:53 INFO - mozversion application_display_name: Nightly 20:31:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:31:53 INFO - mozversion application_name: Firefox 20:31:53 INFO - mozversion application_remotingname: firefox 20:31:53 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 20:31:53 INFO - mozversion application_vendor: Mozilla 20:31:53 INFO - mozversion application_version: 53.0a1 20:31:53 INFO - mozversion platform_buildid: 20161220181453 20:31:53 INFO - mozversion platform_changeset: 64fc4a634e30bca2a9fb672c97b36e46162572c4 20:31:53 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 20:31:53 INFO - mozversion platform_version: 53.0a1 20:31:53 INFO - using testdate: 1482294713 20:31:53 INFO - actual date: 1482294713 20:31:53 INFO - starting webserver on 'localhost:55660' 20:31:53 INFO - SUITE-START | Running 2 tests 20:31:53 INFO - TEST-START | dromaeo_css 20:31:53 INFO - operating with platform_type : mac_ 20:31:53 INFO - Initialising browser for dromaeo_css test... 20:31:53 INFO - TEST-INFO | started process 1925 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/tmpwRdKyD/profile http://localhost:55660/getInfo.html) 20:31:56 INFO - PROCESS | 1925 | __metrics Screen width/height:1600/1200 20:31:56 INFO - PROCESS | 1925 | colorDepth:24 20:31:56 INFO - PROCESS | 1925 | Browser inner width/height: 1024/689 20:31:56 INFO - PROCESS | 1925 | __metrics 20:31:56 INFO - PROCESS | 1925 | 2016-12-20 20:31:56.648 plugin-container[1927:19768] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f37, name = 'com.apple.tsm.portname' 20:31:56 INFO - PROCESS | 1925 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:31:56 INFO - PROCESS | 1925 | 2016-12-20 20:31:56.651 plugin-container[1927:19768] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x905b, name = 'com.apple.CFPasteboardClient' 20:31:56 INFO - PROCESS | 1925 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:31:56 INFO - PROCESS | 1925 | 2016-12-20 20:31:56.651 plugin-container[1927:19768] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:31:56 ERROR - PROCESS | 1925 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3232: TypeError: this.tabs is undefined 20:31:57 INFO - TEST-INFO | 1925: exit 0 20:31:57 INFO - Browser initialized. 20:31:57 INFO - Running cycle 1/1 for dromaeo_css test... 20:31:57 INFO - TEST-INFO | started process 1928 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/tmpwRdKyD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:31:59 INFO - PROCESS | 1928 | 2016-12-20 20:31:59.803 plugin-container[1929:19890] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 20:31:59 INFO - PROCESS | 1928 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:31:59 INFO - PROCESS | 1928 | 2016-12-20 20:31:59.804 plugin-container[1929:19890] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4607, name = 'com.apple.CFPasteboardClient' 20:31:59 INFO - PROCESS | 1928 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:31:59 INFO - PROCESS | 1928 | 2016-12-20 20:31:59.804 plugin-container[1929:19890] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:32:09 INFO - PROCESS | 1928 | RSS: Main: 236724224 20:32:09 INFO - PROCESS | 1928 | 20:32:59 INFO - PROCESS | 1928 | 1482294779167 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 20:35:11 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:55660/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55660/tests/dromaeo/cssquery-ext.html) 20:35:13 INFO - PROCESS | 1928 | RSS: Main: 253317120 20:35:13 INFO - PROCESS | 1928 | 20:38:16 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:55660/tests/dromaeo/cssquery-ext.html (next: http://localhost:55660/tests/dromaeo/cssquery-jquery.html) 20:38:17 INFO - PROCESS | 1928 | RSS: Main: 264658944 20:38:17 INFO - PROCESS | 1928 | 20:41:19 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:55660/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55660/tests/dromaeo/cssquery-mootools.html) 20:41:19 INFO - PROCESS | 1928 | RSS: Main: 269066240 20:41:19 INFO - PROCESS | 1928 | 20:44:21 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:55660/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55660/tests/dromaeo/cssquery-prototype.html) 20:44:22 INFO - PROCESS | 1928 | RSS: Main: 268304384 20:44:22 INFO - PROCESS | 1928 | 20:47:25 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:55660/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55660/tests/dromaeo/cssquery-yui.html) 20:47:25 INFO - PROCESS | 1928 | RSS: Main: 272932864 20:47:25 INFO - PROCESS | 1928 | 20:50:28 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:55660/tests/dromaeo/cssquery-yui.html (next: http://localhost:55660/tests/dromaeo/cssquery-dojo.html) 20:50:29 INFO - PROCESS | 1928 | RSS: Main: 276303872 20:50:29 INFO - PROCESS | 1928 | 20:50:29 INFO - PROCESS | 1928 | __start_tp_report 20:50:29 INFO - PROCESS | 1928 | _x_x_mozilla_page_load 20:50:29 INFO - PROCESS | 1928 | _x_x_mozilla_page_load_details 20:50:29 INFO - PROCESS | 1928 | |i|pagename|runs| 20:50:29 INFO - PROCESS | 1928 | |0;dojo.html;3360.647132051133;3386.932261354773;3412.5734283214597;3414.573178352706;3423.1784371750778;5649.49154576088;5743.052396354602;5774.82675519734;5783.103618939061;5803.361630220683;5667.4049224831415;5727.198192253078;5730.169125476803;5753.626014309078;5760.510356619692;4309.82760689572;5203.505666961642;5275.894482110355;5297.073012222865;5311.893762124755;4271.551487093857;5229.921551176716;5297.417284098746;5360.865978350534;5365.651232669884;468.2833815481392;4529.207388706963;5243.213517972296;5277.182036784305;5380.623356365056;5257.395399529065;5261.421243663194;5295.338082739658;5313.521783039545;5324.227986941911;5211.322528071327;5223.6343455958095;5226.608004399647;5260.447652996419;5351.224072509464;5430.59270554706;5695.231143795576;5707.62900411476;5775.509081728058;5795.101759227285;5784.582777219566;6141.486007784275;6159.952808022647;6170.7531698732;6312.495000399957;5945.851353716149;6238.2825975013;6244;6253.53098517613;6288.371162883721;5768.278965129359;5929.407059294079;5954.404559544011;5965.164876917235;5976.850578735566;6213.813585592439;6232.968835155795;6246.562740608114;6254.655993920378;6257.906131408033;6155.169052177988;6196.47329976957;6202.689865506797;6216.098665693449;6238.376162383725;6272.717727702265;6274.466670333073;6301.590396624205;6304.873902522016;6309.179806625201;6126.609281623701;6136.6722581676495;6175.011998080286;6226.533010024175;6283.842903927347;4847.248676455155;6268.059791031291;6277.937220627827;6279.811605651837;6288.088227207029;4858.17411040124;4898.657094003385;4900.632452566;4904.6566740327835;5007.549320561167;4411.713238639478;4450.198964186413;4458.77706114693;4499.662525310615;4537.274036154266;5742.253507044057;5800.4489573490455;5843;5906.202662640489;5920.407959204045;6133;6158.876822463526;6214.285357183891;6260.749570017239;6260.843478913063;5962.105684147413;5970.104484327386;5975.611585246945;5982.4017598239825;5983.311919129356;5483.698396588146;5486.957478079232;5491.75287112073;5507.091329930517;5538.695371754592;5134.768935397841;6167.074938759132;6250.906236406367;6266.968665156645;6439.839004024937;5162.199859021857;5581.162825576196;6043.456088952015;6165.753369865155;6257.4681152102585;6352.333005034405;6355.650439225887;6384.138141350858;6430.935690643034;6453.806385808433;4435.2016637005645;6105.633661980295;6124.3809352984035;6266.905996409966;6294.1188233648245;5968.552358573212;6062.90905636407;6211.409916057967;6244.937550624436;6263.498920086473;6158.230221222348;6174.938250617616;6191.349908259568;6193.81418557444;6195.101710252078;5605.32736071674;5948.167256583995;6118.60229085108;6199.256089269316;6200.534959877937;5703.5722320826935;5889.970550147222;5960.433758792908;6017.608855424383;6077.908831367445;4414.713043652152;6115.149072927005;6122.489639783254;6135.447809697149;6156.630602163706;5882.088276317178;5928.4960778334325;5961;6063.878722425438;6132.9080063799975 20:50:29 INFO - PROCESS | 1928 | |1;ext.html;438.9517153113155;452.30571073402325;482.3416037109346;517.4334104155951;517.844646606018;36248;39266.60733392665;39341.01647458815;39438.40842387366;39565.80217098914;34367.968960931146;40258.39612405816;40931.59068409315;40953.7952310238;40958.18083638325;8438.957805210965;8651.65393384264;9207.677731279424;9526.476043817587;11319.03788178006;11258.4370781461;11558.595449159282;11571.132165087653;11591.884081159207;11597.362145082017;11552.826707599394;11599.362035088066;11607.303561786277;11613;11625.534978600846;11448.48481818316;11538.94230528842;11539.48072336747;11552.76894462106;11556.711082223012;9543.618255269781;11402.2588531746;11412.31526108436;11491.482883270273;11518.88481115195;39274;39728;39735.8013209935;39821.60178398237;39847.20305593901;3404.863805447779;3508.9649103508896;3614.421692529209;3627;3872.5740168581433;29959.70040299591;30745.538816917764;30770.692293077005;30841.383172336653;30854.537181942287;3150.2596889730894;3265.755068369858;3272.640009598942;3290.6380298167182;3369.3261347730554;502118;506842;507361;508640;508915;60483;60693;60847.391526085055;60947;61035;52457;61267.77464450686;63786.0432093519;63812.36187638157;63836.04245936315;15697.215139242991;15916.68166636684;15926.68146637084;16036.438724644815;16259.430919917746;11107.55569777216;11324.660260192208;11976;12204.938975305244;12269.938650306869;16823.07473088992;16901.91549042247;16916.492505224618;16928.915355423145;16944.57638559046;5327.920081198785;5461.508464238158;5804.506616937608;6100.359462256488;6306.873862522724;932.2681694869494;970.7427531704104;976.1019861727228;1000.3247807729754;1066.589363095214;23053.42366440819;28196.25986315094;30252.03361781477;30432.54351184734;30632.54051189234;891.9375643705008;1062.9627963021258;1085.1915323084195;1093.6992327110108;1223.2415902140729;13316.800247996287;15115.546533604009;15388.692226155637;15468.071915685096;16092.919535402249;606210;608698;609648;610289.9485502545;611075.944620274;46770.5322946773;58343.70828145832;58467.70766146142;58652.12021819676;58920.41079589149;1308.3915979069777;1345.9730805383838;1354.5056054540023;1370.0615078670944;1384.3147641917315;25955.610665840406;29227.123186304445;29936.85031574828;30302.184381324623;30548.694513054586;50068;50660.493395065576;50667.746661267935;50740.238896415845;50816.49183508118;455963;542876;544559;546324;546725;32906.50640240446;39594.81215563538;40003.1999360017;40383;40565.59434405618;45602.54397455983;46498.76750616225;46673.53326466828;47601;47929.28106078342;31963.840180798947;34531.61873525031;38563.98780053058;39649.80175099106;40122;37950.430743538316;38103;38313.233735325695;39158;39373.01567460837;37426;38039.429408559445;38159.42760858534;38275.80862095672;38361.42457863079;26349.20952371432;31907.521387179677;33203.50194747129;34378;34520.48219276663;25242.49515009653;25507.872460637576;25582.976680933298;25953;26009.73990260149 20:50:29 INFO - PROCESS | 1928 | |2;jquery.html;307.9692030796921;335.23665519368126;377.1758707224714;385.74348058541074;396.02775186916125;18073.457796266117;18840.246390144395;19743.40769776907;19979.90010049948;20009.999500025013;19190.904045479787;19683;19848.503787405323;19968.400947971546;20167.991600419995;4440.489343725464;4558.703684260529;5411.810586629469;5415.10650742627;5421.918671219935;5278.155495120782;5593.692346920918;5889;5933.821985340447;5984.910226346587;5008.223725322581;5903.586748927577;5958.136070269808;5979.07324364722;5987.131865879445;5131.384233891919;6386.457151142158;6442;6451.129097571815;6452.612843229421;5530;5686.516646085088;5708.315002199763;5714.79989202268;5721.570882183812;10598.205134614858;12345;13034.41345139471;13049.347532623331;13071.346432678329;2002.469345623411;2260.468789834404;2402.1023391520853;2681.463707258556;2886.5237235856057;9591.184749296319;9627.614895404175;9859.950700246452;10063.949680251551;10230.232732545015;1793.157216108427;1827.661882551732;1860.5627677495777;1865.0954287170712;1898.4779185723899;88286;94149;111102.99999999919;113599;117573.41213293879;11485.598004069818;11487.655370338904;11649.417529123679;11669.040657720434;11793.29240245588;14505.057171283834;14618.195999220145;14626.707465850623;14639.268036598127;14649.047811892193;7176.815825388857;8169.836603268021;8321.708740194064;8358.83282334362;8413.158684131538;7776.844463110706;7921.089074756362;8092.473989190684;8160.0207975042185;8274.793130171794;3923.7645741255615;4523.5024147343765;4599.586037256663;4603.424571928509;4678.619807156897;1634.7793047938376;1867.5424520992171;1914.9712754308696;1937.225109956029;1953.6776431888863;645.5642441352069;790.9802254943558;816.2449733996029;884.7655371326475;909.7543663210896;20152.69007514507;21539.569208616052;21658.783412165678;21837;22111.226107086175;878.9692360767342;933.425943045036;966.7389804752678;975.1857199238574;1001.7846163074973;10685.946570267099;11381.203315767982;11392.601258956063;11398.316101033965;11448.828267575993;259957;262184;262914;264916;290472;49941;81997;102173.4673979891;116352;119302;947.6920000999703;967.579103090158;980.9705708828603;987.8468837330225;995.3977843404781;14773.630659233606;18980.810191897905;19187.424377268704;19201.903990479957;19229;45851.541484584726;50652.49347506478;52976.73511632417;53473.73263133659;54217;52582.211266830265;68283.31716682969;70104.6494767523;71758.56482870418;74904.62547687227;10900.563977440814;10951.83572246433;11168.888311116785;11409.828852567383;12133.878661213275;12961.41673624708;13109;13256;13397.53108641193;13459.519452859982;11219.187473298398;11289.322640641587;11309.20835541537;11416.915393037649;11474.94262528682;8538.914610853812;10987.560497580007;11380.260283081574;11397.430128493377;11517.42412879336;11177.16171287173;11327.263727857662;11665.416729163677;11746.58886938953;11779.941100294443;11793.174477786473;12050.975667068047;12064.396780160781;12104.092193085378;12182.512699492276;11052;11167.776644466903;11468.598599048993;11503.194776365573;11565.595204167814 20:50:29 INFO - PROCESS | 1928 | |3;mootools.html;1484.6214215375078;1501.8873584481164;1721.0792226159008;1840.07996001999;1858.907054647268;7547.13207981081;7872.97651305331;8048.476849004818;8535.359848011401;8556.614952327145;6721.764738234163;8320.95839520803;8392.58037098144;8556.486610803357;8572.571371431435;2287.8856057197113;2340;2344.3084290134366;2347.3779448446176;2353.5292941411785;2338.485533182697;2357.8231632627535;2360.3862995621175;2378.5124049569795;2379.797717194032;2275.4993901341677;2281.8288628352857;2311.191083120911;2312.5259321838985;2321.814254859607;1961.744973153488;2267.1611503743557;2290.7709229077127;2306.515631717333;2314.5023819878807;1825.7535232743542;2248.9887550562144;2322.512272422793;2346.0967527501866;2350.3536527454917;8290.792730181733;8346.123657015987;8358.331333493306;8402.537860417675;8492.74521764348;5014.5737612303;5143.459936706667;5185.766640501187;5191.195364718475;5193.948060519384;2588.6117082437595;3310.874302737057;3349.8492567834505;3575.3206890690685;3602.7478076534653;1034.053840735726;1055.6569115037644;1072.4208927179313;1079.854219680341;1091.9781604367947;10913.83629245562;10973.890261097367;11042.503087361089;11055.502502387411;11056.170787190913;3449.724022078228;4211.810468528924;4536.523665015192;4596.839110631178;4703;9374.484403357745;9451.243900487945;9457.007014263541;9460.57427415768;9483.193928516153;5189.844304670866;5209.609279304067;5211.296474975906;5211.635185536976;5218.869528261824;4309.978450107729;4325.243082460557;4568.086382723469;4606.239970404846;4730;3767.5290588676417;3852.8266228019806;3884.76691398517;3912.7065470089287;3917.8432862685745;1094.7317907112808;1267.828843106169;1285.6978610026636;1298.4222021200476;1310.0829419406264;786.614558866163;892.7634176943116;912.9136327769933;925.7037747920735;944.2304521814699;5143.202803565453;5328.600354973315;5686.289213848269;5797.884042319214;5888.617239879394;909.0909090909091;922.4972390047307;931.6646007437317;931.9767005824908;939.9624015039321;3759.6240375962184;3775;3797;3872.7994321760343;3906.1015966327905;8448.57757112142;9764.316497845082;10304.536295866554;10312.484375781181;10345.844812327821;10179.7455063624;10250.487475626189;10262.640807571699;10274.383536987805;10303;7317.524360916524;7444.367228785614;8000.51996880189;8006.279434850891;8034.799130021679;5761.711914404347;5863;5877.059670452707;5981.491573216325;5993.670348130895;8120.512769233865;8753;9187.954060229657;9390.483523406278;9402.717918462457;8923.483007888804;9032.187103160748;9553;9579.281553883626;9679.66121185755;6609.339066093352;6862.622555759481;7031.683574239274;7172.820679482846;7337.3029562191505;7886.526808391515;8142.715004974797;8155.836883262183;8156.755297340852;8184.181581841768;6652.93553031532;6757.222919364336;7117.750878719427;7289.343959043712;7340.779776606711;6901.551399159039;7067.893981590383;7166.175889772743;7170.856582868418;7203.423726101794;6675.299093595297;6768.864622707617;6789.898151527632;6829.726810927507;6855.280195579392;4313.61177494027;4756.167670657552;4840.830570930141;4921.581665558468;4989.750512474435;4751.762411879323;4796.472388037383;4863.659543831898;4959.528844759742;4986.825461108844 20:50:29 INFO - PROCESS | 1928 | |4;prototype.html;283.93753374257665;358.4444111626979;364.7465011816787;365.3934468781821;369.92786406650714;5220.973895130529;5289.8626795239015;5343;5491.890162196753;5544.835584527249;4133.028738246509;4321.956780432195;5101.591872650189;5342.813001544947;5345.251664766935;3951.8221680024467;4181.560936101711;4200.096979149483;4206.390073439349;4218.810153543082;3738.140227747606;3789.2800367930004;3873.0123818426264;3991.2616166009234;4224.915501689964;3291.934161316772;3413.743969202307;3996.2606917720314;3998.600139985993;4056.9391459128137;3195.72836308914;3637.4725664778725;4018.1963607278662;4036.354183330682;4059.6955228357697;3454.844531996041;3503.4394496880377;3517.736169787276;3521.9295614087832;3532.947005794915;4995.725235112068;5192.71440070796;5273.419923808378;5286.145481440018;5308.92036619451;4371.34429835524;4597.517260687613;4600.5169457207185;4608.976955115203;4611.146937816515;4687.437507499088;5038.722870242135;5182.170852663593;5638.674911395859;5658.3503640152285;2745.890164393422;2951.8192722910794;3342.331533693247;3785.6403641653997;3824.541055073409;4701.529847015306;4825.541573550507;5916.556258280603;5942.187632772003;5949.583529152961;2977.7171168738937;3604.7837129772297;3847.115163512408;3887.241987812406;3898.064464528493;3343.5820522434697;3913.4716813229848;3933.92132157361;3937.488126543531;3950.7036972227197;3903.785291808978;4426.490953540319;4556.544345565483;4567.474740404829;4607.516210797885;4487.125010622963;4953.331300274489;5700;5704.4295570443455;5772.018756811352;1070.4166229404993;1136.9658910232706;1220.2617416462913;1227.5274019502556;1252.5303011370736;754.6905768634944;788.578110710767;863.684755064397;864.9221570058725;885.5034990865416;4105.384192371109;4275.914481710411;4794.082367053206;4979.90040199194;5090.287359769635;4740.288956656529;5414.025475414384;5456.399796022434;5584.581156413204;5593.609016921779;4223.978880105641;5353.57171426285;5457.290552228185;5469.179623056494;5475.67145971243;2517.728547083711;2595.532804095243;2925.7805664575244;2972.176432389239;2973.36072744361;5095.541401273903;5321.148616221386;5456.645318054314;5484.451554844483;5733.283339582552;4720.362751028636;5192.844214673566;5494.972525137428;5697.772089116389;5797.826065218051;4915.3855768029;5842.472084134744;5846.327672317653;5852.502537284294;5874.295084589877;4749.19530578378;5262.763175657028;5862.120681897579;5883.441073098049;5915.704214789157;5182.481751824787;5770.13447982806;5798.59409841307;5829.562782791223;5885.352611212848;5709.543236540983;6051.334353221054;6107.236595425572;6125.663088530174;6162.3026184289065;3574.2315402188656;3803.2203398302727;3853.2678791029616;3867.226554689017;3888.96111038886;5165.044466773659;5185.9887321971855;5413.512783849473;5667.4615911488345;5853.843770922693;4973.428055773632;5215.5135786300725;5672.730907636978;5674.375818660027;5723.914141287967;5318.4149743527405;5535.944640553543;5660.773569057192;5671.773129074956;5676.460736230051;4517.985036609295;4598.563136502027;5055.69665820052;5294.549963253013;5475.5907400743545;5615.747291371979;5688.175214593944;5697.829065128053;5704.486596206361;5716.228309178374;4922.015596880567;4980.526849949249;5273.841784746463;5517.5585953124455;5565.8608534785335 20:50:29 INFO - PROCESS | 1928 | |5;yui.html;742.8254360225346;756.4251169111474;767.3094215206314;794.9284564389204;850.8000619854333;14608.415663373466;15265.465708700198;15669.373225070998;15724;15864.762028569581;12372.381380930963;14052.859471405283;14290.428382864699;14346.713065738679;14486.420543178287;863.5423225690379;887.5207388010466;890.5191196753746;891.6968230801526;896.596531560797;704.2218348724663;730.755197009;872.9388942774009;909.4270609515995;922.2944447497636;755.8374949385881;832.8917240758723;941.4163218804351;944.2681921510815;951.0442005784178;855;864.3906046237388;931.6692574136151;932.7947851472666;934.9298802589799;683.7504310926525;693.9826504337432;698.5983059740619;700.5726506830829;700.7967689370076;14327.140371577634;14857.2571371431;15014.324355404033;15053.94622376434;15137.924310378377;12081.214721043192;12580.616132225448;13713.588592342247;13899.444022239099;14058.789118163235;10878.782424351548;11579.76840463186;11724;11933.04535637147;11992.760144797143;3668.3213605482806;3680.6319368063246;3696.815159242027;3703.981480092609;3827.7320587558884;103934;106475;107475.46262268639;108210;109020;8941.687040953535;10253.33353332031;10263.12763415118;10329.32859364154;10394.896051039545;19960.20159193645;20122.89938550298;20143.697844532344;20143.798562014486;20146.496337591383;7739.883901741478;7742.706362640735;7799.571023593643;7879.881801772978;7888.921110788933;4224.8905044245175;4332.545082766327;4380.824767009284;4416.933745993812;4446.911061778746;3553.857845686195;3607.9458808117897;3655.250673611903;3656.5246517952496;3669.7247706422127;553.9196816461671;642.6882961763541;647.1489990662265;660.21764209412;662.7448432353578;537.4303238567131;570.8886767080462;576.3314555116045;599.9460048595647;650.4373716735084;8261.339092872557;8586.398952073296;8806.339524535686;9266.11971862672;9367.531623418801;617.607819034907;640.4908098062052;650.2554575011585;656.7832615236961;691.1774987764544;3434.587849458031;4310.633261589342;4474.485434175046;4539.546045395434;4569.565891240327;99956;108134;108301.54532228738;110483.44758276157;112010;69327.65336173387;69834.3016569838;92368;94626.58060129103;95197;69813.65093174501;82408.17591824125;82699.75950360749;84031;84742.15257847466;7429.962850185714;8059.234372734581;8383.95808020956;8481.491110533389;8523.872141917753;52357.21464177964;53011.20483192832;53822;53860.461395385544;54997;71676.28323716905;77453;78380;78390.6080469594;81905;11438.771224575295;12622.179558328678;12830.615081547568;12834;13056.543020994219;13086.672833179247;13939.233342165873;14255.287235637968;14561.400003995243;14720.337584808494;11968;12004.579839705568;12027.21823081497;12349.19730217533;12408.193467424588;11033.83449248278;13233.3383330835;13354.732905342033;13640.590782276548;13774.311284435938;12623.053271004896;12688.936555317165;13059.869401306245;13341.132826366254;13359.599212023266;10988.835167472334;11109.944450277697;11161.441927903736;11208;12063.81904271419;9540.33217674756;10095.596176153165;11141.498632561716;11185.105191584817;11329.525899693375 20:50:29 INFO - PROCESS | 1928 | __end_tp_report 20:50:29 INFO - PROCESS | 1928 | __start_cc_report 20:50:29 INFO - PROCESS | 1928 | _x_x_mozilla_cycle_collect,383 20:50:29 INFO - PROCESS | 1928 | __end_cc_report 20:50:29 INFO - PROCESS | 1928 | __startTimestamp1482295829089__endTimestamp 20:50:29 INFO - PROCESS | 1928 | 20:50:29 INFO - PROCESS | 1928 | ------- Summary: start ------- 20:50:29 INFO - PROCESS | 1928 | Number of tests: 6 20:50:29 INFO - PROCESS | 1928 | 20:50:29 INFO - PROCESS | 1928 | [#0] dojo.html Cycles:165 Average:5688.38 Median:5960.43 stddev:780.38 (13.1%) stddev-sans-first:761.11 20:50:29 INFO - PROCESS | 1928 | Values: 3360.6 3386.9 3412.6 3414.6 3423.2 5649.5 5743.1 5774.8 5783.1 5803.4 5667.4 5727.2 5730.2 5753.6 5760.5 4309.8 5203.5 5275.9 5297.1 5311.9 4271.6 5229.9 5297.4 5360.9 5365.7 468.3 4529.2 5243.2 5277.2 5380.6 5257.4 5261.4 5295.3 5313.5 5324.2 5211.3 5223.6 5226.6 5260.4 5351.2 5430.6 5695.2 5707.6 5775.5 5795.1 5784.6 6141.5 6160.0 6170.8 6312.5 5945.9 6238.3 6244.0 6253.5 6288.4 5768.3 5929.4 5954.4 5965.2 5976.9 6213.8 6233.0 6246.6 6254.7 6257.9 6155.2 6196.5 6202.7 6216.1 6238.4 6272.7 6274.5 6301.6 6304.9 6309.2 6126.6 6136.7 6175.0 6226.5 6283.8 4847.2 6268.1 6277.9 6279.8 6288.1 4858.2 4898.7 4900.6 4904.7 5007.5 4411.7 4450.2 4458.8 4499.7 4537.3 5742.3 5800.4 5843.0 5906.2 5920.4 6133.0 6158.9 6214.3 6260.7 6260.8 5962.1 5970.1 5975.6 5982.4 5983.3 5483.7 5487.0 5491.8 5507.1 5538.7 5134.8 6167.1 6250.9 6267.0 6439.8 5162.2 5581.2 6043.5 6165.8 6257.5 6352.3 6355.7 6384.1 6430.9 6453.8 4435.2 6105.6 6124.4 6266.9 6294.1 5968.6 6062.9 6211.4 6244.9 6263.5 6158.2 6174.9 6191.3 6193.8 6195.1 5605.3 5948.2 6118.6 6199.3 6200.5 5703.6 5890.0 5960.4 6017.6 6077.9 4414.7 6115.1 6122.5 6135.4 6156.6 5882.1 5928.5 5961.0 6063.9 6132.9 20:50:29 INFO - PROCESS | 1928 | 20:50:29 INFO - PROCESS | 1928 | [#1] ext.html Cycles:180 Average:68514.22 Median:37688.22 stddev:146656.63 (389.1%) stddev-sans-first:146978.97 20:50:29 INFO - PROCESS | 1928 | Values: 439.0 452.3 482.3 517.4 517.8 36248.0 39266.6 39341.0 39438.4 39565.8 34368.0 40258.4 40931.6 40953.8 40958.2 8439.0 8651.7 9207.7 9526.5 11319.0 11258.4 11558.6 11571.1 11591.9 11597.4 11552.8 11599.4 11607.3 11613.0 11625.5 11448.5 11538.9 11539.5 11552.8 11556.7 9543.6 11402.3 11412.3 11491.5 11518.9 39274.0 39728.0 39735.8 39821.6 39847.2 3404.9 3509.0 3614.4 3627.0 3872.6 29959.7 30745.5 30770.7 30841.4 30854.5 3150.3 3265.8 3272.6 3290.6 3369.3 502118.0 506842.0 507361.0 508640.0 508915.0 60483.0 60693.0 60847.4 60947.0 61035.0 52457.0 61267.8 63786.0 63812.4 63836.0 15697.2 15916.7 15926.7 16036.4 16259.4 11107.6 11324.7 11976.0 12204.9 12269.9 16823.1 16901.9 16916.5 16928.9 16944.6 5327.9 5461.5 5804.5 6100.4 6306.9 932.3 970.7 976.1 1000.3 1066.6 23053.4 28196.3 30252.0 30432.5 30632.5 891.9 1063.0 1085.2 1093.7 1223.2 13316.8 15115.5 15388.7 15468.1 16092.9 606210.0 608698.0 609648.0 610289.9 611075.9 46770.5 58343.7 58467.7 58652.1 58920.4 1308.4 1346.0 1354.5 1370.1 1384.3 25955.6 29227.1 29936.9 30302.2 30548.7 50068.0 50660.5 50667.7 50740.2 50816.5 455963.0 542876.0 544559.0 546324.0 546725.0 32906.5 39594.8 40003.2 40383.0 40565.6 45602.5 46498.8 46673.5 47601.0 47929.3 31963.8 34531.6 38564.0 39649.8 40122.0 37950.4 38103.0 38313.2 39158.0 39373.0 37426.0 38039.4 38159.4 38275.8 38361.4 26349.2 31907.5 33203.5 34378.0 34520.5 25242.5 25507.9 25583.0 25953.0 26009.7 20:50:29 INFO - PROCESS | 1928 | 20:50:29 INFO - PROCESS | 1928 | [#2] jquery.html Cycles:180 Average:24011.03 Median:21599.18 stddev:48070.94 (222.6%) stddev-sans-first:48172.85 20:50:29 INFO - PROCESS | 1928 | Values: 308.0 335.2 377.2 385.7 396.0 18073.5 18840.2 19743.4 19979.9 20010.0 19190.9 19683.0 19848.5 19968.4 20168.0 4440.5 4558.7 5411.8 5415.1 5421.9 5278.2 5593.7 5889.0 5933.8 5984.9 5008.2 5903.6 5958.1 5979.1 5987.1 5131.4 6386.5 6442.0 6451.1 6452.6 5530.0 5686.5 5708.3 5714.8 5721.6 10598.2 12345.0 13034.4 13049.3 13071.3 2002.5 2260.5 2402.1 2681.5 2886.5 9591.2 9627.6 9860.0 10063.9 10230.2 1793.2 1827.7 1860.6 1865.1 1898.5 88286.0 94149.0 111103.0 113599.0 117573.4 11485.6 11487.7 11649.4 11669.0 11793.3 14505.1 14618.2 14626.7 14639.3 14649.0 7176.8 8169.8 8321.7 8358.8 8413.2 7776.8 7921.1 8092.5 8160.0 8274.8 3923.8 4523.5 4599.6 4603.4 4678.6 1634.8 1867.5 1915.0 1937.2 1953.7 645.6 791.0 816.2 884.8 909.8 20152.7 21539.6 21658.8 21837.0 22111.2 879.0 933.4 966.7 975.2 1001.8 10685.9 11381.2 11392.6 11398.3 11448.8 259957.0 262184.0 262914.0 264916.0 290472.0 49941.0 81997.0 102173.5 116352.0 119302.0 947.7 967.6 981.0 987.8 995.4 14773.6 18980.8 19187.4 19201.9 19229.0 45851.5 50652.5 52976.7 53473.7 54217.0 52582.2 68283.3 70104.6 71758.6 74904.6 10900.6 10951.8 11168.9 11409.8 12133.9 12961.4 13109.0 13256.0 13397.5 13459.5 11219.2 11289.3 11309.2 11416.9 11474.9 8538.9 10987.6 11380.3 11397.4 11517.4 11177.2 11327.3 11665.4 11746.6 11779.9 11793.2 12051.0 12064.4 12104.1 12182.5 11052.0 11167.8 11468.6 11503.2 11565.6 20:50:29 INFO - PROCESS | 1928 | 20:50:29 INFO - PROCESS | 1928 | [#3] mootools.html Cycles:180 Average:5437.70 Median:5164.61 stddev:2986.17 (57.8%) stddev-sans-first:2979.77 20:50:29 INFO - PROCESS | 1928 | Values: 1484.6 1501.9 1721.1 1840.1 1858.9 7547.1 7873.0 8048.5 8535.4 8556.6 6721.8 8321.0 8392.6 8556.5 8572.6 2287.9 2340.0 2344.3 2347.4 2353.5 2338.5 2357.8 2360.4 2378.5 2379.8 2275.5 2281.8 2311.2 2312.5 2321.8 1961.7 2267.2 2290.8 2306.5 2314.5 1825.8 2249.0 2322.5 2346.1 2350.4 8290.8 8346.1 8358.3 8402.5 8492.7 5014.6 5143.5 5185.8 5191.2 5193.9 2588.6 3310.9 3349.8 3575.3 3602.7 1034.1 1055.7 1072.4 1079.9 1092.0 10913.8 10973.9 11042.5 11055.5 11056.2 3449.7 4211.8 4536.5 4596.8 4703.0 9374.5 9451.2 9457.0 9460.6 9483.2 5189.8 5209.6 5211.3 5211.6 5218.9 4310.0 4325.2 4568.1 4606.2 4730.0 3767.5 3852.8 3884.8 3912.7 3917.8 1094.7 1267.8 1285.7 1298.4 1310.1 786.6 892.8 912.9 925.7 944.2 5143.2 5328.6 5686.3 5797.9 5888.6 909.1 922.5 931.7 932.0 940.0 3759.6 3775.0 3797.0 3872.8 3906.1 8448.6 9764.3 10304.5 10312.5 10345.8 10179.7 10250.5 10262.6 10274.4 10303.0 7317.5 7444.4 8000.5 8006.3 8034.8 5761.7 5863.0 5877.1 5981.5 5993.7 8120.5 8753.0 9188.0 9390.5 9402.7 8923.5 9032.2 9553.0 9579.3 9679.7 6609.3 6862.6 7031.7 7172.8 7337.3 7886.5 8142.7 8155.8 8156.8 8184.2 6652.9 6757.2 7117.8 7289.3 7340.8 6901.6 7067.9 7166.2 7170.9 7203.4 6675.3 6768.9 6789.9 6829.7 6855.3 4313.6 4756.2 4840.8 4921.6 4989.8 4751.8 4796.5 4863.7 4959.5 4986.8 20:50:29 INFO - PROCESS | 1928 | 20:50:29 INFO - PROCESS | 1928 | [#4] prototype.html Cycles:180 Average:4462.25 Median:4988.13 stddev:1410.88 (28.3%) stddev-sans-first:1379.55 20:50:29 INFO - PROCESS | 1928 | Values: 283.9 358.4 364.7 365.4 369.9 5221.0 5289.9 5343.0 5491.9 5544.8 4133.0 4322.0 5101.6 5342.8 5345.3 3951.8 4181.6 4200.1 4206.4 4218.8 3738.1 3789.3 3873.0 3991.3 4224.9 3291.9 3413.7 3996.3 3998.6 4056.9 3195.7 3637.5 4018.2 4036.4 4059.7 3454.8 3503.4 3517.7 3521.9 3532.9 4995.7 5192.7 5273.4 5286.1 5308.9 4371.3 4597.5 4600.5 4609.0 4611.1 4687.4 5038.7 5182.2 5638.7 5658.4 2745.9 2951.8 3342.3 3785.6 3824.5 4701.5 4825.5 5916.6 5942.2 5949.6 2977.7 3604.8 3847.1 3887.2 3898.1 3343.6 3913.5 3933.9 3937.5 3950.7 3903.8 4426.5 4556.5 4567.5 4607.5 4487.1 4953.3 5700.0 5704.4 5772.0 1070.4 1137.0 1220.3 1227.5 1252.5 754.7 788.6 863.7 864.9 885.5 4105.4 4275.9 4794.1 4979.9 5090.3 4740.3 5414.0 5456.4 5584.6 5593.6 4224.0 5353.6 5457.3 5469.2 5475.7 2517.7 2595.5 2925.8 2972.2 2973.4 5095.5 5321.1 5456.6 5484.5 5733.3 4720.4 5192.8 5495.0 5697.8 5797.8 4915.4 5842.5 5846.3 5852.5 5874.3 4749.2 5262.8 5862.1 5883.4 5915.7 5182.5 5770.1 5798.6 5829.6 5885.4 5709.5 6051.3 6107.2 6125.7 6162.3 3574.2 3803.2 3853.3 3867.2 3889.0 5165.0 5186.0 5413.5 5667.5 5853.8 4973.4 5215.5 5672.7 5674.4 5723.9 5318.4 5535.9 5660.8 5671.8 5676.5 4518.0 4598.6 5055.7 5294.5 5475.6 5615.7 5688.2 5697.8 5704.5 5716.2 4922.0 4980.5 5273.8 5517.6 5565.9 20:50:29 INFO - PROCESS | 1928 | 20:50:29 INFO - PROCESS | 1928 | [#5] yui.html Cycles:180 Average:20686.00 Median:4356.68 stddev:30460.46 (699.2%) stddev-sans-first:30509.11 20:50:29 INFO - PROCESS | 1928 | Values: 742.8 756.4 767.3 794.9 850.8 14608.4 15265.5 15669.4 15724.0 15864.8 12372.4 14052.9 14290.4 14346.7 14486.4 863.5 887.5 890.5 891.7 896.6 704.2 730.8 872.9 909.4 922.3 755.8 832.9 941.4 944.3 951.0 855.0 864.4 931.7 932.8 934.9 683.8 694.0 698.6 700.6 700.8 14327.1 14857.3 15014.3 15053.9 15137.9 12081.2 12580.6 13713.6 13899.4 14058.8 10878.8 11579.8 11724.0 11933.0 11992.8 3668.3 3680.6 3696.8 3704.0 3827.7 103934.0 106475.0 107475.5 108210.0 109020.0 8941.7 10253.3 10263.1 10329.3 10394.9 19960.2 20122.9 20143.7 20143.8 20146.5 7739.9 7742.7 7799.6 7879.9 7888.9 4224.9 4332.5 4380.8 4416.9 4446.9 3553.9 3607.9 3655.3 3656.5 3669.7 553.9 642.7 647.1 660.2 662.7 537.4 570.9 576.3 599.9 650.4 8261.3 8586.4 8806.3 9266.1 9367.5 617.6 640.5 650.3 656.8 691.2 3434.6 4310.6 4474.5 4539.5 4569.6 99956.0 108134.0 108301.5 110483.4 112010.0 69327.7 69834.3 92368.0 94626.6 95197.0 69813.7 82408.2 82699.8 84031.0 84742.2 7430.0 8059.2 8384.0 8481.5 8523.9 52357.2 53011.2 53822.0 53860.5 54997.0 71676.3 77453.0 78380.0 78390.6 81905.0 11438.8 12622.2 12830.6 12834.0 13056.5 13086.7 13939.2 14255.3 14561.4 14720.3 11968.0 12004.6 12027.2 12349.2 12408.2 11033.8 13233.3 13354.7 13640.6 13774.3 12623.1 12688.9 13059.9 13341.1 13359.6 10988.8 11109.9 11161.4 11208.0 12063.8 9540.3 10095.6 11141.5 11185.1 11329.5 20:50:29 INFO - PROCESS | 1928 | -------- Summary: end -------- 20:50:29 INFO - PROCESS | 1928 | 20:50:29 INFO - TEST-INFO | 1928: exit 0 20:50:29 INFO - TEST-OK | dromaeo_css | took 1116124ms 20:50:29 INFO - TEST-START | kraken 20:50:29 INFO - operating with platform_type : mac_ 20:50:29 INFO - Initialising browser for kraken test... 20:50:29 INFO - TEST-INFO | started process 1934 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/tmpcVK8p8/profile http://localhost:55660/getInfo.html) 20:50:32 INFO - PROCESS | 1934 | 2016-12-20 20:50:32.497 plugin-container[1935:21837] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a43, name = 'com.apple.tsm.portname' 20:50:32 INFO - PROCESS | 1934 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:50:32 INFO - PROCESS | 1934 | 2016-12-20 20:50:32.500 plugin-container[1935:21837] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4523, name = 'com.apple.CFPasteboardClient' 20:50:32 INFO - PROCESS | 1934 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:50:32 INFO - PROCESS | 1934 | 2016-12-20 20:50:32.500 plugin-container[1935:21837] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:50:32 INFO - PROCESS | 1934 | __metrics Screen width/height:1600/1200 20:50:32 INFO - PROCESS | 1934 | colorDepth:24 20:50:32 INFO - PROCESS | 1934 | Browser inner width/height: 1024/689 20:50:32 INFO - PROCESS | 1934 | __metrics 20:50:32 ERROR - PROCESS | 1934 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3232: TypeError: this.tabs is undefined 20:50:33 INFO - TEST-INFO | 1934: exit 0 20:50:33 INFO - Browser initialized. 20:50:33 INFO - Running cycle 1/1 for kraken test... 20:50:33 INFO - TEST-INFO | started process 1936 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/tmpcVK8p8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:50:35 INFO - PROCESS | 1936 | 2016-12-20 20:50:35.682 plugin-container[1937:21955] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8943, name = 'com.apple.tsm.portname' 20:50:35 INFO - PROCESS | 1936 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:50:35 INFO - PROCESS | 1936 | 2016-12-20 20:50:35.683 plugin-container[1937:21955] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x431b, name = 'com.apple.CFPasteboardClient' 20:50:35 INFO - PROCESS | 1936 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:50:35 INFO - PROCESS | 1936 | 2016-12-20 20:50:35.683 plugin-container[1937:21955] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:50:45 INFO - PROCESS | 1936 | RSS: Main: 237203456 20:50:45 INFO - PROCESS | 1936 | 20:50:48 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55660/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55660/tests/kraken/driver.html?testName=audio-beat-detection) 20:50:49 INFO - PROCESS | 1936 | RSS: Main: 249659392 20:50:49 INFO - PROCESS | 1936 | 20:50:51 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55660/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55660/tests/kraken/driver.html?testName=audio-dft) 20:50:52 INFO - PROCESS | 1936 | RSS: Main: 257187840 20:50:52 INFO - PROCESS | 1936 | 20:50:56 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55660/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55660/tests/kraken/driver.html?testName=audio-fft) 20:50:56 INFO - PROCESS | 1936 | RSS: Main: 259760128 20:50:56 INFO - PROCESS | 1936 | 20:50:58 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55660/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55660/tests/kraken/driver.html?testName=audio-oscillator) 20:50:59 INFO - PROCESS | 1936 | RSS: Main: 261009408 20:50:59 INFO - PROCESS | 1936 | 20:51:01 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55660/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55660/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:51:01 INFO - PROCESS | 1936 | RSS: Main: 261324800 20:51:01 INFO - PROCESS | 1936 | 20:51:05 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55660/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55660/tests/kraken/driver.html?testName=imaging-darkroom) 20:51:05 INFO - PROCESS | 1936 | RSS: Main: 267587584 20:51:05 INFO - PROCESS | 1936 | 20:51:08 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55660/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55660/tests/kraken/driver.html?testName=imaging-desaturate) 20:51:09 INFO - PROCESS | 1936 | RSS: Main: 267644928 20:51:09 INFO - PROCESS | 1936 | 20:51:12 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55660/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55660/tests/kraken/driver.html?testName=json-parse-financial) 20:51:13 INFO - PROCESS | 1936 | RSS: Main: 267870208 20:51:13 INFO - PROCESS | 1936 | 20:51:14 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55660/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55660/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:51:15 INFO - PROCESS | 1936 | RSS: Main: 267165696 20:51:15 INFO - PROCESS | 1936 | 20:51:17 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55660/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55660/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:51:18 INFO - PROCESS | 1936 | RSS: Main: 267141120 20:51:18 INFO - PROCESS | 1936 | 20:51:20 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55660/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55660/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:51:21 INFO - PROCESS | 1936 | RSS: Main: 267186176 20:51:21 INFO - PROCESS | 1936 | 20:51:24 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55660/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55660/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:51:24 INFO - PROCESS | 1936 | RSS: Main: 267440128 20:51:24 INFO - PROCESS | 1936 | 20:51:27 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55660/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55660/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:51:28 INFO - PROCESS | 1936 | RSS: Main: 267292672 20:51:28 INFO - PROCESS | 1936 | 20:51:30 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55660/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55660/tests/kraken/driver.html?testName=ai-astar) 20:51:30 INFO - PROCESS | 1936 | RSS: Main: 268353536 20:51:30 INFO - PROCESS | 1936 | 20:51:30 INFO - PROCESS | 1936 | __start_tp_report 20:51:30 INFO - PROCESS | 1936 | _x_x_mozilla_page_load 20:51:30 INFO - PROCESS | 1936 | _x_x_mozilla_page_load_details 20:51:30 INFO - PROCESS | 1936 | |i|pagename|runs| 20:51:30 INFO - PROCESS | 1936 | |0;ai-astar;107;116;120;120;117;118;117;124;120;119 20:51:30 INFO - PROCESS | 1936 | |1;audio-beat-detection;144;111;126;192;148;117;115;114;133;114 20:51:30 INFO - PROCESS | 1936 | |2;audio-dft;203;203;237;272;206;285;281;200;198;197 20:51:30 INFO - PROCESS | 1936 | |3;audio-fft;83;60;58;86;61;63;74;87;97;86 20:51:30 INFO - PROCESS | 1936 | |4;audio-oscillator;86;72;86;71;70;73;83;72;72;84 20:51:30 INFO - PROCESS | 1936 | |5;imaging-gaussian-blur;134;113;113;114;125;115;168;173;118;173 20:51:30 INFO - PROCESS | 1936 | |6;imaging-darkroom;95;96;95;96;96;95;105;95;118;109 20:51:30 INFO - PROCESS | 1936 | |7;imaging-desaturate;127;76;145;149;115;94;92;93;92;99 20:51:30 INFO - PROCESS | 1936 | |8;json-parse-financial;55;56;55;55;57;57;57;58;57;58 20:51:30 INFO - PROCESS | 1936 | |9;json-stringify-tinderbox;71;70;104;125;122;118;73;70;103;103 20:51:30 INFO - PROCESS | 1936 | |10;stanford-crypto-aes;92;87;90;91;87;90;88;88;92;92 20:51:30 INFO - PROCESS | 1936 | |11;stanford-crypto-ccm;150;154;150;156;135;165;145;172;223;188 20:51:30 INFO - PROCESS | 1936 | |12;stanford-crypto-pbkdf2;173;174;175;172;177;177;175;175;174;175 20:51:30 INFO - PROCESS | 1936 | |13;stanford-crypto-sha256-iterative;68;68;68;67;76;80;83;89;69;61 20:51:30 INFO - PROCESS | 1936 | __end_tp_report 20:51:30 INFO - PROCESS | 1936 | __start_cc_report 20:51:30 INFO - PROCESS | 1936 | _x_x_mozilla_cycle_collect,946 20:51:30 INFO - PROCESS | 1936 | __end_cc_report 20:51:30 INFO - PROCESS | 1936 | __startTimestamp1482295890439__endTimestamp 20:51:30 INFO - PROCESS | 1936 | 20:51:30 INFO - PROCESS | 1936 | ------- Summary: start ------- 20:51:30 INFO - PROCESS | 1936 | Number of tests: 14 20:51:30 INFO - PROCESS | 1936 | 20:51:30 INFO - PROCESS | 1936 | [#0] ai-astar Cycles:10 Average:117.80 Median:119.50 stddev:4.42 (3.7%) stddev-sans-first:2.40 20:51:30 INFO - PROCESS | 1936 | Values: 107.0 116.0 120.0 120.0 117.0 118.0 117.0 124.0 120.0 119.0 20:51:30 INFO - PROCESS | 1936 | 20:51:30 INFO - PROCESS | 1936 | [#1] audio-beat-detection Cycles:10 Average:131.40 Median:129.50 stddev:25.03 (19.3%) stddev-sans-first:26.12 20:51:30 INFO - PROCESS | 1936 | Values: 144.0 111.0 126.0 192.0 148.0 117.0 115.0 114.0 133.0 114.0 20:51:30 INFO - PROCESS | 1936 | 20:51:30 INFO - PROCESS | 1936 | [#2] audio-dft Cycles:10 Average:228.20 Median:221.50 stddev:37.20 (16.8%) stddev-sans-first:38.32 20:51:30 INFO - PROCESS | 1936 | Values: 203.0 203.0 237.0 272.0 206.0 285.0 281.0 200.0 198.0 197.0 20:51:30 INFO - PROCESS | 1936 | 20:51:30 INFO - PROCESS | 1936 | [#3] audio-fft Cycles:10 Average:75.50 Median:84.50 stddev:14.09 (16.7%) stddev-sans-first:14.68 20:51:30 INFO - PROCESS | 1936 | Values: 83.0 60.0 58.0 86.0 61.0 63.0 74.0 87.0 97.0 86.0 20:51:30 INFO - PROCESS | 1936 | 20:51:30 INFO - PROCESS | 1936 | [#4] audio-oscillator Cycles:10 Average:76.90 Median:78.00 stddev:6.85 (8.8%) stddev-sans-first:6.43 20:51:30 INFO - PROCESS | 1936 | Values: 86.0 72.0 86.0 71.0 70.0 73.0 83.0 72.0 72.0 84.0 20:51:30 INFO - PROCESS | 1936 | 20:51:30 INFO - PROCESS | 1936 | [#5] imaging-gaussian-blur Cycles:10 Average:134.60 Median:129.50 stddev:26.19 (20.2%) stddev-sans-first:27.78 20:51:30 INFO - PROCESS | 1936 | Values: 134.0 113.0 113.0 114.0 125.0 115.0 168.0 173.0 118.0 173.0 20:51:30 INFO - PROCESS | 1936 | 20:51:30 INFO - PROCESS | 1936 | [#6] imaging-darkroom Cycles:10 Average:100.00 Median:95.00 stddev:8.01 (8.4%) stddev-sans-first:8.29 20:51:30 INFO - PROCESS | 1936 | Values: 95.0 96.0 95.0 96.0 96.0 95.0 105.0 95.0 118.0 109.0 20:51:30 INFO - PROCESS | 1936 | 20:51:30 INFO - PROCESS | 1936 | [#7] imaging-desaturate Cycles:10 Average:108.20 Median:92.00 stddev:24.72 (26.9%) stddev-sans-first:25.26 20:51:30 INFO - PROCESS | 1936 | Values: 127.0 76.0 145.0 149.0 115.0 94.0 92.0 93.0 92.0 99.0 20:51:30 INFO - PROCESS | 1936 | 20:51:30 INFO - PROCESS | 1936 | [#8] json-parse-financial Cycles:10 Average:56.50 Median:57.00 stddev:1.18 (2.1%) stddev-sans-first:1.12 20:51:30 INFO - PROCESS | 1936 | Values: 55.0 56.0 55.0 55.0 57.0 57.0 57.0 58.0 57.0 58.0 20:51:30 INFO - PROCESS | 1936 | 20:51:30 INFO - PROCESS | 1936 | [#9] json-stringify-tinderbox Cycles:10 Average:95.90 Median:97.50 stddev:22.78 (23.4%) stddev-sans-first:22.30 20:51:30 INFO - PROCESS | 1936 | Values: 71.0 70.0 104.0 125.0 122.0 118.0 73.0 70.0 103.0 103.0 20:51:30 INFO - PROCESS | 1936 | 20:51:30 INFO - PROCESS | 1936 | [#10] stanford-crypto-aes Cycles:10 Average:89.70 Median:90.50 stddev:2.06 (2.3%) stddev-sans-first:2.01 20:51:30 INFO - PROCESS | 1936 | Values: 92.0 87.0 90.0 91.0 87.0 90.0 88.0 88.0 92.0 92.0 20:51:30 INFO - PROCESS | 1936 | 20:51:30 INFO - PROCESS | 1936 | [#11] stanford-crypto-ccm Cycles:10 Average:163.80 Median:160.50 stddev:25.56 (15.9%) stddev-sans-first:26.62 20:51:30 INFO - PROCESS | 1936 | Values: 150.0 154.0 150.0 156.0 135.0 165.0 145.0 172.0 223.0 188.0 20:51:30 INFO - PROCESS | 1936 | 20:51:30 INFO - PROCESS | 1936 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.70 Median:175.00 stddev:1.57 (0.9%) stddev-sans-first:1.54 20:51:30 INFO - PROCESS | 1936 | Values: 173.0 174.0 175.0 172.0 177.0 177.0 175.0 175.0 174.0 175.0 20:51:30 INFO - PROCESS | 1936 | 20:51:30 INFO - PROCESS | 1936 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.90 Median:72.50 stddev:8.72 (12.0%) stddev-sans-first:9.07 20:51:30 INFO - PROCESS | 1936 | Values: 68.0 68.0 68.0 67.0 76.0 80.0 83.0 89.0 69.0 61.0 20:51:30 INFO - PROCESS | 1936 | -------- Summary: end -------- 20:51:30 INFO - PROCESS | 1936 | 20:51:31 INFO - TEST-INFO | 1936: exit 0 20:51:31 INFO - TEST-OK | kraken | took 61604ms 20:51:31 INFO - SUITE-END | took 1177s 20:51:31 INFO - Completed test suite (00:19:37) 20:51:31 INFO - javascript benchmark 20:51:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7161.473878038324, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3360.647132051133, 3386.932261354773, 3412.5734283214597, 3414.573178352706, 3423.1784371750778, 5649.49154576088, 5743.052396354602, 5774.82675519734, 5783.103618939061, 5803.361630220683, 5667.4049224831415, 5727.198192253078, 5730.169125476803, 5753.626014309078, 5760.510356619692, 4309.82760689572, 5203.505666961642, 5275.894482110355, 5297.073012222865, 5311.893762124755, 4271.551487093857, 5229.921551176716, 5297.417284098746, 5360.865978350534, 5365.651232669884, 468.2833815481392, 4529.207388706963, 5243.213517972296, 5277.182036784305, 5380.623356365056, 5257.395399529065, 5261.421243663194, 5295.338082739658, 5313.521783039545, 5324.227986941911, 5211.322528071327, 5223.6343455958095, 5226.608004399647, 5260.447652996419, 5351.224072509464, 5430.59270554706, 5695.231143795576, 5707.62900411476, 5775.509081728058, 5795.101759227285, 5784.582777219566, 6141.486007784275, 6159.952808022647, 6170.7531698732, 6312.495000399957, 5945.851353716149, 6238.2825975013, 6244.0, 6253.53098517613, 6288.371162883721, 5768.278965129359, 5929.407059294079, 5954.404559544011, 5965.164876917235, 5976.850578735566, 6213.813585592439, 6232.968835155795, 6246.562740608114, 6254.655993920378, 6257.906131408033, 6155.169052177988, 6196.47329976957, 6202.689865506797, 6216.098665693449, 6238.376162383725, 6272.717727702265, 6274.466670333073, 6301.590396624205, 6304.873902522016, 6309.179806625201, 6126.609281623701, 6136.6722581676495, 6175.011998080286, 6226.533010024175, 6283.842903927347, 4847.248676455155, 6268.059791031291, 6277.937220627827, 6279.811605651837, 6288.088227207029, 4858.17411040124, 4898.657094003385, 4900.632452566, 4904.6566740327835, 5007.549320561167, 4411.713238639478, 4450.198964186413, 4458.77706114693, 4499.662525310615, 4537.274036154266, 5742.253507044057, 5800.4489573490455, 5843.0, 5906.202662640489, 5920.407959204045, 6133.0, 6158.876822463526, 6214.285357183891, 6260.749570017239, 6260.843478913063, 5962.105684147413, 5970.104484327386, 5975.611585246945, 5982.4017598239825, 5983.311919129356, 5483.698396588146, 5486.957478079232, 5491.75287112073, 5507.091329930517, 5538.695371754592, 5134.768935397841, 6167.074938759132, 6250.906236406367, 6266.968665156645, 6439.839004024937, 5162.199859021857, 5581.162825576196, 6043.456088952015, 6165.753369865155, 6257.4681152102585, 6352.333005034405, 6355.650439225887, 6384.138141350858, 6430.935690643034, 6453.806385808433, 4435.2016637005645, 6105.633661980295, 6124.3809352984035, 6266.905996409966, 6294.1188233648245, 5968.552358573212, 6062.90905636407, 6211.409916057967, 6244.937550624436, 6263.498920086473, 6158.230221222348, 6174.938250617616, 6191.349908259568, 6193.81418557444, 6195.101710252078, 5605.32736071674, 5948.167256583995, 6118.60229085108, 6199.256089269316, 6200.534959877937, 5703.5722320826935, 5889.970550147222, 5960.433758792908, 6017.608855424383, 6077.908831367445, 4414.713043652152, 6115.149072927005, 6122.489639783254, 6135.447809697149, 6156.630602163706, 5882.088276317178, 5928.4960778334325, 5961.0, 6063.878722425438, 6132.9080063799975], "value": 5643.368079567824, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [438.9517153113155, 452.30571073402325, 482.3416037109346, 517.4334104155951, 517.844646606018, 36248.0, 39266.60733392665, 39341.01647458815, 39438.40842387366, 39565.80217098914, 34367.968960931146, 40258.39612405816, 40931.59068409315, 40953.7952310238, 40958.18083638325, 8438.957805210965, 8651.65393384264, 9207.677731279424, 9526.476043817587, 11319.03788178006, 11258.4370781461, 11558.595449159282, 11571.132165087653, 11591.884081159207, 11597.362145082017, 11552.826707599394, 11599.362035088066, 11607.303561786277, 11613.0, 11625.534978600846, 11448.48481818316, 11538.94230528842, 11539.48072336747, 11552.76894462106, 11556.711082223012, 9543.618255269781, 11402.2588531746, 11412.31526108436, 11491.482883270273, 11518.88481115195, 39274.0, 39728.0, 39735.8013209935, 39821.60178398237, 39847.20305593901, 3404.863805447779, 3508.9649103508896, 3614.421692529209, 3627.0, 3872.5740168581433, 29959.70040299591, 30745.538816917764, 30770.692293077005, 30841.383172336653, 30854.537181942287, 3150.2596889730894, 3265.755068369858, 3272.640009598942, 3290.6380298167182, 3369.3261347730554, 502118.0, 506842.0, 507361.0, 508640.0, 508915.0, 60483.0, 60693.0, 60847.391526085055, 60947.0, 61035.0, 52457.0, 61267.77464450686, 63786.0432093519, 63812.36187638157, 63836.04245936315, 15697.215139242991, 15916.68166636684, 15926.68146637084, 16036.438724644815, 16259.430919917746, 11107.55569777216, 11324.660260192208, 11976.0, 12204.938975305244, 12269.938650306869, 16823.07473088992, 16901.91549042247, 16916.492505224618, 16928.915355423145, 16944.57638559046, 5327.920081198785, 5461.508464238158, 5804.506616937608, 6100.359462256488, 6306.873862522724, 932.2681694869494, 970.7427531704104, 976.1019861727228, 1000.3247807729754, 1066.589363095214, 23053.42366440819, 28196.25986315094, 30252.03361781477, 30432.54351184734, 30632.54051189234, 891.9375643705008, 1062.9627963021258, 1085.1915323084195, 1093.6992327110108, 1223.2415902140729, 13316.800247996287, 15115.546533604009, 15388.692226155637, 15468.071915685096, 16092.919535402249, 606210.0, 608698.0, 609648.0, 610289.9485502545, 611075.944620274, 46770.5322946773, 58343.70828145832, 58467.70766146142, 58652.12021819676, 58920.41079589149, 1308.3915979069777, 1345.9730805383838, 1354.5056054540023, 1370.0615078670944, 1384.3147641917315, 25955.610665840406, 29227.123186304445, 29936.85031574828, 30302.184381324623, 30548.694513054586, 50068.0, 50660.493395065576, 50667.746661267935, 50740.238896415845, 50816.49183508118, 455963.0, 542876.0, 544559.0, 546324.0, 546725.0, 32906.50640240446, 39594.81215563538, 40003.1999360017, 40383.0, 40565.59434405618, 45602.54397455983, 46498.76750616225, 46673.53326466828, 47601.0, 47929.28106078342, 31963.840180798947, 34531.61873525031, 38563.98780053058, 39649.80175099106, 40122.0, 37950.430743538316, 38103.0, 38313.233735325695, 39158.0, 39373.01567460837, 37426.0, 38039.429408559445, 38159.42760858534, 38275.80862095672, 38361.42457863079, 26349.20952371432, 31907.521387179677, 33203.50194747129, 34378.0, 34520.48219276663, 25242.49515009653, 25507.872460637576, 25582.976680933298, 25953.0, 26009.73990260149], "value": 20076.389521316447, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.9692030796921, 335.23665519368126, 377.1758707224714, 385.74348058541074, 396.02775186916125, 18073.457796266117, 18840.246390144395, 19743.40769776907, 19979.90010049948, 20009.999500025013, 19190.904045479787, 19683.0, 19848.503787405323, 19968.400947971546, 20167.991600419995, 4440.489343725464, 4558.703684260529, 5411.810586629469, 5415.10650742627, 5421.918671219935, 5278.155495120782, 5593.692346920918, 5889.0, 5933.821985340447, 5984.910226346587, 5008.223725322581, 5903.586748927577, 5958.136070269808, 5979.07324364722, 5987.131865879445, 5131.384233891919, 6386.457151142158, 6442.0, 6451.129097571815, 6452.612843229421, 5530.0, 5686.516646085088, 5708.315002199763, 5714.79989202268, 5721.570882183812, 10598.205134614858, 12345.0, 13034.41345139471, 13049.347532623331, 13071.346432678329, 2002.469345623411, 2260.468789834404, 2402.1023391520853, 2681.463707258556, 2886.5237235856057, 9591.184749296319, 9627.614895404175, 9859.950700246452, 10063.949680251551, 10230.232732545015, 1793.157216108427, 1827.661882551732, 1860.5627677495777, 1865.0954287170712, 1898.4779185723899, 88286.0, 94149.0, 111102.99999999919, 113599.0, 117573.41213293879, 11485.598004069818, 11487.655370338904, 11649.417529123679, 11669.040657720434, 11793.29240245588, 14505.057171283834, 14618.195999220145, 14626.707465850623, 14639.268036598127, 14649.047811892193, 7176.815825388857, 8169.836603268021, 8321.708740194064, 8358.83282334362, 8413.158684131538, 7776.844463110706, 7921.089074756362, 8092.473989190684, 8160.0207975042185, 8274.793130171794, 3923.7645741255615, 4523.5024147343765, 4599.586037256663, 4603.424571928509, 4678.619807156897, 1634.7793047938376, 1867.5424520992171, 1914.9712754308696, 1937.225109956029, 1953.6776431888863, 645.5642441352069, 790.9802254943558, 816.2449733996029, 884.7655371326475, 909.7543663210896, 20152.69007514507, 21539.569208616052, 21658.783412165678, 21837.0, 22111.226107086175, 878.9692360767342, 933.425943045036, 966.7389804752678, 975.1857199238574, 1001.7846163074973, 10685.946570267099, 11381.203315767982, 11392.601258956063, 11398.316101033965, 11448.828267575993, 259957.0, 262184.0, 262914.0, 264916.0, 290472.0, 49941.0, 81997.0, 102173.4673979891, 116352.0, 119302.0, 947.6920000999703, 967.579103090158, 980.9705708828603, 987.8468837330225, 995.3977843404781, 14773.630659233606, 18980.810191897905, 19187.424377268704, 19201.903990479957, 19229.0, 45851.541484584726, 50652.49347506478, 52976.73511632417, 53473.73263133659, 54217.0, 52582.211266830265, 68283.31716682969, 70104.6494767523, 71758.56482870418, 74904.62547687227, 10900.563977440814, 10951.83572246433, 11168.888311116785, 11409.828852567383, 12133.878661213275, 12961.41673624708, 13109.0, 13256.0, 13397.53108641193, 13459.519452859982, 11219.187473298398, 11289.322640641587, 11309.20835541537, 11416.915393037649, 11474.94262528682, 8538.914610853812, 10987.560497580007, 11380.260283081574, 11397.430128493377, 11517.42412879336, 11177.16171287173, 11327.263727857662, 11665.416729163677, 11746.58886938953, 11779.941100294443, 11793.174477786473, 12050.975667068047, 12064.396780160781, 12104.092193085378, 12182.512699492276, 11052.0, 11167.776644466903, 11468.598599048993, 11503.194776365573, 11565.595204167814], "value": 9119.815590795282, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1484.6214215375078, 1501.8873584481164, 1721.0792226159008, 1840.07996001999, 1858.907054647268, 7547.13207981081, 7872.97651305331, 8048.476849004818, 8535.359848011401, 8556.614952327145, 6721.764738234163, 8320.95839520803, 8392.58037098144, 8556.486610803357, 8572.571371431435, 2287.8856057197113, 2340.0, 2344.3084290134366, 2347.3779448446176, 2353.5292941411785, 2338.485533182697, 2357.8231632627535, 2360.3862995621175, 2378.5124049569795, 2379.797717194032, 2275.4993901341677, 2281.8288628352857, 2311.191083120911, 2312.5259321838985, 2321.814254859607, 1961.744973153488, 2267.1611503743557, 2290.7709229077127, 2306.515631717333, 2314.5023819878807, 1825.7535232743542, 2248.9887550562144, 2322.512272422793, 2346.0967527501866, 2350.3536527454917, 8290.792730181733, 8346.123657015987, 8358.331333493306, 8402.537860417675, 8492.74521764348, 5014.5737612303, 5143.459936706667, 5185.766640501187, 5191.195364718475, 5193.948060519384, 2588.6117082437595, 3310.874302737057, 3349.8492567834505, 3575.3206890690685, 3602.7478076534653, 1034.053840735726, 1055.6569115037644, 1072.4208927179313, 1079.854219680341, 1091.9781604367947, 10913.83629245562, 10973.890261097367, 11042.503087361089, 11055.502502387411, 11056.170787190913, 3449.724022078228, 4211.810468528924, 4536.523665015192, 4596.839110631178, 4703.0, 9374.484403357745, 9451.243900487945, 9457.007014263541, 9460.57427415768, 9483.193928516153, 5189.844304670866, 5209.609279304067, 5211.296474975906, 5211.635185536976, 5218.869528261824, 4309.978450107729, 4325.243082460557, 4568.086382723469, 4606.239970404846, 4730.0, 3767.5290588676417, 3852.8266228019806, 3884.76691398517, 3912.7065470089287, 3917.8432862685745, 1094.7317907112808, 1267.828843106169, 1285.6978610026636, 1298.4222021200476, 1310.0829419406264, 786.614558866163, 892.7634176943116, 912.9136327769933, 925.7037747920735, 944.2304521814699, 5143.202803565453, 5328.600354973315, 5686.289213848269, 5797.884042319214, 5888.617239879394, 909.0909090909091, 922.4972390047307, 931.6646007437317, 931.9767005824908, 939.9624015039321, 3759.6240375962184, 3775.0, 3797.0, 3872.7994321760343, 3906.1015966327905, 8448.57757112142, 9764.316497845082, 10304.536295866554, 10312.484375781181, 10345.844812327821, 10179.7455063624, 10250.487475626189, 10262.640807571699, 10274.383536987805, 10303.0, 7317.524360916524, 7444.367228785614, 8000.51996880189, 8006.279434850891, 8034.799130021679, 5761.711914404347, 5863.0, 5877.059670452707, 5981.491573216325, 5993.670348130895, 8120.512769233865, 8753.0, 9187.954060229657, 9390.483523406278, 9402.717918462457, 8923.483007888804, 9032.187103160748, 9553.0, 9579.281553883626, 9679.66121185755, 6609.339066093352, 6862.622555759481, 7031.683574239274, 7172.820679482846, 7337.3029562191505, 7886.526808391515, 8142.715004974797, 8155.836883262183, 8156.755297340852, 8184.181581841768, 6652.93553031532, 6757.222919364336, 7117.750878719427, 7289.343959043712, 7340.779776606711, 6901.551399159039, 7067.893981590383, 7166.175889772743, 7170.856582868418, 7203.423726101794, 6675.299093595297, 6768.864622707617, 6789.898151527632, 6829.726810927507, 6855.280195579392, 4313.61177494027, 4756.167670657552, 4840.830570930141, 4921.581665558468, 4989.750512474435, 4751.762411879323, 4796.472388037383, 4863.659543831898, 4959.528844759742, 4986.825461108844], "value": 4413.189412864234, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [283.93753374257665, 358.4444111626979, 364.7465011816787, 365.3934468781821, 369.92786406650714, 5220.973895130529, 5289.8626795239015, 5343.0, 5491.890162196753, 5544.835584527249, 4133.028738246509, 4321.956780432195, 5101.591872650189, 5342.813001544947, 5345.251664766935, 3951.8221680024467, 4181.560936101711, 4200.096979149483, 4206.390073439349, 4218.810153543082, 3738.140227747606, 3789.2800367930004, 3873.0123818426264, 3991.2616166009234, 4224.915501689964, 3291.934161316772, 3413.743969202307, 3996.2606917720314, 3998.600139985993, 4056.9391459128137, 3195.72836308914, 3637.4725664778725, 4018.1963607278662, 4036.354183330682, 4059.6955228357697, 3454.844531996041, 3503.4394496880377, 3517.736169787276, 3521.9295614087832, 3532.947005794915, 4995.725235112068, 5192.71440070796, 5273.419923808378, 5286.145481440018, 5308.92036619451, 4371.34429835524, 4597.517260687613, 4600.5169457207185, 4608.976955115203, 4611.146937816515, 4687.437507499088, 5038.722870242135, 5182.170852663593, 5638.674911395859, 5658.3503640152285, 2745.890164393422, 2951.8192722910794, 3342.331533693247, 3785.6403641653997, 3824.541055073409, 4701.529847015306, 4825.541573550507, 5916.556258280603, 5942.187632772003, 5949.583529152961, 2977.7171168738937, 3604.7837129772297, 3847.115163512408, 3887.241987812406, 3898.064464528493, 3343.5820522434697, 3913.4716813229848, 3933.92132157361, 3937.488126543531, 3950.7036972227197, 3903.785291808978, 4426.490953540319, 4556.544345565483, 4567.474740404829, 4607.516210797885, 4487.125010622963, 4953.331300274489, 5700.0, 5704.4295570443455, 5772.018756811352, 1070.4166229404993, 1136.9658910232706, 1220.2617416462913, 1227.5274019502556, 1252.5303011370736, 754.6905768634944, 788.578110710767, 863.684755064397, 864.9221570058725, 885.5034990865416, 4105.384192371109, 4275.914481710411, 4794.082367053206, 4979.90040199194, 5090.287359769635, 4740.288956656529, 5414.025475414384, 5456.399796022434, 5584.581156413204, 5593.609016921779, 4223.978880105641, 5353.57171426285, 5457.290552228185, 5469.179623056494, 5475.67145971243, 2517.728547083711, 2595.532804095243, 2925.7805664575244, 2972.176432389239, 2973.36072744361, 5095.541401273903, 5321.148616221386, 5456.645318054314, 5484.451554844483, 5733.283339582552, 4720.362751028636, 5192.844214673566, 5494.972525137428, 5697.772089116389, 5797.826065218051, 4915.3855768029, 5842.472084134744, 5846.327672317653, 5852.502537284294, 5874.295084589877, 4749.19530578378, 5262.763175657028, 5862.120681897579, 5883.441073098049, 5915.704214789157, 5182.481751824787, 5770.13447982806, 5798.59409841307, 5829.562782791223, 5885.352611212848, 5709.543236540983, 6051.334353221054, 6107.236595425572, 6125.663088530174, 6162.3026184289065, 3574.2315402188656, 3803.2203398302727, 3853.2678791029616, 3867.226554689017, 3888.96111038886, 5165.044466773659, 5185.9887321971855, 5413.512783849473, 5667.4615911488345, 5853.843770922693, 4973.428055773632, 5215.5135786300725, 5672.730907636978, 5674.375818660027, 5723.914141287967, 5318.4149743527405, 5535.944640553543, 5660.773569057192, 5671.773129074956, 5676.460736230051, 4517.985036609295, 4598.563136502027, 5055.69665820052, 5294.549963253013, 5475.5907400743545, 5615.747291371979, 5688.175214593944, 5697.829065128053, 5704.486596206361, 5716.228309178374, 4922.015596880567, 4980.526849949249, 5273.841784746463, 5517.5585953124455, 5565.8608534785335], "value": 4021.624911093517, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [742.8254360225346, 756.4251169111474, 767.3094215206314, 794.9284564389204, 850.8000619854333, 14608.415663373466, 15265.465708700198, 15669.373225070998, 15724.0, 15864.762028569581, 12372.381380930963, 14052.859471405283, 14290.428382864699, 14346.713065738679, 14486.420543178287, 863.5423225690379, 887.5207388010466, 890.5191196753746, 891.6968230801526, 896.596531560797, 704.2218348724663, 730.755197009, 872.9388942774009, 909.4270609515995, 922.2944447497636, 755.8374949385881, 832.8917240758723, 941.4163218804351, 944.2681921510815, 951.0442005784178, 855.0, 864.3906046237388, 931.6692574136151, 932.7947851472666, 934.9298802589799, 683.7504310926525, 693.9826504337432, 698.5983059740619, 700.5726506830829, 700.7967689370076, 14327.140371577634, 14857.2571371431, 15014.324355404033, 15053.94622376434, 15137.924310378377, 12081.214721043192, 12580.616132225448, 13713.588592342247, 13899.444022239099, 14058.789118163235, 10878.782424351548, 11579.76840463186, 11724.0, 11933.04535637147, 11992.760144797143, 3668.3213605482806, 3680.6319368063246, 3696.815159242027, 3703.981480092609, 3827.7320587558884, 103934.0, 106475.0, 107475.46262268639, 108210.0, 109020.0, 8941.687040953535, 10253.33353332031, 10263.12763415118, 10329.32859364154, 10394.896051039545, 19960.20159193645, 20122.89938550298, 20143.697844532344, 20143.798562014486, 20146.496337591383, 7739.883901741478, 7742.706362640735, 7799.571023593643, 7879.881801772978, 7888.921110788933, 4224.8905044245175, 4332.545082766327, 4380.824767009284, 4416.933745993812, 4446.911061778746, 3553.857845686195, 3607.9458808117897, 3655.250673611903, 3656.5246517952496, 3669.7247706422127, 553.9196816461671, 642.6882961763541, 647.1489990662265, 660.21764209412, 662.7448432353578, 537.4303238567131, 570.8886767080462, 576.3314555116045, 599.9460048595647, 650.4373716735084, 8261.339092872557, 8586.398952073296, 8806.339524535686, 9266.11971862672, 9367.531623418801, 617.607819034907, 640.4908098062052, 650.2554575011585, 656.7832615236961, 691.1774987764544, 3434.587849458031, 4310.633261589342, 4474.485434175046, 4539.546045395434, 4569.565891240327, 99956.0, 108134.0, 108301.54532228738, 110483.44758276157, 112010.0, 69327.65336173387, 69834.3016569838, 92368.0, 94626.58060129103, 95197.0, 69813.65093174501, 82408.17591824125, 82699.75950360749, 84031.0, 84742.15257847466, 7429.962850185714, 8059.234372734581, 8383.95808020956, 8481.491110533389, 8523.872141917753, 52357.21464177964, 53011.20483192832, 53822.0, 53860.461395385544, 54997.0, 71676.28323716905, 77453.0, 78380.0, 78390.6080469594, 81905.0, 11438.771224575295, 12622.179558328678, 12830.615081547568, 12834.0, 13056.543020994219, 13086.672833179247, 13939.233342165873, 14255.287235637968, 14561.400003995243, 14720.337584808494, 11968.0, 12004.579839705568, 12027.21823081497, 12349.19730217533, 12408.193467424588, 11033.83449248278, 13233.3383330835, 13354.732905342033, 13640.590782276548, 13774.311284435938, 12623.053271004896, 12688.936555317165, 13059.869401306245, 13341.132826366254, 13359.599212023266, 10988.835167472334, 11109.944450277697, 11161.441927903736, 11208.0, 12063.81904271419, 9540.33217674756, 10095.596176153165, 11141.498632561716, 11185.105191584817, 11329.525899693375], "value": 7355.335975554728, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1626.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 116.0, 120.0, 120.0, 117.0, 118.0, 117.0, 124.0, 120.0, 119.0], "value": 117.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 111.0, 126.0, 192.0, 148.0, 117.0, 115.0, 114.0, 133.0, 114.0], "value": 131.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [203.0, 203.0, 237.0, 272.0, 206.0, 285.0, 281.0, 200.0, 198.0, 197.0], "value": 228.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 60.0, 58.0, 86.0, 61.0, 63.0, 74.0, 87.0, 97.0, 86.0], "value": 75.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 72.0, 86.0, 71.0, 70.0, 73.0, 83.0, 72.0, 72.0, 84.0], "value": 76.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 113.0, 113.0, 114.0, 125.0, 115.0, 168.0, 173.0, 118.0, 173.0], "value": 134.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 96.0, 96.0, 95.0, 105.0, 95.0, 118.0, 109.0], "value": 100.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 76.0, 145.0, 149.0, 115.0, 94.0, 92.0, 93.0, 92.0, 99.0], "value": 108.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 55.0, 55.0, 57.0, 57.0, 57.0, 58.0, 57.0, 58.0], "value": 56.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 104.0, 125.0, 122.0, 118.0, 73.0, 70.0, 103.0, 103.0], "value": 95.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 87.0, 90.0, 91.0, 87.0, 90.0, 88.0, 88.0, 92.0, 92.0], "value": 89.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 154.0, 150.0, 156.0, 135.0, 165.0, 145.0, 172.0, 223.0, 188.0], "value": 163.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 174.0, 175.0, 172.0, 177.0, 177.0, 175.0, 175.0, 174.0, 175.0], "value": 174.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 67.0, 76.0, 80.0, 83.0, 89.0, 69.0, 61.0], "value": 72.9, "unit": "score"}]}]} 20:51:31 INFO - Return code: 0 20:51:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:51:31 ERROR - # TBPL SUCCESS # 20:51:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:51:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:51:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:51:31 INFO - 2016-12-20 20:51:31.759 screenresolution[1941:22329] starting screenresolution argv=screenresolution get 20:51:31 INFO - 2016-12-20 20:51:31.762 screenresolution[1941:22329] Display 0: 1600x1200x32@50 20:51:31 INFO - 2016-12-20 20:51:31.768 screenresolution[1942:22332] starting screenresolution argv=screenresolution list 20:51:31 INFO - Available Modes on Display 0 20:51:31 INFO - 1600x1200x32@50 1152x870x32@75 1280x1024x32@75 20:51:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:51:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:51:31 INFO - 640x480x32@60 1280x1024x32@60 1600x1200x32@60 20:51:31 INFO - 1280x960x32@60 1280x960x32@75 1344x1008x32@50 20:51:32 INFO - Graphics/Displays: 20:51:32 INFO - Intel Iris: 20:51:32 INFO - Chipset Model: Intel Iris 20:51:32 INFO - Type: GPU 20:51:32 INFO - Bus: Built-In 20:51:32 INFO - VRAM (Dynamic, Max): 1536 MB 20:51:32 INFO - Vendor: Intel (0x8086) 20:51:32 INFO - Device ID: 0x0a2e 20:51:32 INFO - Revision ID: 0x0009 20:51:32 INFO - Displays: 20:51:32 INFO - Display: 20:51:32 INFO - Resolution: 1600 x 1200 @ 50 Hz 20:51:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:51:32 INFO - Display Serial Number: 1600x1200 50 20:51:32 INFO - Main Display: Yes 20:51:32 INFO - Mirror: Off 20:51:32 INFO - Online: Yes 20:51:32 INFO - Rotation: Supported 20:51:32 INFO - Return code: 0 20:51:32 INFO - Running post-action listener: _resource_record_post_action 20:51:32 INFO - [mozharness: 2016-12-21 04:51:32.007643Z] Finished run-tests step (success) 20:51:32 INFO - Running post-run listener: _resource_record_post_run 20:51:32 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 659456; Write bytes: 552743936; Read time: 10; Write time: 1699 20:51:32 INFO - TinderboxPrint: CPU usage
54.6% 20:51:32 INFO - TinderboxPrint: I/O read bytes / time
659,456 / 10 20:51:32 INFO - TinderboxPrint: I/O write bytes / time
552,743,936 / 1,699 20:51:32 INFO - TinderboxPrint: CPU idle
2,178.9 (45.4%) 20:51:32 INFO - TinderboxPrint: CPU system
974.9 (20.3%) 20:51:32 INFO - TinderboxPrint: CPU user
1,647.9 (34.3%) 20:51:32 INFO - TinderboxPrint: Swap in / out
250,662,912 / 0 20:51:32 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 247699968; Write bytes: 240320512; Read time: 18351; Write time: 429 20:51:32 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 622592; Write bytes: 300868608; Read time: 9; Write time: 1236 20:51:32 INFO - Running post-run listener: _upload_blobber_files 20:51:32 INFO - Blob upload gear active. 20:51:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:51:32 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 20:51:32 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:51:32 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:51:32 INFO - (blobuploader) - INFO - Open directory for files ... 20:51:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 20:51:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:51:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:51:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 20:51:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:51:34 INFO - (blobuploader) - INFO - Done attempting. 20:51:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 20:51:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:51:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:51:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 20:51:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:51:35 INFO - (blobuploader) - INFO - Done attempting. 20:51:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 20:51:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:51:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:51:35 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 20:51:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:51:35 INFO - (blobuploader) - INFO - Done attempting. 20:51:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:51:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:51:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:51:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:51:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:51:38 INFO - (blobuploader) - INFO - Done attempting. 20:51:38 INFO - (blobuploader) - INFO - Iteration through files over. 20:51:38 INFO - Return code: 0 20:51:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:51:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:51:38 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/5d5f746a5ef7e6bcbd04edd298379e55a4292bd58512c2ab6074fce2812bc09f35a8c1f2b481e77cface1d5fc5c0f146f6c9cdc00f76ae7b54a77b2726e3ed12", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9ee847a9addaf1c600015e5a3ea0bcd18e5ea54b2243c3e0e95bf425c547beb07328cae4875589977aa2c47849d88d9be93e995379c2d459511e596d870460db", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/1e4e46e45b8fc5ddfff6a33b03fd540852b1442b776d6406916d54bb696abbeebad73b5377f3b6fd8c204b3c4fe2699fbaa4a6c434fb87fa3006e6641ee99e3d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/084a83a54f79db90b58cc4a64259834227e77320a070124297286d4a908417e4b0d11b4585bae1efae16ddefa51f96383402174923a981191c241ca28da9a03a"} 20:51:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:51:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:51:38 INFO - Contents: 20:51:38 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/5d5f746a5ef7e6bcbd04edd298379e55a4292bd58512c2ab6074fce2812bc09f35a8c1f2b481e77cface1d5fc5c0f146f6c9cdc00f76ae7b54a77b2726e3ed12", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9ee847a9addaf1c600015e5a3ea0bcd18e5ea54b2243c3e0e95bf425c547beb07328cae4875589977aa2c47849d88d9be93e995379c2d459511e596d870460db", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/1e4e46e45b8fc5ddfff6a33b03fd540852b1442b776d6406916d54bb696abbeebad73b5377f3b6fd8c204b3c4fe2699fbaa4a6c434fb87fa3006e6641ee99e3d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/084a83a54f79db90b58cc4a64259834227e77320a070124297286d4a908417e4b0d11b4585bae1efae16ddefa51f96383402174923a981191c241ca28da9a03a"} 20:51:38 INFO - Running post-run listener: copy_logs_to_upload_dir 20:51:38 INFO - Copying logs to upload dir... 20:51:38 INFO - mkdir: /builds/slave/test/build/upload/logs 20:51:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.851450 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-12-20 20:51:38.209180) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-20 20:51:38.212847) ========= 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.7LOscDetMk/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.XTqUNlknZQ/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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/Graphics/sha512/5d5f746a5ef7e6bcbd04edd298379e55a4292bd58512c2ab6074fce2812bc09f35a8c1f2b481e77cface1d5fc5c0f146f6c9cdc00f76ae7b54a77b2726e3ed12", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9ee847a9addaf1c600015e5a3ea0bcd18e5ea54b2243c3e0e95bf425c547beb07328cae4875589977aa2c47849d88d9be93e995379c2d459511e596d870460db", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/1e4e46e45b8fc5ddfff6a33b03fd540852b1442b776d6406916d54bb696abbeebad73b5377f3b6fd8c204b3c4fe2699fbaa4a6c434fb87fa3006e6641ee99e3d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/084a83a54f79db90b58cc4a64259834227e77320a070124297286d4a908417e4b0d11b4585bae1efae16ddefa51f96383402174923a981191c241ca28da9a03a"} build_url:https://queue.taskcluster.net/v1/task/MK8ByD-8S8Ke-Ab9Sr7Vqg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011260 build_url: 'https://queue.taskcluster.net/v1/task/MK8ByD-8S8Ke-Ab9Sr7Vqg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/5d5f746a5ef7e6bcbd04edd298379e55a4292bd58512c2ab6074fce2812bc09f35a8c1f2b481e77cface1d5fc5c0f146f6c9cdc00f76ae7b54a77b2726e3ed12", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9ee847a9addaf1c600015e5a3ea0bcd18e5ea54b2243c3e0e95bf425c547beb07328cae4875589977aa2c47849d88d9be93e995379c2d459511e596d870460db", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/1e4e46e45b8fc5ddfff6a33b03fd540852b1442b776d6406916d54bb696abbeebad73b5377f3b6fd8c204b3c4fe2699fbaa4a6c434fb87fa3006e6641ee99e3d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/084a83a54f79db90b58cc4a64259834227e77320a070124297286d4a908417e4b0d11b4585bae1efae16ddefa51f96383402174923a981191c241ca28da9a03a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-20 20:51:38.248177) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 20:51:38.248549) ========= 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.7LOscDetMk/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.XTqUNlknZQ/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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.005229 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 20:51:38.297365) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-20 20:51:38.297692) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-20 20:51:38.298031) ========= ========= Total master_lag: 0.46 =========