builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0072 starttime: 1482126727.17 results: success (0) buildid: 20161218202435 builduid: bac23a1e40c9400c902e2e4a8b81ca83 revision: 863c2b61bd27bb6099104933134d3be7c052551a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-18 21:52:07.173192) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-18 21:52:07.173587) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-18 21:52:07.173853) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-18 21:52:07.198199) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-18 21:52:07.198470) ========= 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.BKhl6J7qsg/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.nzYHKsbBHH/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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-18 21:52:07-- 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.42M=0.001s 2016-12-18 21:52:07 (8.42 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.335727 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-18 21:52:07.557209) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-18 21:52:07.557513) ========= 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.BKhl6J7qsg/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.nzYHKsbBHH/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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.020168 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-18 21:52:07.607757) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-18 21:52:07.610161) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 863c2b61bd27bb6099104933134d3be7c052551a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 863c2b61bd27bb6099104933134d3be7c052551a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.BKhl6J7qsg/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.nzYHKsbBHH/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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-18 21:52:07,685 truncating revision to first 12 chars 2016-12-18 21:52:07,685 Setting DEBUG logging. 2016-12-18 21:52:07,685 attempt 1/10 2016-12-18 21:52:07,685 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/863c2b61bd27?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-18 21:52:08,784 unpacking tar archive at: mozilla-central-863c2b61bd27/testing/mozharness/ program finished with exit code 0 elapsedTime=1.338332 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-18 21:52:08.971708) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-18 21:52:08.972038) ========= script_repo_revision: 863c2b61bd27bb6099104933134d3be7c052551a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-18 21:52:08.972403) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-18 21:52:08.972670) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-18 21:52:08.992197) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-12-18 21:52:08.992465) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.BKhl6J7qsg/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.nzYHKsbBHH/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 21:52:09 INFO - MultiFileLogger online at 20161218 21:52:09 in /builds/slave/test 21:52:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 21:52:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:52:09 INFO - {'append_to_log': False, 21:52:09 INFO - 'base_work_dir': '/builds/slave/test', 21:52:09 INFO - 'blob_upload_branch': 'Firefox', 21:52:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:52:09 INFO - 'branch': 'Firefox', 21:52:09 INFO - 'buildbot_json_path': 'buildprops.json', 21:52:09 INFO - 'config_files': ('talos/mac_config.py',), 21:52:09 INFO - 'default_actions': ('clobber', 21:52:09 INFO - 'read-buildbot-config', 21:52:09 INFO - 'download-and-extract', 21:52:09 INFO - 'populate-webroot', 21:52:09 INFO - 'create-virtualenv', 21:52:09 INFO - 'install', 21:52:09 INFO - 'run-tests'), 21:52:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:52:09 INFO - 'download_minidump_stackwalk': True, 21:52:09 INFO - 'download_symbols': 'ondemand', 21:52:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:52:09 INFO - 'tooltool.py': '/tools/tooltool.py', 21:52:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:52:09 INFO - '/tools/misc-python/virtualenv.py')}, 21:52:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:52:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:52:09 INFO - 'installer_path': 'installer.exe', 21:52:09 INFO - 'log_level': 'info', 21:52:09 INFO - 'log_name': 'talos', 21:52:09 INFO - 'log_to_console': True, 21:52:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:52:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:52:09 INFO - 'opt_config_files': (), 21:52:09 INFO - 'pip_index': False, 21:52:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:52:09 INFO - 'cmd': ('bash', 21:52:09 INFO - '-c', 21:52:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:52:09 INFO - 'enabled': True, 21:52:09 INFO - 'halt_on_failure': False, 21:52:09 INFO - 'name': 'check_screen_resolution'},), 21:52:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:52:09 INFO - 'cmd': ('bash', 21:52:09 INFO - '-c', 21:52:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:52:09 INFO - 'enabled': True, 21:52:09 INFO - 'halt_on_failure': False, 21:52:09 INFO - 'name': 'check_screen_resolution'},), 21:52:09 INFO - 'run_cmd_checks_enabled': True, 21:52:09 INFO - 'sps_profile': False, 21:52:09 INFO - 'sps_profile_interval': 0, 21:52:09 INFO - 'suite': 'dromaeojs-e10s', 21:52:09 INFO - 'system_bits': '32', 21:52:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:52:09 INFO - 'title': 't-yosemite-r7-0072', 21:52:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:52:09 INFO - 'use_talos_json': True, 21:52:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:52:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:52:09 INFO - 'work_dir': 'build'} 21:52:09 INFO - [mozharness: 2016-12-19 05:52:09.194929Z] Running clobber step. 21:52:09 INFO - Running pre-action listener: _resource_record_pre_action 21:52:09 INFO - Running main action method: clobber 21:52:09 INFO - rmtree: /builds/slave/test/build 21:52:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:52:10 INFO - Running post-action listener: _resource_record_post_action 21:52:10 INFO - [mozharness: 2016-12-19 05:52:10.086005Z] Finished clobber step (success) 21:52:10 INFO - [mozharness: 2016-12-19 05:52:10.086146Z] Running read-buildbot-config step. 21:52:10 INFO - Running pre-action listener: _resource_record_pre_action 21:52:10 INFO - Running main action method: read_buildbot_config 21:52:10 INFO - Using buildbot properties: 21:52:10 INFO - { 21:52:10 INFO - "project": "", 21:52:10 INFO - "product": "firefox", 21:52:10 INFO - "script_repo_revision": "production", 21:52:10 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 21:52:10 INFO - "repository": "", 21:52:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 21:52:10 INFO - "buildid": "20161218202435", 21:52:10 INFO - "pgo_build": "False", 21:52:10 INFO - "basedir": "/builds/slave/test", 21:52:10 INFO - "buildnumber": 14, 21:52:10 INFO - "slavename": "t-yosemite-r7-0072", 21:52:10 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 21:52:10 INFO - "platform": "yosemite_r7", 21:52:10 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 21:52:10 INFO - "branch": "mozilla-central", 21:52:10 INFO - "repo_path": "mozilla-central", 21:52:10 INFO - "revision": "863c2b61bd27bb6099104933134d3be7c052551a", 21:52:10 INFO - "stage_platform": "macosx64", 21:52:10 INFO - "builduid": "bac23a1e40c9400c902e2e4a8b81ca83", 21:52:10 INFO - "slavebuilddir": "test" 21:52:10 INFO - } 21:52:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/b-1z4RHZQgSCbqNJohgJNA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 21:52:10 INFO - Running post-action listener: _resource_record_post_action 21:52:10 INFO - [mozharness: 2016-12-19 05:52:10.090125Z] Finished read-buildbot-config step (success) 21:52:10 INFO - [mozharness: 2016-12-19 05:52:10.090282Z] Running download-and-extract step. 21:52:10 INFO - Running pre-action listener: _resource_record_pre_action 21:52:10 INFO - Running main action method: download_and_extract 21:52:10 INFO - mkdir: /builds/slave/test/build/tests 21:52:10 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')]} 21:52:10 INFO - trying https://queue.taskcluster.net/v1/task/b-1z4RHZQgSCbqNJohgJNA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 21:52:10 INFO - Downloading https://queue.taskcluster.net/v1/task/b-1z4RHZQgSCbqNJohgJNA/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 21:52:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b-1z4RHZQgSCbqNJohgJNA/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 21:52:11 INFO - Downloaded 1199 bytes. 21:52:11 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 21:52:11 INFO - Using the following test package requirements: 21:52:11 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 21:52:11 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:52:11 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 21:52:11 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:52:11 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 21:52:11 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:52:11 INFO - u'jsshell-mac.zip'], 21:52:11 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:52:11 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 21:52:11 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 21:52:11 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:52:11 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 21:52:11 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:52:11 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 21:52:11 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:52:11 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 21:52:11 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:52:11 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 21:52:11 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:52:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b-1z4RHZQgSCbqNJohgJNA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 21:52:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b-1z4RHZQgSCbqNJohgJNA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:52:11 INFO - Fetch https://queue.taskcluster.net/v1/task/b-1z4RHZQgSCbqNJohgJNA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 21:52:11 INFO - Expected file size: 16276064 21:52:11 INFO - Obtained file size: 16276064 21:52:18 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 21:52:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b-1z4RHZQgSCbqNJohgJNA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 21:52:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b-1z4RHZQgSCbqNJohgJNA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:52:18 INFO - Fetch https://queue.taskcluster.net/v1/task/b-1z4RHZQgSCbqNJohgJNA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 21:52:19 INFO - Expected file size: 16276064 21:52:19 INFO - Obtained file size: 16276064 21:52:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b-1z4RHZQgSCbqNJohgJNA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 21:52:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b-1z4RHZQgSCbqNJohgJNA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 21:52:25 INFO - Fetch https://queue.taskcluster.net/v1/task/b-1z4RHZQgSCbqNJohgJNA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 21:52:28 INFO - Expected file size: 14409734 21:52:28 INFO - Obtained file size: 14409734 21:52:28 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')]} 21:52:28 INFO - trying https://queue.taskcluster.net/v1/task/b-1z4RHZQgSCbqNJohgJNA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 21:52:28 INFO - Downloading https://queue.taskcluster.net/v1/task/b-1z4RHZQgSCbqNJohgJNA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 21:52:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b-1z4RHZQgSCbqNJohgJNA/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 21:52:30 INFO - Downloaded 69547620 bytes. 21:52:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/b-1z4RHZQgSCbqNJohgJNA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 21:52:30 INFO - mkdir: /builds/slave/test/properties 21:52:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:52:30 INFO - Writing to file /builds/slave/test/properties/build_url 21:52:30 INFO - Contents: 21:52:30 INFO - build_url:https://queue.taskcluster.net/v1/task/b-1z4RHZQgSCbqNJohgJNA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 21:52:31 INFO - Running post-action listener: _resource_record_post_action 21:52:31 INFO - Running post-action listener: set_extra_try_arguments 21:52:31 INFO - [mozharness: 2016-12-19 05:52:31.179940Z] Finished download-and-extract step (success) 21:52:31 INFO - [mozharness: 2016-12-19 05:52:31.180107Z] Running populate-webroot step. 21:52:31 INFO - Running pre-action listener: _resource_record_pre_action 21:52:31 INFO - Running main action method: populate_webroot 21:52:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:52:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:52:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:52:31 INFO - '--tppagecycles', 21:52:31 INFO - '7'], 21:52:31 INFO - 'tests': ['tsvgm']}}, 21:52:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:52:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:52:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:52:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:52:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:52:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:52:31 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 21:52:31 INFO - 'talos_options': ['--disable-e10s'], 21:52:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:52:31 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:52:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:52:31 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 21:52:31 INFO - 'talos_options': ['--disable-e10s'], 21:52:31 INFO - 'tests': ['damp', 'tps']}, 21:52:31 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:52:31 INFO - 'tests': ['damp', 'tps']}, 21:52:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:52:31 INFO - 'tests': ['dromaeo_dom']}, 21:52:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:52:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:52:31 INFO - 'tests': ['basic_compositor_video']}, 21:52:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:52:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:52:31 INFO - 'tests': ['a11yr', 21:52:31 INFO - 'ts_paint', 21:52:31 INFO - 'tpaint', 21:52:31 INFO - 'sessionrestore', 21:52:31 INFO - 'sessionrestore_no_auto_restore', 21:52:31 INFO - 'tabpaint']}, 21:52:31 INFO - 'other-e10s': {'tests': ['a11yr', 21:52:31 INFO - 'ts_paint', 21:52:31 INFO - 'tpaint', 21:52:31 INFO - 'sessionrestore', 21:52:31 INFO - 'sessionrestore_no_auto_restore', 21:52:31 INFO - 'tabpaint']}, 21:52:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:52:31 INFO - 'ts_paint', 21:52:31 INFO - 'tpaint', 21:52:31 INFO - 'sessionrestore', 21:52:31 INFO - 'sessionrestore_no_auto_restore', 21:52:31 INFO - 'tabpaint']}, 21:52:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:52:31 INFO - 'ts_paint', 21:52:31 INFO - 'tpaint', 21:52:31 INFO - 'sessionrestore', 21:52:31 INFO - 'sessionrestore_no_auto_restore', 21:52:31 INFO - 'tabpaint']}, 21:52:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:52:31 INFO - 'tests': ['a11yr', 21:52:31 INFO - 'ts_paint', 21:52:31 INFO - 'tpaint', 21:52:31 INFO - 'sessionrestore', 21:52:31 INFO - 'sessionrestore_no_auto_restore', 21:52:31 INFO - 'tabpaint']}, 21:52:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:52:31 INFO - 'tests': ['a11yr', 21:52:31 INFO - 'ts_paint', 21:52:31 INFO - 'tpaint', 21:52:31 INFO - 'sessionrestore', 21:52:31 INFO - 'sessionrestore_no_auto_restore', 21:52:31 INFO - 'tabpaint']}, 21:52:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:52:31 INFO - 'tests': ['tsvgx', 21:52:31 INFO - 'tsvgr_opacity', 21:52:31 INFO - 'tart', 21:52:31 INFO - 'tscrollx', 21:52:31 INFO - 'cart', 21:52:31 INFO - 'tsvg_static']}, 21:52:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:52:31 INFO - 'tsvgr_opacity', 21:52:31 INFO - 'tart', 21:52:31 INFO - 'tscrollx', 21:52:31 INFO - 'cart', 21:52:31 INFO - 'tsvg_static']}, 21:52:31 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 21:52:31 INFO - 'talos_options': ['--disable-e10s'], 21:52:31 INFO - 'tests': ['tp5o']}, 21:52:31 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:52:31 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 21:52:31 INFO - 'talos_options': ['--disable-e10s', 21:52:31 INFO - '--xperf_path', 21:52:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:52:31 INFO - 'tests': ['tp5n']}, 21:52:31 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:52:31 INFO - 'talos_options': ['--xperf_path', 21:52:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:52:31 INFO - 'tests': ['tp5n']}}, 21:52:31 INFO - 'talos.zip': {'path': '', 21:52:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:52:31 INFO - Running post-action listener: _resource_record_post_action 21:52:31 INFO - [mozharness: 2016-12-19 05:52:31.198360Z] Finished populate-webroot step (success) 21:52:31 INFO - [mozharness: 2016-12-19 05:52:31.198470Z] Running create-virtualenv step. 21:52:31 INFO - Running pre-action listener: _resource_record_pre_action 21:52:31 INFO - Running main action method: create_virtualenv 21:52:31 INFO - Creating virtualenv /builds/slave/test/build/venv 21:52:31 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 21:52:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:52:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:52:31 INFO - Using real prefix '/tools/python27' 21:52:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:52:32 INFO - Installing distribute.............................................................................................................................................................................................done. 21:52:35 INFO - Installing pip.................done. 21:52:35 INFO - Return code: 0 21:52:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:52:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:52:35 INFO - Reading from file tmpfile_stdout 21:52:35 INFO - Output received: 21:52:35 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) 21:52:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:52: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')]} 21:52:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:52:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52: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')]} 21:52:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:52:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52:35 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 0x107898b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10789b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f024757f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107676d30>, '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 0x10759ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10766bbe8>, '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.BKhl6J7qsg/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.nzYHKsbBHH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 21:52:35 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 21:52:35 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 21:52:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BKhl6J7qsg/Render', 21:52:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:52:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:52:35 INFO - 'HOME': '/Users/cltbld', 21:52:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:52:35 INFO - 'LOGNAME': 'cltbld', 21:52:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:52:35 INFO - 'MOZ_NO_REMOTE': '1', 21:52:35 INFO - 'NO_EM_RESTART': '1', 21:52:35 INFO - 'PAGER': '/bin/cat', 21:52:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:52:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:52:35 INFO - 'PWD': '/builds/slave/test', 21:52:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:52:35 INFO - 'SHELL': '/bin/bash', 21:52:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nzYHKsbBHH/Listeners', 21:52:35 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 21:52:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:52:35 INFO - 'USER': 'cltbld', 21:52:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:52:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:52:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:52:35 INFO - 'XPC_FLAGS': '0x0', 21:52:35 INFO - 'XPC_SERVICE_NAME': '0', 21:52:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:52:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:52:35 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 21:52:35 INFO - Cleaning up... 21:52:35 INFO - Return code: 0 21:52:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:52: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')]} 21:52:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:52:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52: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')]} 21:52:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:52:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52:35 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 0x107898b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10789b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f024757f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107676d30>, '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 0x10759ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10766bbe8>, '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.BKhl6J7qsg/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.nzYHKsbBHH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 21:52:35 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 21:52:35 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 21:52:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BKhl6J7qsg/Render', 21:52:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:52:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:52:35 INFO - 'HOME': '/Users/cltbld', 21:52:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:52:35 INFO - 'LOGNAME': 'cltbld', 21:52:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:52:35 INFO - 'MOZ_NO_REMOTE': '1', 21:52:35 INFO - 'NO_EM_RESTART': '1', 21:52:35 INFO - 'PAGER': '/bin/cat', 21:52:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:52:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:52:35 INFO - 'PWD': '/builds/slave/test', 21:52:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:52:35 INFO - 'SHELL': '/bin/bash', 21:52:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nzYHKsbBHH/Listeners', 21:52:35 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 21:52:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:52:35 INFO - 'USER': 'cltbld', 21:52:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:52:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:52:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:52:35 INFO - 'XPC_FLAGS': '0x0', 21:52:35 INFO - 'XPC_SERVICE_NAME': '0', 21:52:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:52:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:52:35 INFO - Downloading/unpacking psutil>=3.1.1 21:52:35 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 21:52:35 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 21:52:35 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 21:52:35 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 21:52:35 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 21:52:35 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 21:52:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:52:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:52:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:52:39 INFO - Installing collected packages: psutil 21:52:39 INFO - Running setup.py install for psutil 21:52:39 INFO - building 'psutil._psutil_osx' extension 21:52:39 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 21:52:39 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 21:52:39 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 21:52:39 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 21:52:39 INFO - building 'psutil._psutil_posix' extension 21:52:39 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 21:52:39 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:52:39 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:52:39 INFO - ^ 21:52:39 INFO - 1 warning generated. 21:52:39 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 21:52:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:52:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:52:40 INFO - Successfully installed psutil 21:52:40 INFO - Cleaning up... 21:52:40 INFO - Return code: 0 21:52:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:52:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:52:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:52:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:52:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:52:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x107898b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10789b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f024757f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107676d30>, '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 0x10759ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10766bbe8>, '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.BKhl6J7qsg/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.nzYHKsbBHH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 21:52:40 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 21:52:40 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 21:52:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BKhl6J7qsg/Render', 21:52:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:52:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:52:40 INFO - 'HOME': '/Users/cltbld', 21:52:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:52:40 INFO - 'LOGNAME': 'cltbld', 21:52:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:52:40 INFO - 'MOZ_NO_REMOTE': '1', 21:52:40 INFO - 'NO_EM_RESTART': '1', 21:52:40 INFO - 'PAGER': '/bin/cat', 21:52:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:52:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:52:40 INFO - 'PWD': '/builds/slave/test', 21:52:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:52:40 INFO - 'SHELL': '/bin/bash', 21:52:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nzYHKsbBHH/Listeners', 21:52:40 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 21:52:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:52:40 INFO - 'USER': 'cltbld', 21:52:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:52:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:52:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:52:40 INFO - 'XPC_FLAGS': '0x0', 21:52:40 INFO - 'XPC_SERVICE_NAME': '0', 21:52:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:52:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:52:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:52:40 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 21:52:40 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 21:52:40 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 21:52:40 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 21:52:40 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 21:52:40 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 21:52:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:52:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:52:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:52:42 INFO - Installing collected packages: mozsystemmonitor 21:52:42 INFO - Running setup.py install for mozsystemmonitor 21:52:43 INFO - Successfully installed mozsystemmonitor 21:52:43 INFO - Cleaning up... 21:52:43 INFO - Return code: 0 21:52:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:52:43 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')]} 21:52:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:52:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52:43 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')]} 21:52:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:52:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52:43 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 0x107898b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10789b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f024757f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107676d30>, '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 0x10759ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10766bbe8>, '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.BKhl6J7qsg/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.nzYHKsbBHH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 21:52:43 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 21:52:43 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 21:52:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BKhl6J7qsg/Render', 21:52:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:52:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:52:43 INFO - 'HOME': '/Users/cltbld', 21:52:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:52:43 INFO - 'LOGNAME': 'cltbld', 21:52:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:52:43 INFO - 'MOZ_NO_REMOTE': '1', 21:52:43 INFO - 'NO_EM_RESTART': '1', 21:52:43 INFO - 'PAGER': '/bin/cat', 21:52:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:52:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:52:43 INFO - 'PWD': '/builds/slave/test', 21:52:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:52:43 INFO - 'SHELL': '/bin/bash', 21:52:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nzYHKsbBHH/Listeners', 21:52:43 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 21:52:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:52:43 INFO - 'USER': 'cltbld', 21:52:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:52:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:52:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:52:43 INFO - 'XPC_FLAGS': '0x0', 21:52:43 INFO - 'XPC_SERVICE_NAME': '0', 21:52:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:52:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:52:43 INFO - Downloading/unpacking jsonschema==2.5.1 21:52:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:52:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:52:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:52:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:52:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:52:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:52:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:52:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:52:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:52:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:52:45 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 21:52:45 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 21:52:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:52:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:52:46 INFO - Downloading functools32-3.2.3-2.tar.gz 21:52:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:52:46 INFO - warning: no files found matching '*.txt' 21:52:46 INFO - no previously-included directories found matching 'build' 21:52:46 INFO - no previously-included directories found matching 'dist' 21:52:46 INFO - no previously-included directories found matching '.git*' 21:52:46 INFO - Installing collected packages: jsonschema, functools32 21:52:46 INFO - Running setup.py install for functools32 21:52:46 INFO - warning: no files found matching '*.txt' 21:52:46 INFO - no previously-included directories found matching 'build' 21:52:46 INFO - no previously-included directories found matching 'dist' 21:52:46 INFO - no previously-included directories found matching '.git*' 21:52:46 INFO - Successfully installed jsonschema functools32 21:52:46 INFO - Cleaning up... 21:52:46 INFO - Return code: 0 21:52:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:52:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:52:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:52:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:52:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:52:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107898b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10789b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f024757f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107676d30>, '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 0x10759ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10766bbe8>, '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.BKhl6J7qsg/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.nzYHKsbBHH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 21:52:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 21:52:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 21:52:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BKhl6J7qsg/Render', 21:52:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:52:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:52:46 INFO - 'HOME': '/Users/cltbld', 21:52:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:52:46 INFO - 'LOGNAME': 'cltbld', 21:52:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:52:46 INFO - 'MOZ_NO_REMOTE': '1', 21:52:46 INFO - 'NO_EM_RESTART': '1', 21:52:46 INFO - 'PAGER': '/bin/cat', 21:52:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:52:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:52:46 INFO - 'PWD': '/builds/slave/test', 21:52:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:52:46 INFO - 'SHELL': '/bin/bash', 21:52:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nzYHKsbBHH/Listeners', 21:52:46 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 21:52:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:52:46 INFO - 'USER': 'cltbld', 21:52:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:52:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:52:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:52:46 INFO - 'XPC_FLAGS': '0x0', 21:52:46 INFO - 'XPC_SERVICE_NAME': '0', 21:52:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:52:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:52:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:52:46 INFO - Cleaning up... 21:52:46 INFO - Return code: 0 21:52:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:52:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:52:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:52:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:52:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:52:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107898b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10789b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f024757f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107676d30>, '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 0x10759ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10766bbe8>, '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.BKhl6J7qsg/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.nzYHKsbBHH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 21:52:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:52:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:52:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BKhl6J7qsg/Render', 21:52:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:52:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:52:46 INFO - 'HOME': '/Users/cltbld', 21:52:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:52:46 INFO - 'LOGNAME': 'cltbld', 21:52:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:52:46 INFO - 'MOZ_NO_REMOTE': '1', 21:52:46 INFO - 'NO_EM_RESTART': '1', 21:52:46 INFO - 'PAGER': '/bin/cat', 21:52:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:52:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:52:46 INFO - 'PWD': '/builds/slave/test', 21:52:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:52:46 INFO - 'SHELL': '/bin/bash', 21:52:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nzYHKsbBHH/Listeners', 21:52:46 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 21:52:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:52:46 INFO - 'USER': 'cltbld', 21:52:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:52:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:52:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:52:46 INFO - 'XPC_FLAGS': '0x0', 21:52:46 INFO - 'XPC_SERVICE_NAME': '0', 21:52:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:52:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:52:47 INFO - Downloading/unpacking blobuploader==1.2.4 21:52:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:52:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:52:47 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 21:52:47 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 21:52:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:52:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:52:49 INFO - Downloading blobuploader-1.2.4.tar.gz 21:52:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:52:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:52:49 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 21:52:49 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 21:52:49 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 21:52:49 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 21:52:49 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 21:52:49 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 21:52:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:52:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:52:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:52:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:52:50 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 21:52:50 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 21:52:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:52:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:52:50 INFO - Downloading docopt-0.6.1.tar.gz 21:52:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:52:51 INFO - Installing collected packages: blobuploader, requests, docopt 21:52:51 INFO - Running setup.py install for blobuploader 21:52:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:52:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:52:51 INFO - Running setup.py install for requests 21:52:51 INFO - Running setup.py install for docopt 21:52:51 INFO - Successfully installed blobuploader requests docopt 21:52:51 INFO - Cleaning up... 21:52:51 INFO - Return code: 0 21:52:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:52:51 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')]} 21:52:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:52:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52:51 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')]} 21:52:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:52:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52:51 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 0x107898b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10789b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f024757f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107676d30>, '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 0x10759ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10766bbe8>, '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.BKhl6J7qsg/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.nzYHKsbBHH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 21:52:51 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 21:52:51 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 21:52:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BKhl6J7qsg/Render', 21:52:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:52:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:52:51 INFO - 'HOME': '/Users/cltbld', 21:52:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:52:51 INFO - 'LOGNAME': 'cltbld', 21:52:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:52:51 INFO - 'MOZ_NO_REMOTE': '1', 21:52:51 INFO - 'NO_EM_RESTART': '1', 21:52:51 INFO - 'PAGER': '/bin/cat', 21:52:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:52:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:52:51 INFO - 'PWD': '/builds/slave/test', 21:52:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:52:51 INFO - 'SHELL': '/bin/bash', 21:52:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nzYHKsbBHH/Listeners', 21:52:51 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 21:52:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:52:51 INFO - 'USER': 'cltbld', 21:52:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:52:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:52:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:52:51 INFO - 'XPC_FLAGS': '0x0', 21:52:51 INFO - 'XPC_SERVICE_NAME': '0', 21:52:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:52:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:52:52 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-sLvlRR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:52:52 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-Kx0JfG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:52:52 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-6j0L0E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:52:52 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-GNxmTD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:52:52 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-R1EmrS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:52:52 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-NaeeDL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:52:52 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-dQFuil-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:52:52 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-fIdz2j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:52:53 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-D7VbGb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:52:53 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-beXzPS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:52:53 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-aR6l90-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:52:53 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-mORVcT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:52:53 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-7_Xgnb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:52:53 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-C6NrZr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:52:53 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-SQN45K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:52:53 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-ute7gl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:52:53 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-zxEPd5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:52:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:52:53 INFO - Running setup.py install for manifestparser 21:52:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:52:54 INFO - Running setup.py install for mozcrash 21:52:54 INFO - Running setup.py install for mozdebug 21:52:54 INFO - Running setup.py install for mozdevice 21:52:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:52:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:52:54 INFO - Running setup.py install for mozfile 21:52:54 INFO - Running setup.py install for mozhttpd 21:52:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:52:54 INFO - Running setup.py install for mozinfo 21:52:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:52:55 INFO - Running setup.py install for mozInstall 21:52:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:52:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:52:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:52:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:52:55 INFO - Running setup.py install for mozleak 21:52:55 INFO - Running setup.py install for mozlog 21:52:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:52:55 INFO - Running setup.py install for moznetwork 21:52:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:52:55 INFO - Running setup.py install for mozprocess 21:52:55 INFO - Running setup.py install for mozprofile 21:52:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:52:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:52:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:52:55 INFO - Running setup.py install for mozrunner 21:52:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:52:56 INFO - Running setup.py install for mozscreenshot 21:52:56 INFO - Running setup.py install for moztest 21:52:56 INFO - Running setup.py install for mozversion 21:52:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:52:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:52:56 INFO - Cleaning up... 21:52:56 INFO - Return code: 0 21:52:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:52:56 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')]} 21:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:52:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52:56 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')]} 21:52:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:52:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52:56 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 0x107898b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10789b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f024757f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107676d30>, '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 0x10759ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10766bbe8>, '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.BKhl6J7qsg/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.nzYHKsbBHH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 21:52:56 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 21:52:56 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 21:52:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BKhl6J7qsg/Render', 21:52:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:52:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:52:56 INFO - 'HOME': '/Users/cltbld', 21:52:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:52:56 INFO - 'LOGNAME': 'cltbld', 21:52:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:52:56 INFO - 'MOZ_NO_REMOTE': '1', 21:52:56 INFO - 'NO_EM_RESTART': '1', 21:52:56 INFO - 'PAGER': '/bin/cat', 21:52:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:52:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:52:56 INFO - 'PWD': '/builds/slave/test', 21:52:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:52:56 INFO - 'SHELL': '/bin/bash', 21:52:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nzYHKsbBHH/Listeners', 21:52:56 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 21:52:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:52:56 INFO - 'USER': 'cltbld', 21:52:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:52:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:52:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:52:56 INFO - 'XPC_FLAGS': '0x0', 21:52:56 INFO - 'XPC_SERVICE_NAME': '0', 21:52:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:52:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:52:56 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-Yulvsc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:52:56 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)) 21:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:52:56 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-DxweGQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:52:57 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)) 21:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:52:57 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-6cobOA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:52:57 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)) 21:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:52:57 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-nBD7CH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:52:57 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)) 21:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:52:57 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-ofGr0g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:52:57 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)) 21:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:52:57 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-UgFHNA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:52:57 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)) 21:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:52:57 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-NQHHHh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:52:57 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)) 21:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:52:57 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-IPOKLZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:52:57 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)) 21:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:52:57 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-jxN4uQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:52:57 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)) 21:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:52:57 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-7Fei0h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:52:57 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)) 21:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:52:57 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-UEqUll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:52:58 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)) 21:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:52:58 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-loFSLB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:52:58 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)) 21:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:52:58 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-CgF83b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:52:58 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)) 21:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:52:58 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-Tag6Aj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:52:58 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)) 21:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:52:58 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-MbzSKH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:52:58 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)) 21:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:52:58 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-WvaJOF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:52:58 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)) 21:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:52:58 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-tua6_6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:52:58 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)) 21:52:58 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)) 21:52:58 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)) 21:52:58 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)) 21:52:58 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)) 21:52:58 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)) 21:52:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:52:58 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 21:52:58 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 21:52:58 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 21:52:58 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 21:52:58 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 21:52:58 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 21:53:01 INFO - Downloading blessings-1.6.tar.gz 21:53:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:53:01 INFO - Installing collected packages: blessings 21:53:01 INFO - Running setup.py install for blessings 21:53:01 INFO - Successfully installed blessings 21:53:01 INFO - Cleaning up... 21:53:01 INFO - Return code: 0 21:53:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:53:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:53:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:53:01 INFO - Reading from file tmpfile_stdout 21:53:01 INFO - Current package versions: 21:53:01 INFO - blessings == 1.6 21:53:01 INFO - blobuploader == 1.2.4 21:53:01 INFO - docopt == 0.6.1 21:53:01 INFO - functools32 == 3.2.3-2 21:53:01 INFO - jsonschema == 2.5.1 21:53:01 INFO - manifestparser == 1.1 21:53:01 INFO - mozInstall == 1.12 21:53:01 INFO - mozcrash == 1.0 21:53:01 INFO - mozdebug == 0.1 21:53:01 INFO - mozdevice == 0.49 21:53:01 INFO - mozfile == 1.2 21:53:01 INFO - mozhttpd == 0.7 21:53:01 INFO - mozinfo == 0.9 21:53:01 INFO - mozleak == 0.1 21:53:01 INFO - mozlog == 3.4 21:53:01 INFO - moznetwork == 0.27 21:53:01 INFO - mozprocess == 0.23 21:53:01 INFO - mozprofile == 0.28 21:53:01 INFO - mozrunner == 6.13 21:53:01 INFO - mozscreenshot == 0.1 21:53:01 INFO - mozsystemmonitor == 0.3 21:53:01 INFO - moztest == 0.7 21:53:01 INFO - mozversion == 1.4 21:53:01 INFO - psutil == 3.1.1 21:53:01 INFO - requests == 1.2.3 21:53:01 INFO - wsgiref == 0.1.2 21:53:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:53: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')]} 21:53:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:53:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:53: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')]} 21:53:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:53:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:53:01 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 0x107898b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10789b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f024757f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107676d30>, '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 0x10759ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10766bbe8>, '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.BKhl6J7qsg/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.nzYHKsbBHH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 21:53:01 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 21:53:01 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 21:53:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BKhl6J7qsg/Render', 21:53:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:53:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:53:01 INFO - 'HOME': '/Users/cltbld', 21:53:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:53:01 INFO - 'LOGNAME': 'cltbld', 21:53:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:53:01 INFO - 'MOZ_NO_REMOTE': '1', 21:53:01 INFO - 'NO_EM_RESTART': '1', 21:53:01 INFO - 'PAGER': '/bin/cat', 21:53:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:53:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:53:01 INFO - 'PWD': '/builds/slave/test', 21:53:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:53:01 INFO - 'SHELL': '/bin/bash', 21:53:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nzYHKsbBHH/Listeners', 21:53:01 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 21:53:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:53:01 INFO - 'USER': 'cltbld', 21:53:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:53:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:53:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:53:01 INFO - 'XPC_FLAGS': '0x0', 21:53:01 INFO - 'XPC_SERVICE_NAME': '0', 21:53:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:53:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:53:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 21:53:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 21:53:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 21:53:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 21:53:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 21:53:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 21:53:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 21:53:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 21:53:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 21:53:02 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)) 21:53:02 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)) 21:53:02 INFO - Cleaning up... 21:53:02 INFO - Return code: 0 21:53:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:53:02 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')]} 21:53:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:53:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:53:02 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')]} 21:53:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:53:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:53:02 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 0x107898b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10789b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f024757f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107676d30>, '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 0x10759ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10766bbe8>, '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.BKhl6J7qsg/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.nzYHKsbBHH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 21:53:02 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 21:53:02 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 21:53:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BKhl6J7qsg/Render', 21:53:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:53:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:53:02 INFO - 'HOME': '/Users/cltbld', 21:53:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:53:02 INFO - 'LOGNAME': 'cltbld', 21:53:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:53:02 INFO - 'MOZ_NO_REMOTE': '1', 21:53:02 INFO - 'NO_EM_RESTART': '1', 21:53:02 INFO - 'PAGER': '/bin/cat', 21:53:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:53:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:53:02 INFO - 'PWD': '/builds/slave/test', 21:53:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:53:02 INFO - 'SHELL': '/bin/bash', 21:53:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nzYHKsbBHH/Listeners', 21:53:02 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 21:53:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:53:02 INFO - 'USER': 'cltbld', 21:53:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:53:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:53:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:53:02 INFO - 'XPC_FLAGS': '0x0', 21:53:02 INFO - 'XPC_SERVICE_NAME': '0', 21:53:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:53:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:53:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:53:02 INFO - Cleaning up... 21:53:02 INFO - Return code: 0 21:53:02 INFO - Running post-action listener: _resource_record_post_action 21:53:02 INFO - Running post-action listener: _start_resource_monitoring 21:53:02 INFO - Starting resource monitoring. 21:53:02 INFO - [mozharness: 2016-12-19 05:53:02.396988Z] Finished create-virtualenv step (success) 21:53:02 INFO - [mozharness: 2016-12-19 05:53:02.398005Z] Running install step. 21:53:02 INFO - Running pre-action listener: _resource_record_pre_action 21:53:02 INFO - Running main action method: install 21:53:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:53:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:53:02 INFO - Reading from file tmpfile_stdout 21:53:02 INFO - Detecting whether we're running mozinstall >=1.0... 21:53:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:53:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:53:02 INFO - Reading from file tmpfile_stdout 21:53:02 INFO - Output received: 21:53:02 INFO - Usage: mozinstall [options] installer 21:53:02 INFO - Options: 21:53:02 INFO - -h, --help show this help message and exit 21:53:02 INFO - -d DEST, --destination=DEST 21:53:02 INFO - Directory to install application into. [default: 21:53:02 INFO - "/builds/slave/test"] 21:53:02 INFO - --app=APP Application being installed. [default: firefox] 21:53:02 INFO - mkdir: /builds/slave/test/build/application 21:53:02 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'] 21:53:02 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 21:53:24 INFO - Reading from file tmpfile_stdout 21:53:24 INFO - Output received: 21:53:24 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:53:24 INFO - Running post-action listener: _resource_record_post_action 21:53:24 INFO - [mozharness: 2016-12-19 05:53:24.237210Z] Finished install step (success) 21:53:24 INFO - [mozharness: 2016-12-19 05:53:24.237352Z] Running run-tests step. 21:53:24 INFO - Running pre-action listener: _resource_record_pre_action 21:53:24 INFO - Running main action method: run_tests 21:53:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:53:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:53:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:53:24 INFO - 2016-12-18 21:53:24.250 screenresolution[1895:11755] starting screenresolution argv=screenresolution get 21:53:24 INFO - 2016-12-18 21:53:24.252 screenresolution[1895:11755] Display 0: 1600x1200x32@60 21:53:24 INFO - 2016-12-18 21:53:24.258 screenresolution[1896:11758] starting screenresolution argv=screenresolution list 21:53:24 INFO - Available Modes on Display 0 21:53:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:53:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:53:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:53:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:53:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:53:24 INFO - Intel Iris: 21:53:24 INFO - Chipset Model: Intel Iris 21:53:24 INFO - Type: GPU 21:53:24 INFO - Bus: Built-In 21:53:24 INFO - VRAM (Dynamic, Max): 1536 MB 21:53:24 INFO - Vendor: Intel (0x8086) 21:53:24 INFO - Device ID: 0x0a2e 21:53:24 INFO - Revision ID: 0x0009 21:53:24 INFO - Displays: 21:53:24 INFO - Display: 21:53:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:53:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:53:24 INFO - Display Serial Number: 1600x1200 60 21:53:24 INFO - Main Display: Yes 21:53:24 INFO - Mirror: Off 21:53:24 INFO - Online: Yes 21:53:24 INFO - Rotation: Supported 21:53:24 INFO - Return code: 0 21:53:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:53:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:53:24 INFO - Python 2.7.3 21:53:24 INFO - Return code: 0 21:53:24 INFO - grabbing minidump binary from tooltool 21:53: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')]} 21:53:24 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 0x107676d30>, '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 0x10759ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10766bbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:53:24 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 21:53:24 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 21:53:24 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 21:53:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:53:24 INFO - Return code: 0 21:53:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:53:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:53:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:53:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:53:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:53:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b-1z4RHZQgSCbqNJohgJNA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0072', '--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 21:53:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/b-1z4RHZQgSCbqNJohgJNA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0072 --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 21:53:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BKhl6J7qsg/Render', 21:53:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:53:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:53:24 INFO - 'HOME': '/Users/cltbld', 21:53:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:53:24 INFO - 'LOGNAME': 'cltbld', 21:53:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:53:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:53:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:53:24 INFO - 'MOZ_NO_REMOTE': '1', 21:53:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:53:24 INFO - 'NO_EM_RESTART': '1', 21:53:24 INFO - 'PAGER': '/bin/cat', 21:53:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:53:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:53:24 INFO - 'PWD': '/builds/slave/test', 21:53:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:53:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:53:24 INFO - 'SHELL': '/bin/bash', 21:53:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nzYHKsbBHH/Listeners', 21:53:24 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 21:53:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:53:24 INFO - 'USER': 'cltbld', 21:53:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:53:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:53:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:53:24 INFO - 'XPC_FLAGS': '0x0', 21:53:24 INFO - 'XPC_SERVICE_NAME': '0', 21:53:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:53:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b-1z4RHZQgSCbqNJohgJNA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0072', '--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 21:53:24 INFO - mozversion application_buildid: 20161218202435 21:53:24 INFO - mozversion application_changeset: 863c2b61bd27bb6099104933134d3be7c052551a 21:53:24 INFO - mozversion application_display_name: Nightly 21:53:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:53:24 INFO - mozversion application_name: Firefox 21:53:24 INFO - mozversion application_remotingname: firefox 21:53:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:53:24 INFO - mozversion application_vendor: Mozilla 21:53:24 INFO - mozversion application_version: 53.0a1 21:53:24 INFO - mozversion platform_buildid: 20161218202435 21:53:24 INFO - mozversion platform_changeset: 863c2b61bd27bb6099104933134d3be7c052551a 21:53:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:53:24 INFO - mozversion platform_version: 53.0a1 21:53:24 INFO - using testdate: 1482126804 21:53:24 INFO - actual date: 1482126804 21:53:24 INFO - starting webserver on 'localhost:49708' 21:53:24 INFO - SUITE-START | Running 2 tests 21:53:24 INFO - TEST-START | dromaeo_css 21:53:24 INFO - operating with platform_type : mac_ 21:53:24 INFO - Initialising browser for dromaeo_css test... 21:53:24 INFO - TEST-INFO | started process 1906 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/tmp0XFCou/profile http://localhost:49708/getInfo.html) 21:53:27 INFO - PROCESS | 1906 | 2016-12-18 21:53:27.662 plugin-container[1908:11913] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x484b, name = 'com.apple.tsm.portname' 21:53:27 INFO - PROCESS | 1906 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:53:27 INFO - PROCESS | 1906 | 2016-12-18 21:53:27.664 plugin-container[1908:11913] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 21:53:27 INFO - PROCESS | 1906 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:53:27 INFO - PROCESS | 1906 | 2016-12-18 21:53:27.664 plugin-container[1908:11913] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:53:27 INFO - PROCESS | 1906 | __metrics Screen width/height:1600/1200 21:53:27 INFO - PROCESS | 1906 | colorDepth:24 21:53:27 INFO - PROCESS | 1906 | Browser inner width/height: 1024/689 21:53:27 INFO - PROCESS | 1906 | __metrics 21:53:27 ERROR - PROCESS | 1906 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3232: TypeError: this.tabs is undefined 21:53:28 INFO - TEST-INFO | 1906: exit 0 21:53:28 INFO - Browser initialized. 21:53:28 INFO - Running cycle 1/1 for dromaeo_css test... 21:53:28 INFO - TEST-INFO | started process 1909 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/tmp0XFCou/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:53:31 INFO - PROCESS | 1909 | 2016-12-18 21:53:31.089 plugin-container[1910:12035] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8943, name = 'com.apple.tsm.portname' 21:53:31 INFO - PROCESS | 1909 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:53:31 INFO - PROCESS | 1909 | 2016-12-18 21:53:31.090 plugin-container[1910:12035] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4623, name = 'com.apple.CFPasteboardClient' 21:53:31 INFO - PROCESS | 1909 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:53:31 INFO - PROCESS | 1909 | 2016-12-18 21:53:31.090 plugin-container[1910:12035] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:53:40 INFO - PROCESS | 1909 | RSS: Main: 236822528 21:53:40 INFO - PROCESS | 1909 | 21:54:30 INFO - PROCESS | 1909 | 1482126870316 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] 21:56:42 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:49708/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49708/tests/dromaeo/cssquery-ext.html) 21:56:45 INFO - PROCESS | 1909 | RSS: Main: 254464000 21:56:45 INFO - PROCESS | 1909 | 21:59:47 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:49708/tests/dromaeo/cssquery-ext.html (next: http://localhost:49708/tests/dromaeo/cssquery-jquery.html) 21:59:48 INFO - PROCESS | 1909 | RSS: Main: 266743808 21:59:48 INFO - PROCESS | 1909 | 22:02:50 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:49708/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49708/tests/dromaeo/cssquery-mootools.html) 22:02:51 INFO - PROCESS | 1909 | RSS: Main: 272003072 22:02:51 INFO - PROCESS | 1909 | 22:05:52 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:49708/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49708/tests/dromaeo/cssquery-prototype.html) 22:05:53 INFO - PROCESS | 1909 | RSS: Main: 270753792 22:05:53 INFO - PROCESS | 1909 | 22:08:56 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:49708/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49708/tests/dromaeo/cssquery-yui.html) 22:08:57 INFO - PROCESS | 1909 | RSS: Main: 275316736 22:08:57 INFO - PROCESS | 1909 | 22:11:59 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:49708/tests/dromaeo/cssquery-yui.html (next: http://localhost:49708/tests/dromaeo/cssquery-dojo.html) 22:11:59 INFO - PROCESS | 1909 | RSS: Main: 277913600 22:11:59 INFO - PROCESS | 1909 | 22:11:59 INFO - PROCESS | 1909 | __start_tp_report 22:11:59 INFO - PROCESS | 1909 | _x_x_mozilla_page_load 22:11:59 INFO - PROCESS | 1909 | _x_x_mozilla_page_load_details 22:11:59 INFO - PROCESS | 1909 | |i|pagename|runs| 22:11:59 INFO - PROCESS | 1909 | |0;dojo.html;3319.7344212463004;3329.134425049487;3354.4129777288986;3359.5464612277337;3373.949390759138;4824.469308376085;5562.08225642769;5564.7217639118035;5579.8605034874145;5583.246261754667;5448.700321482317;5547.44525547446;5586.050371436855;5589.077802162648;5594.692291923942;5086.567641750456;5089.669171503859;5134.974325128369;5178.145605975028;5184.11869982104;5092.465291144432;5143.254228136937;5165.560927321182;5181.896362072756;5183.663061900965;5145.073886700392;5158.123119069767;5168.224766285069;5177.663451875616;5187.325647665798;5132.358455193083;5134.871628209287;5159.38087429509;5162.638615296931;5175.353080864892;5054.241863720435;5114.693118412907;5117.1556693145585;5124.0776660201145;5132.076226279269;5559.722013899289;5587.38538760736;5588.91616625751;5627.2684551008515;5630.746616402272;4842.41890973082;5871.207387002785;6080.270367555878;6097;6104.694765261719;6060.090986352038;6113.2330707408255;6121.632702037892;6126.975740326267;6191.2570491540855;5675.914861277084;5680.091185410314;5700.087985922274;5732.885342293131;5756.280464941882;6228.875422491525;6255.437010669061;6268.874622507525;6281.214848143982;6297.559170858088;5847.532197424196;5855.180274761537;6031.336552979169;6032.698365081728;6080.14877917092;6058.969975104243;6086.482648974887;6120.204373431426;6121.632702037803;6127.89207890682;5506.981208476444;5829.912551311733;6074.787382441594;6120.479759220516;6125.509959203253;5649.4915457608195;5736.913946290725;6073.8177854663545;6131;6134;4707.7881495332795;4730.408698912636;4767.928481072787;4784.593309568656;4808.7595620219545;4269.316909294498;4290.978545107255;4293.720908140961;4325.307950727932;4333.155034768253;5885.05839065756;5885.852853678693;5895.41045895407;5902.2622172228475;5929.525637948954;6158.630482171084;6184.979478386106;6192.721327540272;6203.286622038433;6211.409916057967;5716.399778023248;5739.62692424991;5748.367679555246;5811.3026436827;5871.236739223959;5228;5239.790408383698;5239.921401178985;5251.684898906078;5271.525562699376;6337.049442583557;6347.936520634827;6354.6822658867795;6356.936430635635;6380.1067850501895;6149.108379285068;6214.036824292242;6221.440070393686;6235.220597425322;6273.121762953281;6187.474064704551;6230.408111229426;6240.781572644935;6259.530535209787;6332.683365831598;6108.584917381041;6111.287884814297;6127.311699317154;6130.448259656652;6144.341027922525;6123.601965872204;6151.292601350723;6191.288001879841;6199.535034872312;6247.96876015617;4483.439570053743;5470.425605311385;5907.645541267538;6060.030395136757;6068.78759243442;5796.884062318814;5883;5918.112283157561;5930.881382372415;5931.3772053935445;5677.829665110054;5682.261306030189;5760.769569217352;5844.795432159854;5858.765649373977;5945.405459454193;6028.608140470856;6039.214902062879;6059.666718330534;6068.089786532056;5851.765929362778;5867.413258674099;5880.059190529666;5891.086881533369;5902.61632993854 22:11:59 INFO - PROCESS | 1909 | |1;ext.html;435.1992334105247;450.5472000639357;477.2149813556699;514.3133916221843;529.8860744939838;35485.64514354856;38577;38582.80708596457;38755.99999999996;38765.61234387655;39639.60360396395;40426.797866010704;40537;40546;40622.593774062254;8458.069612342637;8680.08859069798;9125.543722813849;9496;11201.215914885961;11193.608223712172;11409.37248451335;11415.942920285428;11419.600313989013;11480.081593472501;11402.942985285103;11439.027682646985;11442.199046066737;11449.255798373079;11467.19729618927;11361.772764544663;11363.204575679705;11388.202825802196;11392.71518212043;11411.771764564663;11221.607243746512;11325.603603873908;11338.206325557214;11338.886611133865;11343.26268792534;33180;37622.623773762185;39355.606443935765;39528.40707389391;40013.59918404905;2992.9102126936227;3351.581052368454;3440.8451619677176;3490.581130264359;3560.359135355635;29301.56047659287;29458.705412945812;29926.70073299261;29967.550486742715;30189.396212075855;2778.6943436222;3191.377681352137;3203.5515027896113;3224.4679627861374;3308.602967643874;487978;492283.5385823048;504135.47932260105;510662;516915;58985.41014589885;59366;59507.40492595105;59560;59613;62424;62489.687551561954;62980.37019629837;63034;63121.73756524844;15641.687166256612;16087.356505739874;16127.435539756052;16157.4344897928;16185.028898266142;11196.888031119584;11494.712632184095;12221.450034748457;12411.255324680547;12499.999999999978;16630.75053874193;16632.584185395463;16667.749983750255;16741.414050508174;16755.664886702198;7183.030290910765;7261.673224704901;7270.236625154388;7271.927280727231;7440.888386674204;841.2512863551444;926.7636752628036;992.4591097851544;998.9100980911753;1023.2888142740724;23223.883880580488;27446.45107097847;28734.856325718654;28991.710082899324;29735.702642973727;1042.280237607953;1093.4587379247323;1094.0536435982976;1124.971875703114;1152.124385467051;13898.096623719426;14609.050411723203;15417.691646167239;15700.371985120468;15930.920345398199;660312;885323;888165;894890;913293;49828;50338.993220135395;50501.989960201325;50569;50606.98786024259;1160.570588882119;1328.335832083958;1368.4868174434587;1372.4167228927865;1373.8900887929142;27643.861780690968;27788.30529236785;28053.298667533476;28373.85813071004;28555.28611784722;36563.817180913924;43463.56536434722;43710.781446092566;43918.560814392724;43973.78013109914;504795;505417;507359;507673;508559;21469.92650367444;21831.672524912457;23547.175848845207;23621.165460144894;24259.393515161555;40029.199416012096;40816.183676325716;40851.18297633971;41314.79342603268;41455.37816932688;34225.48661770125;34248.31503369869;34293;35186.47220291749;35340.93977180689;34027.829860850536;34267.65732342644;34951.12622184466;35238.823805880806;35526.822365888;34007;34190;34240;34463.82768086143;34517.65482345144;29461.55807662844;30826.229344266572;30905.0728478146;30940;30994.845025774728;20590.79409205949;23389.41526461852;23405.76594234036;23424.6486302709;23701.881490593125 22:11:59 INFO - PROCESS | 1909 | |2;jquery.html;310.7808994658764;378.16425699204757;378.9862118832123;379.63554987212274;413.22107826746594;15813.051216926979;19236.23055077797;19535.706964395544;19942.302019429324;20144.294949676765;19858.404247872586;19914.40256792295;20260.696089558667;20430.89784551079;20488.795112048876;5383.380911195204;5503.7798488060425;5688.573356998221;5937.762489500415;5947.702614869261;5437.374701909272;5817.389174136718;5842.824715258528;5846.356900740915;5858.5606079543995;5570.275864137657;5756.481916627503;5791.478766910977;5806.506446952014;5865.442782935615;5949.3158286796925;5987.191729116579;6007.4893634041155;6026.427489388501;6028.608140470856;4667.253239481666;5713.228714123582;5735.942640573582;5774.797882074149;5780.826575202751;12877.742445151138;13074.346282685829;13090.672733181651;19085.904570477196;23800.880995595086;2212.867227966311;2222.1228872697297;2277.39648993017;2404.9398765031015;2695.7034726180104;9796.755081122958;9834.754131146707;9910.9504452478;10122.443265620388;10288.588456461732;1853.249433979227;1899.0504747626187;1902.3891975385563;1916.559191385989;1948.084400331856;121441;121682;123252.38373808074;124268;126366.99999999908;11037.889621103846;11361.431928403546;11455.198136130392;11484.138689598314;11679.065674746;11953.880461195451;14269.500567480089;14299.928500357431;14329.996900217093;14371.712565748627;7396.260373962561;8144.185581441927;8374.953130858643;8377.37169712274;8511.872321915176;7884.093329267093;8239.95880020596;8266.669333226604;8408.243258106677;8419.410641255054;4007.73949693269;4227.852025179104;4479.566538707648;4504.076664283814;4559.970812988188;1882.8587855910862;1940.9902950485157;1941.79611140831;1942.1648691062978;1944.6499630066724;802.4583406200791;803.843250566134;888.2094935507404;927.9536023198813;955.6082006377352;19969.900150499445;20518;20600.175992960114;20827.8958605206;20848.895755521124;826.8057006603443;881.5107615273585;897.5512243878061;920.6823645842173;926.6061923682408;8575.528345941033;8969.641214351483;9254.768630784285;10431.5827366906;10821.971912668283;503824;509427;509983;533804;540860;76452.61773691207;79890.00274993172;111228.44385778181;111435.32847007301;127282.72717272709;980.7401038724744;1012.2711647613706;1029.1972261635935;1032.0917592518535;1049.3441599000778;14632.195229262492;18098.106070033205;18428.539286517946;19162.13770380364;19576.21695132179;44750;46664.53335466602;52835.471645283054;53016;53885.19172212498;79184.20815791999;79317.60341198256;80309.79535307092;80657;81411.59294203491;7923.881141782993;8056.516609003478;8059.959700201462;8177.141400152898;8204.302634275915;11851.407429628656;12333;12439.875601243872;12598;12615.116941813985;9722.659706910224;10697.786044278915;10767.784644307227;10956.890431095906;11000.339979601249;10618.787624247627;10658.14518653999;10687.358758474516;10868.239223254297;11036.613718519813;10904.854990225913;10976.780464390828;11139.4936139368;11358.26171298863;11359.54561817518;7767.495112817649;7856.764004619895;7929.907336139026;7984.842197881391;8245.422820402515;8095.55474448911;10249.897501025192;10461.843072354073;10490.580376784843;10788.083012943987 22:11:59 INFO - PROCESS | 1909 | |3;mootools.html;1476.239736535684;1497.6255936015993;1716.622343084521;1836.9173387197575;1856.7864695560006;7537.057867766529;7792.92207077929;8039.356851451885;8485.703000394988;8498.957505212473;7913.446058775888;8222.465539739927;8261.83476330473;8272.17278272174;8414.789630259247;1941.8737782044193;2255.842091053631;2317.351141680332;2318.53629274145;2322.7909488146065;2274.7725227477204;2314;2317.687112239843;2318.40880575453;2319.4549280918973;1919.4913347962802;2278.8176945844375;2280.5552917181158;2296.9655455168186;2319.1535089692225;2171.0555908179913;2197.516546359798;2199.3072182262536;2206.9668954965687;2207.4922767763424;2303.2284184798114;2337.0651739304244;2340.2511196417154;2340.520193360374;2344.6717459555675;8155.836883262361;8287.751367458986;8335.916640833575;8336.791580210482;8374.706885259047;5067.543921047087;5229.424763276036;5233.947660523384;5238.528532432061;5250.369955605313;3318.96681033189;3409.556757621518;3598.784072955631;3611.6388361163936;3625.981870090659;1045.4041196517987;1050.5640159333868;1086;1091.4815462655254;1109.8224284114503;10583.10043646291;10595.947020264926;10643.680689579325;10762.73093172677;10786.838197427045;4429.468463784302;4562.908741825211;4596.655250856133;4675.111728771591;4692.577668009895;9337.066293370608;9373.390729602554;9384.155426011555;9391.248700103975;9410.95294523523;5167.6641018333685;5208.479152084761;5213.713245771443;5219.582433405319;5225.346831646044;3739.4016957286703;3946.8223929923224;4703.95296047042;4729.07782982322;4796.2805579163405;2998.760099192103;3613.4760459733193;3817.9427308590393;3832.9808350958624;3919.2553414851086;1090.3675867996542;1096.8135416979133;1153.9747742711488;1206.6862615719801;1277.8274932884128;734.9926500734924;746.8655641984386;789.4907784479094;881.299367003234;907.3013779389833;5564.721763911788;5661.122526008476;5923.407659234128;6041.516678665696;6156.168917196122;766.4213518793275;845.4631309118754;886;905.714699869539;906.243286855483;3459.0314711880715;3683.1344634010984;3779.622037796253;3816.7709937403843;3842.269968705937;9705.6603018894;9951.701448956543;10011.649592264235;10040.397576145455;10077.344972576759;9973.002699729968;9977.052180042885;10001.44992025431;10006.249531285182;10012.299139060196;7506.3994880408345;7625.923740762632;7804.882926756103;7805.336546393507;7814.804629884298;5440.564754819684;5450.427705091067;5609.467100625434;5647.519960803378;5677.290338707661;9275.953620231856;9281.535923203948;9298.163165315154;9304.348695591514;9321.067893210624;7899.84200315979;8620.267277281251;9040.915090189219;9301.674441394518;9553.140217380469;7019.438444924496;7083.114610673666;7126.608036558039;7157.677904494414;7200.783976480714;7732.961335193288;7777.533347999138;7789.7273595423885;7795.4543181976715;7810.179931107379;6980.965095174492;7139;7164.677589508589;7249.347558719741;7260.636968151677;6301.842453938688;6785.355391237825;6906.102206713095;6954.687039083152;7106.253843346581;5668.886622267614;6515.446187074152;6567.441767449629;6629.6685165740555;6657.500687448364;4837.959838634711;4871.244957031666;4884.877878053077;4927.162382394929;4978.6763860348965;4853.174960256694;4871.732054737024;4925.876853078702;4993.600511958962;5003.574696150868 22:11:59 INFO - PROCESS | 1909 | |4;prototype.html;306.56008627619366;343.2054793153847;350.3658378335212;369.538077403246;371.9423489359149;4696.765161741916;4798.736069516175;4875.561199492045;4932.742150751558;5189.572867461446;4263.722858014227;4547.863564093074;4950.504949505048;5141.383034035921;5249.553787928031;3823.1971286029966;4140.896477588054;4144.70987030908;4156.210320039183;4156.896077598054;3861.575226725058;3985.641292283694;4194.874153775391;4201.033762234688;4215.85244516442;3640.617735137812;3893.766374017554;4099.048684946833;4112.203701483006;4118.496748686723;3457.844397002141;3896.376579747255;4084.019835239536;4111.224923176858;4126.968257935516;2809.9157025289273;3266.591676040495;3674.1341753432366;3687.6681098701247;3715.4241092630687;3988.823297127354;4840.394950631171;5049.394072711299;5089.720065396402;5174.793008279665;3869.6456240315665;4477.552244775529;4600.987782687804;4755.928661070052;4793.640476964207;4872.585830204437;4927.384076990376;5425.213344065128;5578.453560348344;5581.6371935824045;3339.9499007514905;3407.386670399327;3799.715021373408;4028.9597104028876;4059.0867054912705;4731.33761273422;5705.918803845918;5810.535157187415;5847.006008978441;5952.553558483131;4071.328230841937;4106.466159399259;4112.773797441109;4122.958770412318;4125.267387697174;3560.6101920111723;4149;4188.204241194163;4206.957930420718;4214.157168566299;3928.5678575356687;4338.804753786024;4640.95359046412;4679.532046795362;4721.536323739651;5186.299849520266;5365.651232669864;5613.382527921925;5623.831285061455;5624;1186.6736647422028;1297.7145028093805;1301.7113058072441;1345.4012964230824;1354.5665387076237;827.4662842466576;865.8787769712245;911.1025639744978;972.4359871274644;974.936629119105;4369.300911854088;5112.188296436945;5149.867029253558;5175.430702622709;5218.660787048906;4282.743035417885;5430.4880546245595;5449.673019618756;5508.3114610673665;5566.471185237396;4610.400647915817;4949.653524253267;4993.275974983607;5013.072581572421;5042.319286896222;2378.702662167229;2523.987380063088;2593.922182334533;2680.7855371570226;2864.393402004166;5371.785128594891;5437.673739575638;5466.125419932791;5626.943730562642;5789.565782566325;4927.817323842252;5039.546440820343;5181.637285389987;5198.610104242197;5600.607957443021;4466.575675310905;4846.933674591584;4923.9167383175645;5151.845444636667;5634.154876768435;4902.289168070539;5114.948850511596;5247.606429517725;5397.541208997279;5423.942331245369;4992.8751781205765;5010.549050585465;5041.899162016666;5306.602004849729;5436.429174936575;5010;5098;5181.455947125498;5640.32316122068;5742.36833948257;3374.4983482350117;3632.473291372789;3637.890863274106;3798.6011468796482;3842.9640583938376;4807.903841923212;4859.757012149449;5305.60207984395;5431.501509305764;5431.538319242909;4589.518100599523;4835.637327200404;4966.453690094015;4990.750462476934;5034.672746271481;4803.855884323476;5185.455527169593;5235.476452354734;5562.887422515432;5651.067573850269;5227.790888364423;5276.364327058624;5357.892842143057;5498.780048798163;5504.669719816824;5040.722760248075;5098.566621837185;5354.6265852514225;5427.511523962862;5605.243292155506;4824.421069471686;5183.300254465599;5320.466714616755;5475;5475.945240547703 22:11:59 INFO - PROCESS | 1909 | |5;yui.html;744.9702011919524;754.5472716370178;796.6136423834441;808.773543407846;845.9661613535457;14564.271786410676;15644.983076100045;15652.921735391335;15714.921425392871;15748.763768543467;14174.858251417509;14296.785548216785;14366.353514091865;14393.568192954202;14408.567742967702;858.8797568340437;867.7440155154245;900.2572877376158;909.745271324027;911.2800887299026;891.777055736066;897.6050537763396;902.3908861518481;912.9543522823866;914.561010714854;939.8919124300725;944.2870632672323;944.8110377924409;945.7588314979671;950.1543626172712;889.4307643108448;934.0892629686069;936.2276122199213;939.6899023322287;942.2885721280423;690.6650274616807;692.1210063219733;693.0262980512388;695.3290075077574;697.1494776372867;15001.849981500154;15006.324715387724;15231.162286074261;15328.693426131527;15351.232438378036;14055.718885622231;14322.212278324689;14605.12369257848;14607.269636518131;14684.192369419678;11750.941245293805;11756.706082347924;11806.704832379173;11809.468573914108;11856.940715296454;3721.8697345592773;3725.155256303628;3749.286901307157;3777.924441511182;3806.257779732953;109175;109822;111982;112744;113862;10975.725606859893;11086.61196858106;11104.722381940355;11145.777084458265;11213.327200368005;23377.181798636968;23412.591863321475;23572.882135589556;23694.289171324886;23702.407439814142;7947.3642108631175;7997.5601341925585;8025.197480251928;8105.189481051848;8202.302804261704;4311.698181127291;4369.737815731066;4398.853252105349;4433.82264709408;4507.706999045052;3183.0928185467033;3206.823624700617;3409.62494125646;3693.8707145249787;3698.8335524901445;573.1545969694668;621.2700077409027;642.9260635026939;643.2891654721559;673.1182151381705;541.8970395624818;549.3188446326526;569.4419468920481;610.484140900938;635.2028204603193;9219.400738951947;9312.534373281444;9319.68118451296;9505.239580833517;9520.143187113194;619.662284055191;637.3690046853582;660.7951535024158;666.6699983508195;669.4209508774875;3882.359410697203;4392.802323895432;4578.5421457853945;4584.266517357207;4616.3306320584;103496;107370.46314768377;108380;112078;115939.42030289794;84734;85083.57458212669;87434;90217;93569.53215234017;76516.23483765092;77712.61143694245;81098;85197;86119.27761444611;9505.477198754135;9914.355566888129;9916.950415247878;9989.950050249703;10003.949980250343;37082.8145859269;52556.737216313675;52783.94432111413;53286.2007069902;54161.187582187085;72238.55522889618;75796;77052.61473692596;77131;79585.60207198927;12388.256704597752;12825.679358016125;12970.692473419218;13031.218126912416;13082.149660272053;10271.537780799732;12073.27560346382;12374.62876113718;12707.301098439675;12748.553800617305;13378.799318010433;13454.394552245369;13602.183868967893;13653.79519307231;13900.026998110035;13157.934210328887;13474.595762127152;13600.115992460458;13723;14003.50987715425;13157.473701052233;13164.539241126515;13351.332433378486;13423.798643020167;13689.589312320646;10787;11306.830397544209;11362.772744545227;11438.124760544633;11728.589499367481;8729;9748.366356186825;10144.782626084918;11508.13688973347;11568.305901645928 22:11:59 INFO - PROCESS | 1909 | __end_tp_report 22:11:59 INFO - PROCESS | 1909 | __start_cc_report 22:11:59 INFO - PROCESS | 1909 | _x_x_mozilla_cycle_collect,374 22:11:59 INFO - PROCESS | 1909 | __end_cc_report 22:11:59 INFO - PROCESS | 1909 | __startTimestamp1482127919820__endTimestamp 22:11:59 INFO - PROCESS | 1909 | 22:11:59 INFO - PROCESS | 1909 | ------- Summary: start ------- 22:11:59 INFO - PROCESS | 1909 | Number of tests: 6 22:11:59 INFO - PROCESS | 1909 | 22:11:59 INFO - PROCESS | 1909 | [#0] dojo.html Cycles:165 Average:5634.97 Median:5851.77 stddev:645.72 (11.0%) stddev-sans-first:621.64 22:11:59 INFO - PROCESS | 1909 | Values: 3319.7 3329.1 3354.4 3359.5 3373.9 4824.5 5562.1 5564.7 5579.9 5583.2 5448.7 5547.4 5586.1 5589.1 5594.7 5086.6 5089.7 5135.0 5178.1 5184.1 5092.5 5143.3 5165.6 5181.9 5183.7 5145.1 5158.1 5168.2 5177.7 5187.3 5132.4 5134.9 5159.4 5162.6 5175.4 5054.2 5114.7 5117.2 5124.1 5132.1 5559.7 5587.4 5588.9 5627.3 5630.7 4842.4 5871.2 6080.3 6097.0 6104.7 6060.1 6113.2 6121.6 6127.0 6191.3 5675.9 5680.1 5700.1 5732.9 5756.3 6228.9 6255.4 6268.9 6281.2 6297.6 5847.5 5855.2 6031.3 6032.7 6080.1 6059.0 6086.5 6120.2 6121.6 6127.9 5507.0 5829.9 6074.8 6120.5 6125.5 5649.5 5736.9 6073.8 6131.0 6134.0 4707.8 4730.4 4767.9 4784.6 4808.8 4269.3 4291.0 4293.7 4325.3 4333.2 5885.1 5885.9 5895.4 5902.3 5929.5 6158.6 6185.0 6192.7 6203.3 6211.4 5716.4 5739.6 5748.4 5811.3 5871.2 5228.0 5239.8 5239.9 5251.7 5271.5 6337.0 6347.9 6354.7 6356.9 6380.1 6149.1 6214.0 6221.4 6235.2 6273.1 6187.5 6230.4 6240.8 6259.5 6332.7 6108.6 6111.3 6127.3 6130.4 6144.3 6123.6 6151.3 6191.3 6199.5 6248.0 4483.4 5470.4 5907.6 6060.0 6068.8 5796.9 5883.0 5918.1 5930.9 5931.4 5677.8 5682.3 5760.8 5844.8 5858.8 5945.4 6028.6 6039.2 6059.7 6068.1 5851.8 5867.4 5880.1 5891.1 5902.6 22:11:59 INFO - PROCESS | 1909 | 22:11:59 INFO - PROCESS | 1909 | [#1] ext.html Cycles:180 Average:72993.07 Median:34017.41 stddev:173193.74 (509.1%) stddev-sans-first:173593.92 22:11:59 INFO - PROCESS | 1909 | Values: 435.2 450.5 477.2 514.3 529.9 35485.6 38577.0 38582.8 38756.0 38765.6 39639.6 40426.8 40537.0 40546.0 40622.6 8458.1 8680.1 9125.5 9496.0 11201.2 11193.6 11409.4 11415.9 11419.6 11480.1 11402.9 11439.0 11442.2 11449.3 11467.2 11361.8 11363.2 11388.2 11392.7 11411.8 11221.6 11325.6 11338.2 11338.9 11343.3 33180.0 37622.6 39355.6 39528.4 40013.6 2992.9 3351.6 3440.8 3490.6 3560.4 29301.6 29458.7 29926.7 29967.6 30189.4 2778.7 3191.4 3203.6 3224.5 3308.6 487978.0 492283.5 504135.5 510662.0 516915.0 58985.4 59366.0 59507.4 59560.0 59613.0 62424.0 62489.7 62980.4 63034.0 63121.7 15641.7 16087.4 16127.4 16157.4 16185.0 11196.9 11494.7 12221.5 12411.3 12500.0 16630.8 16632.6 16667.7 16741.4 16755.7 7183.0 7261.7 7270.2 7271.9 7440.9 841.3 926.8 992.5 998.9 1023.3 23223.9 27446.5 28734.9 28991.7 29735.7 1042.3 1093.5 1094.1 1125.0 1152.1 13898.1 14609.1 15417.7 15700.4 15930.9 660312.0 885323.0 888165.0 894890.0 913293.0 49828.0 50339.0 50502.0 50569.0 50607.0 1160.6 1328.3 1368.5 1372.4 1373.9 27643.9 27788.3 28053.3 28373.9 28555.3 36563.8 43463.6 43710.8 43918.6 43973.8 504795.0 505417.0 507359.0 507673.0 508559.0 21469.9 21831.7 23547.2 23621.2 24259.4 40029.2 40816.2 40851.2 41314.8 41455.4 34225.5 34248.3 34293.0 35186.5 35340.9 34027.8 34267.7 34951.1 35238.8 35526.8 34007.0 34190.0 34240.0 34463.8 34517.7 29461.6 30826.2 30905.1 30940.0 30994.8 20590.8 23389.4 23405.8 23424.6 23701.9 22:11:59 INFO - PROCESS | 1909 | 22:11:59 INFO - PROCESS | 1909 | [#2] jquery.html Cycles:180 Average:31760.48 Median:378.58 stddev:87181.55 (23028.9%) stddev-sans-first:87394.14 22:11:59 INFO - PROCESS | 1909 | Values: 310.8 378.2 379.0 379.6 413.2 15813.1 19236.2 19535.7 19942.3 20144.3 19858.4 19914.4 20260.7 20430.9 20488.8 5383.4 5503.8 5688.6 5937.8 5947.7 5437.4 5817.4 5842.8 5846.4 5858.6 5570.3 5756.5 5791.5 5806.5 5865.4 5949.3 5987.2 6007.5 6026.4 6028.6 4667.3 5713.2 5735.9 5774.8 5780.8 12877.7 13074.3 13090.7 19085.9 23800.9 2212.9 2222.1 2277.4 2404.9 2695.7 9796.8 9834.8 9911.0 10122.4 10288.6 1853.2 1899.1 1902.4 1916.6 1948.1 121441.0 121682.0 123252.4 124268.0 126367.0 11037.9 11361.4 11455.2 11484.1 11679.1 11953.9 14269.5 14299.9 14330.0 14371.7 7396.3 8144.2 8375.0 8377.4 8511.9 7884.1 8240.0 8266.7 8408.2 8419.4 4007.7 4227.9 4479.6 4504.1 4560.0 1882.9 1941.0 1941.8 1942.2 1944.6 802.5 803.8 888.2 928.0 955.6 19969.9 20518.0 20600.2 20827.9 20848.9 826.8 881.5 897.6 920.7 926.6 8575.5 8969.6 9254.8 10431.6 10822.0 503824.0 509427.0 509983.0 533804.0 540860.0 76452.6 79890.0 111228.4 111435.3 127282.7 980.7 1012.3 1029.2 1032.1 1049.3 14632.2 18098.1 18428.5 19162.1 19576.2 44750.0 46664.5 52835.5 53016.0 53885.2 79184.2 79317.6 80309.8 80657.0 81411.6 7923.9 8056.5 8060.0 8177.1 8204.3 11851.4 12333.0 12439.9 12598.0 12615.1 9722.7 10697.8 10767.8 10956.9 11000.3 10618.8 10658.1 10687.4 10868.2 11036.6 10904.9 10976.8 11139.5 11358.3 11359.5 7767.5 7856.8 7929.9 7984.8 8245.4 8095.6 10249.9 10461.8 10490.6 10788.1 22:11:59 INFO - PROCESS | 1909 | 22:11:59 INFO - PROCESS | 1909 | [#3] mootools.html Cycles:180 Average:5393.18 Median:5222.46 stddev:2948.20 (56.5%) stddev-sans-first:2941.77 22:11:59 INFO - PROCESS | 1909 | Values: 1476.2 1497.6 1716.6 1836.9 1856.8 7537.1 7792.9 8039.4 8485.7 8499.0 7913.4 8222.5 8261.8 8272.2 8414.8 1941.9 2255.8 2317.4 2318.5 2322.8 2274.8 2314.0 2317.7 2318.4 2319.5 1919.5 2278.8 2280.6 2297.0 2319.2 2171.1 2197.5 2199.3 2207.0 2207.5 2303.2 2337.1 2340.3 2340.5 2344.7 8155.8 8287.8 8335.9 8336.8 8374.7 5067.5 5229.4 5233.9 5238.5 5250.4 3319.0 3409.6 3598.8 3611.6 3626.0 1045.4 1050.6 1086.0 1091.5 1109.8 10583.1 10595.9 10643.7 10762.7 10786.8 4429.5 4562.9 4596.7 4675.1 4692.6 9337.1 9373.4 9384.2 9391.2 9411.0 5167.7 5208.5 5213.7 5219.6 5225.3 3739.4 3946.8 4704.0 4729.1 4796.3 2998.8 3613.5 3817.9 3833.0 3919.3 1090.4 1096.8 1154.0 1206.7 1277.8 735.0 746.9 789.5 881.3 907.3 5564.7 5661.1 5923.4 6041.5 6156.2 766.4 845.5 886.0 905.7 906.2 3459.0 3683.1 3779.6 3816.8 3842.3 9705.7 9951.7 10011.6 10040.4 10077.3 9973.0 9977.1 10001.4 10006.2 10012.3 7506.4 7625.9 7804.9 7805.3 7814.8 5440.6 5450.4 5609.5 5647.5 5677.3 9276.0 9281.5 9298.2 9304.3 9321.1 7899.8 8620.3 9040.9 9301.7 9553.1 7019.4 7083.1 7126.6 7157.7 7200.8 7733.0 7777.5 7789.7 7795.5 7810.2 6981.0 7139.0 7164.7 7249.3 7260.6 6301.8 6785.4 6906.1 6954.7 7106.3 5668.9 6515.4 6567.4 6629.7 6657.5 4838.0 4871.2 4884.9 4927.2 4978.7 4853.2 4871.7 4925.9 4993.6 5003.6 22:11:59 INFO - PROCESS | 1909 | 22:11:59 INFO - PROCESS | 1909 | [#4] prototype.html Cycles:180 Average:4389.24 Median:4913.10 stddev:1299.73 (26.5%) stddev-sans-first:1266.73 22:11:59 INFO - PROCESS | 1909 | Values: 306.6 343.2 350.4 369.5 371.9 4696.8 4798.7 4875.6 4932.7 5189.6 4263.7 4547.9 4950.5 5141.4 5249.6 3823.2 4140.9 4144.7 4156.2 4156.9 3861.6 3985.6 4194.9 4201.0 4215.9 3640.6 3893.8 4099.0 4112.2 4118.5 3457.8 3896.4 4084.0 4111.2 4127.0 2809.9 3266.6 3674.1 3687.7 3715.4 3988.8 4840.4 5049.4 5089.7 5174.8 3869.6 4477.6 4601.0 4755.9 4793.6 4872.6 4927.4 5425.2 5578.5 5581.6 3339.9 3407.4 3799.7 4029.0 4059.1 4731.3 5705.9 5810.5 5847.0 5952.6 4071.3 4106.5 4112.8 4123.0 4125.3 3560.6 4149.0 4188.2 4207.0 4214.2 3928.6 4338.8 4641.0 4679.5 4721.5 5186.3 5365.7 5613.4 5623.8 5624.0 1186.7 1297.7 1301.7 1345.4 1354.6 827.5 865.9 911.1 972.4 974.9 4369.3 5112.2 5149.9 5175.4 5218.7 4282.7 5430.5 5449.7 5508.3 5566.5 4610.4 4949.7 4993.3 5013.1 5042.3 2378.7 2524.0 2593.9 2680.8 2864.4 5371.8 5437.7 5466.1 5626.9 5789.6 4927.8 5039.5 5181.6 5198.6 5600.6 4466.6 4846.9 4923.9 5151.8 5634.2 4902.3 5114.9 5247.6 5397.5 5423.9 4992.9 5010.5 5041.9 5306.6 5436.4 5010.0 5098.0 5181.5 5640.3 5742.4 3374.5 3632.5 3637.9 3798.6 3843.0 4807.9 4859.8 5305.6 5431.5 5431.5 4589.5 4835.6 4966.5 4990.8 5034.7 4803.9 5185.5 5235.5 5562.9 5651.1 5227.8 5276.4 5357.9 5498.8 5504.7 5040.7 5098.6 5354.6 5427.5 5605.2 4824.4 5183.3 5320.5 5475.0 5475.9 22:11:59 INFO - PROCESS | 1909 | 22:11:59 INFO - PROCESS | 1909 | [#5] yui.html Cycles:180 Average:21115.35 Median:4097.03 stddev:30953.60 (755.5%) stddev-sans-first:31002.65 22:11:59 INFO - PROCESS | 1909 | Values: 745.0 754.5 796.6 808.8 846.0 14564.3 15645.0 15652.9 15714.9 15748.8 14174.9 14296.8 14366.4 14393.6 14408.6 858.9 867.7 900.3 909.7 911.3 891.8 897.6 902.4 913.0 914.6 939.9 944.3 944.8 945.8 950.2 889.4 934.1 936.2 939.7 942.3 690.7 692.1 693.0 695.3 697.1 15001.8 15006.3 15231.2 15328.7 15351.2 14055.7 14322.2 14605.1 14607.3 14684.2 11750.9 11756.7 11806.7 11809.5 11856.9 3721.9 3725.2 3749.3 3777.9 3806.3 109175.0 109822.0 111982.0 112744.0 113862.0 10975.7 11086.6 11104.7 11145.8 11213.3 23377.2 23412.6 23572.9 23694.3 23702.4 7947.4 7997.6 8025.2 8105.2 8202.3 4311.7 4369.7 4398.9 4433.8 4507.7 3183.1 3206.8 3409.6 3693.9 3698.8 573.2 621.3 642.9 643.3 673.1 541.9 549.3 569.4 610.5 635.2 9219.4 9312.5 9319.7 9505.2 9520.1 619.7 637.4 660.8 666.7 669.4 3882.4 4392.8 4578.5 4584.3 4616.3 103496.0 107370.5 108380.0 112078.0 115939.4 84734.0 85083.6 87434.0 90217.0 93569.5 76516.2 77712.6 81098.0 85197.0 86119.3 9505.5 9914.4 9917.0 9990.0 10003.9 37082.8 52556.7 52783.9 53286.2 54161.2 72238.6 75796.0 77052.6 77131.0 79585.6 12388.3 12825.7 12970.7 13031.2 13082.1 10271.5 12073.3 12374.6 12707.3 12748.6 13378.8 13454.4 13602.2 13653.8 13900.0 13157.9 13474.6 13600.1 13723.0 14003.5 13157.5 13164.5 13351.3 13423.8 13689.6 10787.0 11306.8 11362.8 11438.1 11728.6 8729.0 9748.4 10144.8 11508.1 11568.3 22:11:59 INFO - PROCESS | 1909 | -------- Summary: end -------- 22:11:59 INFO - PROCESS | 1909 | 22:12:00 INFO - TEST-INFO | 1909: exit 0 22:12:00 INFO - TEST-OK | dromaeo_css | took 1115694ms 22:12:00 INFO - TEST-START | kraken 22:12:00 INFO - operating with platform_type : mac_ 22:12:00 INFO - Initialising browser for kraken test... 22:12:00 INFO - TEST-INFO | started process 1914 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/tmpD2EMXf/profile http://localhost:49708/getInfo.html) 22:12:03 INFO - PROCESS | 1914 | __metrics Screen width/height:1600/1200 22:12:03 INFO - PROCESS | 1914 | colorDepth:24 22:12:03 INFO - PROCESS | 1914 | Browser inner width/height: 1024/689 22:12:03 INFO - PROCESS | 1914 | __metrics 22:12:03 INFO - PROCESS | 1914 | 2016-12-18 22:12:03.404 plugin-container[1915:14026] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4443, name = 'com.apple.tsm.portname' 22:12:03 INFO - PROCESS | 1914 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:12:03 INFO - PROCESS | 1914 | 2016-12-18 22:12:03.407 plugin-container[1915:14026] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0f, name = 'com.apple.CFPasteboardClient' 22:12:03 INFO - PROCESS | 1914 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:12:03 INFO - PROCESS | 1914 | 2016-12-18 22:12:03.407 plugin-container[1915:14026] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:12:03 ERROR - PROCESS | 1914 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3232: TypeError: this.tabs is undefined 22:12:04 INFO - TEST-INFO | 1914: exit 0 22:12:04 INFO - Browser initialized. 22:12:04 INFO - Running cycle 1/1 for kraken test... 22:12:04 INFO - TEST-INFO | started process 1916 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/tmpD2EMXf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:12:06 INFO - PROCESS | 1916 | 2016-12-18 22:12:06.679 plugin-container[1917:14146] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4743, name = 'com.apple.tsm.portname' 22:12:06 INFO - PROCESS | 1916 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:12:06 INFO - PROCESS | 1916 | 2016-12-18 22:12:06.679 plugin-container[1917:14146] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8937, name = 'com.apple.CFPasteboardClient' 22:12:06 INFO - PROCESS | 1916 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:12:06 INFO - PROCESS | 1916 | 2016-12-18 22:12:06.679 plugin-container[1917:14146] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:12:16 INFO - PROCESS | 1916 | RSS: Main: 237015040 22:12:16 INFO - PROCESS | 1916 | 22:12:19 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49708/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49708/tests/kraken/driver.html?testName=audio-beat-detection) 22:12:20 INFO - PROCESS | 1916 | RSS: Main: 249712640 22:12:20 INFO - PROCESS | 1916 | 22:12:23 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49708/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49708/tests/kraken/driver.html?testName=audio-dft) 22:12:23 INFO - PROCESS | 1916 | RSS: Main: 257273856 22:12:23 INFO - PROCESS | 1916 | 22:12:27 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49708/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49708/tests/kraken/driver.html?testName=audio-fft) 22:12:27 INFO - PROCESS | 1916 | RSS: Main: 259899392 22:12:27 INFO - PROCESS | 1916 | 22:12:29 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49708/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49708/tests/kraken/driver.html?testName=audio-oscillator) 22:12:30 INFO - PROCESS | 1916 | RSS: Main: 261074944 22:12:30 INFO - PROCESS | 1916 | 22:12:32 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49708/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49708/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:12:33 INFO - PROCESS | 1916 | RSS: Main: 261386240 22:12:33 INFO - PROCESS | 1916 | 22:12:36 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49708/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49708/tests/kraken/driver.html?testName=imaging-darkroom) 22:12:36 INFO - PROCESS | 1916 | RSS: Main: 268402688 22:12:36 INFO - PROCESS | 1916 | 22:12:39 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49708/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49708/tests/kraken/driver.html?testName=imaging-desaturate) 22:12:40 INFO - PROCESS | 1916 | RSS: Main: 267313152 22:12:40 INFO - PROCESS | 1916 | 22:12:43 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49708/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49708/tests/kraken/driver.html?testName=json-parse-financial) 22:12:44 INFO - PROCESS | 1916 | RSS: Main: 267874304 22:12:44 INFO - PROCESS | 1916 | 22:12:45 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49708/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49708/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:12:46 INFO - PROCESS | 1916 | RSS: Main: 267239424 22:12:46 INFO - PROCESS | 1916 | 22:12:48 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49708/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49708/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:12:48 INFO - PROCESS | 1916 | RSS: Main: 267227136 22:12:48 INFO - PROCESS | 1916 | 22:12:51 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49708/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49708/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:12:51 INFO - PROCESS | 1916 | RSS: Main: 267317248 22:12:51 INFO - PROCESS | 1916 | 22:12:54 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49708/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49708/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:12:55 INFO - PROCESS | 1916 | RSS: Main: 267632640 22:12:55 INFO - PROCESS | 1916 | 22:12:58 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49708/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49708/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:12:59 INFO - PROCESS | 1916 | RSS: Main: 267333632 22:12:59 INFO - PROCESS | 1916 | 22:13:01 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49708/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49708/tests/kraken/driver.html?testName=ai-astar) 22:13:01 INFO - PROCESS | 1916 | RSS: Main: 268550144 22:13:01 INFO - PROCESS | 1916 | 22:13:01 INFO - PROCESS | 1916 | __start_tp_report 22:13:01 INFO - PROCESS | 1916 | _x_x_mozilla_page_load 22:13:01 INFO - PROCESS | 1916 | _x_x_mozilla_page_load_details 22:13:01 INFO - PROCESS | 1916 | |i|pagename|runs| 22:13:01 INFO - PROCESS | 1916 | |0;ai-astar;106;116;118;125;101;173;173;165;174;174 22:13:01 INFO - PROCESS | 1916 | |1;audio-beat-detection;147;112;111;148;104;151;143;145;130;114 22:13:01 INFO - PROCESS | 1916 | |2;audio-dft;207;268;251;201;196;198;195;194;194;222 22:13:01 INFO - PROCESS | 1916 | |3;audio-fft;98;59;55;83;60;61;60;60;72;61 22:13:01 INFO - PROCESS | 1916 | |4;audio-oscillator;77;66;84;74;72;71;83;72;105;115 22:13:01 INFO - PROCESS | 1916 | |5;imaging-gaussian-blur;115;122;115;157;161;123;119;121;118;116 22:13:01 INFO - PROCESS | 1916 | |6;imaging-darkroom;95;97;96;98;95;96;94;97;96;97 22:13:01 INFO - PROCESS | 1916 | |7;imaging-desaturate;93;97;114;85;104;147;133;100;119;81 22:13:01 INFO - PROCESS | 1916 | |8;json-parse-financial;57;58;56;57;56;60;58;58;69;89 22:13:01 INFO - PROCESS | 1916 | |9;json-stringify-tinderbox;76;73;68;82;76;70;71;70;71;82 22:13:01 INFO - PROCESS | 1916 | |10;stanford-crypto-aes;93;93;98;105;108;86;89;92;88;91 22:13:01 INFO - PROCESS | 1916 | |11;stanford-crypto-ccm;155;160;152;151;147;148;142;138;212;178 22:13:01 INFO - PROCESS | 1916 | |12;stanford-crypto-pbkdf2;180;171;174;173;174;171;170;169;202;173 22:13:01 INFO - PROCESS | 1916 | |13;stanford-crypto-sha256-iterative;72;87;87;90;88;87;70;68;66;68 22:13:01 INFO - PROCESS | 1916 | __end_tp_report 22:13:01 INFO - PROCESS | 1916 | __start_cc_report 22:13:01 INFO - PROCESS | 1916 | _x_x_mozilla_cycle_collect,939 22:13:01 INFO - PROCESS | 1916 | __end_cc_report 22:13:01 INFO - PROCESS | 1916 | __startTimestamp1482127981273__endTimestamp 22:13:01 INFO - PROCESS | 1916 | 22:13:01 INFO - PROCESS | 1916 | ------- Summary: start ------- 22:13:01 INFO - PROCESS | 1916 | Number of tests: 14 22:13:01 INFO - PROCESS | 1916 | 22:13:01 INFO - PROCESS | 1916 | [#0] ai-astar Cycles:10 Average:142.50 Median:169.00 stddev:31.65 (18.7%) stddev-sans-first:30.69 22:13:01 INFO - PROCESS | 1916 | Values: 106.0 116.0 118.0 125.0 101.0 173.0 173.0 165.0 174.0 174.0 22:13:01 INFO - PROCESS | 1916 | 22:13:01 INFO - PROCESS | 1916 | [#1] audio-beat-detection Cycles:10 Average:130.50 Median:144.00 stddev:18.45 (12.8%) stddev-sans-first:18.57 22:13:01 INFO - PROCESS | 1916 | Values: 147.0 112.0 111.0 148.0 104.0 151.0 143.0 145.0 130.0 114.0 22:13:01 INFO - PROCESS | 1916 | 22:13:01 INFO - PROCESS | 1916 | [#2] audio-dft Cycles:10 Average:212.60 Median:204.00 stddev:26.43 (13.0%) stddev-sans-first:27.96 22:13:01 INFO - PROCESS | 1916 | Values: 207.0 268.0 251.0 201.0 196.0 198.0 195.0 194.0 194.0 222.0 22:13:01 INFO - PROCESS | 1916 | 22:13:01 INFO - PROCESS | 1916 | [#3] audio-fft Cycles:10 Average:66.90 Median:61.00 stddev:13.62 (22.3%) stddev-sans-first:8.62 22:13:01 INFO - PROCESS | 1916 | Values: 98.0 59.0 55.0 83.0 60.0 61.0 60.0 60.0 72.0 61.0 22:13:01 INFO - PROCESS | 1916 | 22:13:01 INFO - PROCESS | 1916 | [#4] audio-oscillator Cycles:10 Average:81.90 Median:73.00 stddev:15.95 (21.8%) stddev-sans-first:16.82 22:13:01 INFO - PROCESS | 1916 | Values: 77.0 66.0 84.0 74.0 72.0 71.0 83.0 72.0 105.0 115.0 22:13:01 INFO - PROCESS | 1916 | 22:13:01 INFO - PROCESS | 1916 | [#5] imaging-gaussian-blur Cycles:10 Average:126.70 Median:121.50 stddev:17.28 (14.2%) stddev-sans-first:17.80 22:13:01 INFO - PROCESS | 1916 | Values: 115.0 122.0 115.0 157.0 161.0 123.0 119.0 121.0 118.0 116.0 22:13:01 INFO - PROCESS | 1916 | 22:13:01 INFO - PROCESS | 1916 | [#6] imaging-darkroom Cycles:10 Average:96.10 Median:96.50 stddev:1.20 (1.2%) stddev-sans-first:1.20 22:13:01 INFO - PROCESS | 1916 | Values: 95.0 97.0 96.0 98.0 95.0 96.0 94.0 97.0 96.0 97.0 22:13:01 INFO - PROCESS | 1916 | 22:13:01 INFO - PROCESS | 1916 | [#7] imaging-desaturate Cycles:10 Average:107.30 Median:114.00 stddev:21.03 (18.5%) stddev-sans-first:21.66 22:13:01 INFO - PROCESS | 1916 | Values: 93.0 97.0 114.0 85.0 104.0 147.0 133.0 100.0 119.0 81.0 22:13:01 INFO - PROCESS | 1916 | 22:13:01 INFO - PROCESS | 1916 | [#8] json-parse-financial Cycles:10 Average:61.80 Median:58.00 stddev:10.28 (17.7%) stddev-sans-first:10.76 22:13:01 INFO - PROCESS | 1916 | Values: 57.0 58.0 56.0 57.0 56.0 60.0 58.0 58.0 69.0 89.0 22:13:01 INFO - PROCESS | 1916 | 22:13:01 INFO - PROCESS | 1916 | [#9] json-stringify-tinderbox Cycles:10 Average:73.90 Median:74.50 stddev:4.98 (6.7%) stddev-sans-first:5.22 22:13:01 INFO - PROCESS | 1916 | Values: 76.0 73.0 68.0 82.0 76.0 70.0 71.0 70.0 71.0 82.0 22:13:01 INFO - PROCESS | 1916 | 22:13:01 INFO - PROCESS | 1916 | [#10] stanford-crypto-aes Cycles:10 Average:94.30 Median:91.50 stddev:7.24 (7.9%) stddev-sans-first:7.67 22:13:01 INFO - PROCESS | 1916 | Values: 93.0 93.0 98.0 105.0 108.0 86.0 89.0 92.0 88.0 91.0 22:13:01 INFO - PROCESS | 1916 | 22:13:01 INFO - PROCESS | 1916 | [#11] stanford-crypto-ccm Cycles:10 Average:158.30 Median:153.50 stddev:21.83 (14.2%) stddev-sans-first:23.12 22:13:01 INFO - PROCESS | 1916 | Values: 155.0 160.0 152.0 151.0 147.0 148.0 142.0 138.0 212.0 178.0 22:13:01 INFO - PROCESS | 1916 | 22:13:01 INFO - PROCESS | 1916 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.70 Median:173.50 stddev:9.73 (5.6%) stddev-sans-first:10.20 22:13:01 INFO - PROCESS | 1916 | Values: 180.0 171.0 174.0 173.0 174.0 171.0 170.0 169.0 202.0 173.0 22:13:01 INFO - PROCESS | 1916 | 22:13:01 INFO - PROCESS | 1916 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.30 Median:87.00 stddev:10.17 (11.7%) stddev-sans-first:10.52 22:13:01 INFO - PROCESS | 1916 | Values: 72.0 87.0 87.0 90.0 88.0 87.0 70.0 68.0 66.0 68.0 22:13:01 INFO - PROCESS | 1916 | -------- Summary: end -------- 22:13:01 INFO - PROCESS | 1916 | 22:13:02 INFO - TEST-INFO | 1916: exit 0 22:13:02 INFO - TEST-OK | kraken | took 61636ms 22:13:02 INFO - SUITE-END | took 1177s 22:13:02 INFO - Completed test suite (00:19:37) 22:13:02 INFO - javascript benchmark 22:13:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7126.624070978294, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3319.7344212463004, 3329.134425049487, 3354.4129777288986, 3359.5464612277337, 3373.949390759138, 4824.469308376085, 5562.08225642769, 5564.7217639118035, 5579.8605034874145, 5583.246261754667, 5448.700321482317, 5547.44525547446, 5586.050371436855, 5589.077802162648, 5594.692291923942, 5086.567641750456, 5089.669171503859, 5134.974325128369, 5178.145605975028, 5184.11869982104, 5092.465291144432, 5143.254228136937, 5165.560927321182, 5181.896362072756, 5183.663061900965, 5145.073886700392, 5158.123119069767, 5168.224766285069, 5177.663451875616, 5187.325647665798, 5132.358455193083, 5134.871628209287, 5159.38087429509, 5162.638615296931, 5175.353080864892, 5054.241863720435, 5114.693118412907, 5117.1556693145585, 5124.0776660201145, 5132.076226279269, 5559.722013899289, 5587.38538760736, 5588.91616625751, 5627.2684551008515, 5630.746616402272, 4842.41890973082, 5871.207387002785, 6080.270367555878, 6097.0, 6104.694765261719, 6060.090986352038, 6113.2330707408255, 6121.632702037892, 6126.975740326267, 6191.2570491540855, 5675.914861277084, 5680.091185410314, 5700.087985922274, 5732.885342293131, 5756.280464941882, 6228.875422491525, 6255.437010669061, 6268.874622507525, 6281.214848143982, 6297.559170858088, 5847.532197424196, 5855.180274761537, 6031.336552979169, 6032.698365081728, 6080.14877917092, 6058.969975104243, 6086.482648974887, 6120.204373431426, 6121.632702037803, 6127.89207890682, 5506.981208476444, 5829.912551311733, 6074.787382441594, 6120.479759220516, 6125.509959203253, 5649.4915457608195, 5736.913946290725, 6073.8177854663545, 6131.0, 6134.0, 4707.7881495332795, 4730.408698912636, 4767.928481072787, 4784.593309568656, 4808.7595620219545, 4269.316909294498, 4290.978545107255, 4293.720908140961, 4325.307950727932, 4333.155034768253, 5885.05839065756, 5885.852853678693, 5895.41045895407, 5902.2622172228475, 5929.525637948954, 6158.630482171084, 6184.979478386106, 6192.721327540272, 6203.286622038433, 6211.409916057967, 5716.399778023248, 5739.62692424991, 5748.367679555246, 5811.3026436827, 5871.236739223959, 5228.0, 5239.790408383698, 5239.921401178985, 5251.684898906078, 5271.525562699376, 6337.049442583557, 6347.936520634827, 6354.6822658867795, 6356.936430635635, 6380.1067850501895, 6149.108379285068, 6214.036824292242, 6221.440070393686, 6235.220597425322, 6273.121762953281, 6187.474064704551, 6230.408111229426, 6240.781572644935, 6259.530535209787, 6332.683365831598, 6108.584917381041, 6111.287884814297, 6127.311699317154, 6130.448259656652, 6144.341027922525, 6123.601965872204, 6151.292601350723, 6191.288001879841, 6199.535034872312, 6247.96876015617, 4483.439570053743, 5470.425605311385, 5907.645541267538, 6060.030395136757, 6068.78759243442, 5796.884062318814, 5883.0, 5918.112283157561, 5930.881382372415, 5931.3772053935445, 5677.829665110054, 5682.261306030189, 5760.769569217352, 5844.795432159854, 5858.765649373977, 5945.405459454193, 6028.608140470856, 6039.214902062879, 6059.666718330534, 6068.089786532056, 5851.765929362778, 5867.413258674099, 5880.059190529666, 5891.086881533369, 5902.61632993854], "value": 5594.2285447079985, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [435.1992334105247, 450.5472000639357, 477.2149813556699, 514.3133916221843, 529.8860744939838, 35485.64514354856, 38577.0, 38582.80708596457, 38755.99999999996, 38765.61234387655, 39639.60360396395, 40426.797866010704, 40537.0, 40546.0, 40622.593774062254, 8458.069612342637, 8680.08859069798, 9125.543722813849, 9496.0, 11201.215914885961, 11193.608223712172, 11409.37248451335, 11415.942920285428, 11419.600313989013, 11480.081593472501, 11402.942985285103, 11439.027682646985, 11442.199046066737, 11449.255798373079, 11467.19729618927, 11361.772764544663, 11363.204575679705, 11388.202825802196, 11392.71518212043, 11411.771764564663, 11221.607243746512, 11325.603603873908, 11338.206325557214, 11338.886611133865, 11343.26268792534, 33180.0, 37622.623773762185, 39355.606443935765, 39528.40707389391, 40013.59918404905, 2992.9102126936227, 3351.581052368454, 3440.8451619677176, 3490.581130264359, 3560.359135355635, 29301.56047659287, 29458.705412945812, 29926.70073299261, 29967.550486742715, 30189.396212075855, 2778.6943436222, 3191.377681352137, 3203.5515027896113, 3224.4679627861374, 3308.602967643874, 487978.0, 492283.5385823048, 504135.47932260105, 510662.0, 516915.0, 58985.41014589885, 59366.0, 59507.40492595105, 59560.0, 59613.0, 62424.0, 62489.687551561954, 62980.37019629837, 63034.0, 63121.73756524844, 15641.687166256612, 16087.356505739874, 16127.435539756052, 16157.4344897928, 16185.028898266142, 11196.888031119584, 11494.712632184095, 12221.450034748457, 12411.255324680547, 12499.999999999978, 16630.75053874193, 16632.584185395463, 16667.749983750255, 16741.414050508174, 16755.664886702198, 7183.030290910765, 7261.673224704901, 7270.236625154388, 7271.927280727231, 7440.888386674204, 841.2512863551444, 926.7636752628036, 992.4591097851544, 998.9100980911753, 1023.2888142740724, 23223.883880580488, 27446.45107097847, 28734.856325718654, 28991.710082899324, 29735.702642973727, 1042.280237607953, 1093.4587379247323, 1094.0536435982976, 1124.971875703114, 1152.124385467051, 13898.096623719426, 14609.050411723203, 15417.691646167239, 15700.371985120468, 15930.920345398199, 660312.0, 885323.0, 888165.0, 894890.0, 913293.0, 49828.0, 50338.993220135395, 50501.989960201325, 50569.0, 50606.98786024259, 1160.570588882119, 1328.335832083958, 1368.4868174434587, 1372.4167228927865, 1373.8900887929142, 27643.861780690968, 27788.30529236785, 28053.298667533476, 28373.85813071004, 28555.28611784722, 36563.817180913924, 43463.56536434722, 43710.781446092566, 43918.560814392724, 43973.78013109914, 504795.0, 505417.0, 507359.0, 507673.0, 508559.0, 21469.92650367444, 21831.672524912457, 23547.175848845207, 23621.165460144894, 24259.393515161555, 40029.199416012096, 40816.183676325716, 40851.18297633971, 41314.79342603268, 41455.37816932688, 34225.48661770125, 34248.31503369869, 34293.0, 35186.47220291749, 35340.93977180689, 34027.829860850536, 34267.65732342644, 34951.12622184466, 35238.823805880806, 35526.822365888, 34007.0, 34190.0, 34240.0, 34463.82768086143, 34517.65482345144, 29461.55807662844, 30826.229344266572, 30905.0728478146, 30940.0, 30994.845025774728, 20590.79409205949, 23389.41526461852, 23405.76594234036, 23424.6486302709, 23701.881490593125], "value": 19443.250074303694, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.7808994658764, 378.16425699204757, 378.9862118832123, 379.63554987212274, 413.22107826746594, 15813.051216926979, 19236.23055077797, 19535.706964395544, 19942.302019429324, 20144.294949676765, 19858.404247872586, 19914.40256792295, 20260.696089558667, 20430.89784551079, 20488.795112048876, 5383.380911195204, 5503.7798488060425, 5688.573356998221, 5937.762489500415, 5947.702614869261, 5437.374701909272, 5817.389174136718, 5842.824715258528, 5846.356900740915, 5858.5606079543995, 5570.275864137657, 5756.481916627503, 5791.478766910977, 5806.506446952014, 5865.442782935615, 5949.3158286796925, 5987.191729116579, 6007.4893634041155, 6026.427489388501, 6028.608140470856, 4667.253239481666, 5713.228714123582, 5735.942640573582, 5774.797882074149, 5780.826575202751, 12877.742445151138, 13074.346282685829, 13090.672733181651, 19085.904570477196, 23800.880995595086, 2212.867227966311, 2222.1228872697297, 2277.39648993017, 2404.9398765031015, 2695.7034726180104, 9796.755081122958, 9834.754131146707, 9910.9504452478, 10122.443265620388, 10288.588456461732, 1853.249433979227, 1899.0504747626187, 1902.3891975385563, 1916.559191385989, 1948.084400331856, 121441.0, 121682.0, 123252.38373808074, 124268.0, 126366.99999999908, 11037.889621103846, 11361.431928403546, 11455.198136130392, 11484.138689598314, 11679.065674746, 11953.880461195451, 14269.500567480089, 14299.928500357431, 14329.996900217093, 14371.712565748627, 7396.260373962561, 8144.185581441927, 8374.953130858643, 8377.37169712274, 8511.872321915176, 7884.093329267093, 8239.95880020596, 8266.669333226604, 8408.243258106677, 8419.410641255054, 4007.73949693269, 4227.852025179104, 4479.566538707648, 4504.076664283814, 4559.970812988188, 1882.8587855910862, 1940.9902950485157, 1941.79611140831, 1942.1648691062978, 1944.6499630066724, 802.4583406200791, 803.843250566134, 888.2094935507404, 927.9536023198813, 955.6082006377352, 19969.900150499445, 20518.0, 20600.175992960114, 20827.8958605206, 20848.895755521124, 826.8057006603443, 881.5107615273585, 897.5512243878061, 920.6823645842173, 926.6061923682408, 8575.528345941033, 8969.641214351483, 9254.768630784285, 10431.5827366906, 10821.971912668283, 503824.0, 509427.0, 509983.0, 533804.0, 540860.0, 76452.61773691207, 79890.00274993172, 111228.44385778181, 111435.32847007301, 127282.72717272709, 980.7401038724744, 1012.2711647613706, 1029.1972261635935, 1032.0917592518535, 1049.3441599000778, 14632.195229262492, 18098.106070033205, 18428.539286517946, 19162.13770380364, 19576.21695132179, 44750.0, 46664.53335466602, 52835.471645283054, 53016.0, 53885.19172212498, 79184.20815791999, 79317.60341198256, 80309.79535307092, 80657.0, 81411.59294203491, 7923.881141782993, 8056.516609003478, 8059.959700201462, 8177.141400152898, 8204.302634275915, 11851.407429628656, 12333.0, 12439.875601243872, 12598.0, 12615.116941813985, 9722.659706910224, 10697.786044278915, 10767.784644307227, 10956.890431095906, 11000.339979601249, 10618.787624247627, 10658.14518653999, 10687.358758474516, 10868.239223254297, 11036.613718519813, 10904.854990225913, 10976.780464390828, 11139.4936139368, 11358.26171298863, 11359.54561817518, 7767.495112817649, 7856.764004619895, 7929.907336139026, 7984.842197881391, 8245.422820402515, 8095.55474448911, 10249.897501025192, 10461.843072354073, 10490.580376784843, 10788.083012943987], "value": 9171.166598552296, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1476.239736535684, 1497.6255936015993, 1716.622343084521, 1836.9173387197575, 1856.7864695560006, 7537.057867766529, 7792.92207077929, 8039.356851451885, 8485.703000394988, 8498.957505212473, 7913.446058775888, 8222.465539739927, 8261.83476330473, 8272.17278272174, 8414.789630259247, 1941.8737782044193, 2255.842091053631, 2317.351141680332, 2318.53629274145, 2322.7909488146065, 2274.7725227477204, 2314.0, 2317.687112239843, 2318.40880575453, 2319.4549280918973, 1919.4913347962802, 2278.8176945844375, 2280.5552917181158, 2296.9655455168186, 2319.1535089692225, 2171.0555908179913, 2197.516546359798, 2199.3072182262536, 2206.9668954965687, 2207.4922767763424, 2303.2284184798114, 2337.0651739304244, 2340.2511196417154, 2340.520193360374, 2344.6717459555675, 8155.836883262361, 8287.751367458986, 8335.916640833575, 8336.791580210482, 8374.706885259047, 5067.543921047087, 5229.424763276036, 5233.947660523384, 5238.528532432061, 5250.369955605313, 3318.96681033189, 3409.556757621518, 3598.784072955631, 3611.6388361163936, 3625.981870090659, 1045.4041196517987, 1050.5640159333868, 1086.0, 1091.4815462655254, 1109.8224284114503, 10583.10043646291, 10595.947020264926, 10643.680689579325, 10762.73093172677, 10786.838197427045, 4429.468463784302, 4562.908741825211, 4596.655250856133, 4675.111728771591, 4692.577668009895, 9337.066293370608, 9373.390729602554, 9384.155426011555, 9391.248700103975, 9410.95294523523, 5167.6641018333685, 5208.479152084761, 5213.713245771443, 5219.582433405319, 5225.346831646044, 3739.4016957286703, 3946.8223929923224, 4703.95296047042, 4729.07782982322, 4796.2805579163405, 2998.760099192103, 3613.4760459733193, 3817.9427308590393, 3832.9808350958624, 3919.2553414851086, 1090.3675867996542, 1096.8135416979133, 1153.9747742711488, 1206.6862615719801, 1277.8274932884128, 734.9926500734924, 746.8655641984386, 789.4907784479094, 881.299367003234, 907.3013779389833, 5564.721763911788, 5661.122526008476, 5923.407659234128, 6041.516678665696, 6156.168917196122, 766.4213518793275, 845.4631309118754, 886.0, 905.714699869539, 906.243286855483, 3459.0314711880715, 3683.1344634010984, 3779.622037796253, 3816.7709937403843, 3842.269968705937, 9705.6603018894, 9951.701448956543, 10011.649592264235, 10040.397576145455, 10077.344972576759, 9973.002699729968, 9977.052180042885, 10001.44992025431, 10006.249531285182, 10012.299139060196, 7506.3994880408345, 7625.923740762632, 7804.882926756103, 7805.336546393507, 7814.804629884298, 5440.564754819684, 5450.427705091067, 5609.467100625434, 5647.519960803378, 5677.290338707661, 9275.953620231856, 9281.535923203948, 9298.163165315154, 9304.348695591514, 9321.067893210624, 7899.84200315979, 8620.267277281251, 9040.915090189219, 9301.674441394518, 9553.140217380469, 7019.438444924496, 7083.114610673666, 7126.608036558039, 7157.677904494414, 7200.783976480714, 7732.961335193288, 7777.533347999138, 7789.7273595423885, 7795.4543181976715, 7810.179931107379, 6980.965095174492, 7139.0, 7164.677589508589, 7249.347558719741, 7260.636968151677, 6301.842453938688, 6785.355391237825, 6906.102206713095, 6954.687039083152, 7106.253843346581, 5668.886622267614, 6515.446187074152, 6567.441767449629, 6629.6685165740555, 6657.500687448364, 4837.959838634711, 4871.244957031666, 4884.877878053077, 4927.162382394929, 4978.6763860348965, 4853.174960256694, 4871.732054737024, 4925.876853078702, 4993.600511958962, 5003.574696150868], "value": 4361.91306222687, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.56008627619366, 343.2054793153847, 350.3658378335212, 369.538077403246, 371.9423489359149, 4696.765161741916, 4798.736069516175, 4875.561199492045, 4932.742150751558, 5189.572867461446, 4263.722858014227, 4547.863564093074, 4950.504949505048, 5141.383034035921, 5249.553787928031, 3823.1971286029966, 4140.896477588054, 4144.70987030908, 4156.210320039183, 4156.896077598054, 3861.575226725058, 3985.641292283694, 4194.874153775391, 4201.033762234688, 4215.85244516442, 3640.617735137812, 3893.766374017554, 4099.048684946833, 4112.203701483006, 4118.496748686723, 3457.844397002141, 3896.376579747255, 4084.019835239536, 4111.224923176858, 4126.968257935516, 2809.9157025289273, 3266.591676040495, 3674.1341753432366, 3687.6681098701247, 3715.4241092630687, 3988.823297127354, 4840.394950631171, 5049.394072711299, 5089.720065396402, 5174.793008279665, 3869.6456240315665, 4477.552244775529, 4600.987782687804, 4755.928661070052, 4793.640476964207, 4872.585830204437, 4927.384076990376, 5425.213344065128, 5578.453560348344, 5581.6371935824045, 3339.9499007514905, 3407.386670399327, 3799.715021373408, 4028.9597104028876, 4059.0867054912705, 4731.33761273422, 5705.918803845918, 5810.535157187415, 5847.006008978441, 5952.553558483131, 4071.328230841937, 4106.466159399259, 4112.773797441109, 4122.958770412318, 4125.267387697174, 3560.6101920111723, 4149.0, 4188.204241194163, 4206.957930420718, 4214.157168566299, 3928.5678575356687, 4338.804753786024, 4640.95359046412, 4679.532046795362, 4721.536323739651, 5186.299849520266, 5365.651232669864, 5613.382527921925, 5623.831285061455, 5624.0, 1186.6736647422028, 1297.7145028093805, 1301.7113058072441, 1345.4012964230824, 1354.5665387076237, 827.4662842466576, 865.8787769712245, 911.1025639744978, 972.4359871274644, 974.936629119105, 4369.300911854088, 5112.188296436945, 5149.867029253558, 5175.430702622709, 5218.660787048906, 4282.743035417885, 5430.4880546245595, 5449.673019618756, 5508.3114610673665, 5566.471185237396, 4610.400647915817, 4949.653524253267, 4993.275974983607, 5013.072581572421, 5042.319286896222, 2378.702662167229, 2523.987380063088, 2593.922182334533, 2680.7855371570226, 2864.393402004166, 5371.785128594891, 5437.673739575638, 5466.125419932791, 5626.943730562642, 5789.565782566325, 4927.817323842252, 5039.546440820343, 5181.637285389987, 5198.610104242197, 5600.607957443021, 4466.575675310905, 4846.933674591584, 4923.9167383175645, 5151.845444636667, 5634.154876768435, 4902.289168070539, 5114.948850511596, 5247.606429517725, 5397.541208997279, 5423.942331245369, 4992.8751781205765, 5010.549050585465, 5041.899162016666, 5306.602004849729, 5436.429174936575, 5010.0, 5098.0, 5181.455947125498, 5640.32316122068, 5742.36833948257, 3374.4983482350117, 3632.473291372789, 3637.890863274106, 3798.6011468796482, 3842.9640583938376, 4807.903841923212, 4859.757012149449, 5305.60207984395, 5431.501509305764, 5431.538319242909, 4589.518100599523, 4835.637327200404, 4966.453690094015, 4990.750462476934, 5034.672746271481, 4803.855884323476, 5185.455527169593, 5235.476452354734, 5562.887422515432, 5651.067573850269, 5227.790888364423, 5276.364327058624, 5357.892842143057, 5498.780048798163, 5504.669719816824, 5040.722760248075, 5098.566621837185, 5354.6265852514225, 5427.511523962862, 5605.243292155506, 4824.421069471686, 5183.300254465599, 5320.466714616755, 5475.0, 5475.945240547703], "value": 3991.1431909326243, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [744.9702011919524, 754.5472716370178, 796.6136423834441, 808.773543407846, 845.9661613535457, 14564.271786410676, 15644.983076100045, 15652.921735391335, 15714.921425392871, 15748.763768543467, 14174.858251417509, 14296.785548216785, 14366.353514091865, 14393.568192954202, 14408.567742967702, 858.8797568340437, 867.7440155154245, 900.2572877376158, 909.745271324027, 911.2800887299026, 891.777055736066, 897.6050537763396, 902.3908861518481, 912.9543522823866, 914.561010714854, 939.8919124300725, 944.2870632672323, 944.8110377924409, 945.7588314979671, 950.1543626172712, 889.4307643108448, 934.0892629686069, 936.2276122199213, 939.6899023322287, 942.2885721280423, 690.6650274616807, 692.1210063219733, 693.0262980512388, 695.3290075077574, 697.1494776372867, 15001.849981500154, 15006.324715387724, 15231.162286074261, 15328.693426131527, 15351.232438378036, 14055.718885622231, 14322.212278324689, 14605.12369257848, 14607.269636518131, 14684.192369419678, 11750.941245293805, 11756.706082347924, 11806.704832379173, 11809.468573914108, 11856.940715296454, 3721.8697345592773, 3725.155256303628, 3749.286901307157, 3777.924441511182, 3806.257779732953, 109175.0, 109822.0, 111982.0, 112744.0, 113862.0, 10975.725606859893, 11086.61196858106, 11104.722381940355, 11145.777084458265, 11213.327200368005, 23377.181798636968, 23412.591863321475, 23572.882135589556, 23694.289171324886, 23702.407439814142, 7947.3642108631175, 7997.5601341925585, 8025.197480251928, 8105.189481051848, 8202.302804261704, 4311.698181127291, 4369.737815731066, 4398.853252105349, 4433.82264709408, 4507.706999045052, 3183.0928185467033, 3206.823624700617, 3409.62494125646, 3693.8707145249787, 3698.8335524901445, 573.1545969694668, 621.2700077409027, 642.9260635026939, 643.2891654721559, 673.1182151381705, 541.8970395624818, 549.3188446326526, 569.4419468920481, 610.484140900938, 635.2028204603193, 9219.400738951947, 9312.534373281444, 9319.68118451296, 9505.239580833517, 9520.143187113194, 619.662284055191, 637.3690046853582, 660.7951535024158, 666.6699983508195, 669.4209508774875, 3882.359410697203, 4392.802323895432, 4578.5421457853945, 4584.266517357207, 4616.3306320584, 103496.0, 107370.46314768377, 108380.0, 112078.0, 115939.42030289794, 84734.0, 85083.57458212669, 87434.0, 90217.0, 93569.53215234017, 76516.23483765092, 77712.61143694245, 81098.0, 85197.0, 86119.27761444611, 9505.477198754135, 9914.355566888129, 9916.950415247878, 9989.950050249703, 10003.949980250343, 37082.8145859269, 52556.737216313675, 52783.94432111413, 53286.2007069902, 54161.187582187085, 72238.55522889618, 75796.0, 77052.61473692596, 77131.0, 79585.60207198927, 12388.256704597752, 12825.679358016125, 12970.692473419218, 13031.218126912416, 13082.149660272053, 10271.537780799732, 12073.27560346382, 12374.62876113718, 12707.301098439675, 12748.553800617305, 13378.799318010433, 13454.394552245369, 13602.183868967893, 13653.79519307231, 13900.026998110035, 13157.934210328887, 13474.595762127152, 13600.115992460458, 13723.0, 14003.50987715425, 13157.473701052233, 13164.539241126515, 13351.332433378486, 13423.798643020167, 13689.589312320646, 10787.0, 11306.830397544209, 11362.772744545227, 11438.124760544633, 11728.589499367481, 8729.0, 9748.366356186825, 10144.782626084918, 11508.13688973347, 11568.305901645928], "value": 7543.059112936211, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1606.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 116.0, 118.0, 125.0, 101.0, 173.0, 173.0, 165.0, 174.0, 174.0], "value": 142.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 112.0, 111.0, 148.0, 104.0, 151.0, 143.0, 145.0, 130.0, 114.0], "value": 130.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [207.0, 268.0, 251.0, 201.0, 196.0, 198.0, 195.0, 194.0, 194.0, 222.0], "value": 212.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 59.0, 55.0, 83.0, 60.0, 61.0, 60.0, 60.0, 72.0, 61.0], "value": 66.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 66.0, 84.0, 74.0, 72.0, 71.0, 83.0, 72.0, 105.0, 115.0], "value": 81.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 122.0, 115.0, 157.0, 161.0, 123.0, 119.0, 121.0, 118.0, 116.0], "value": 126.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 96.0, 98.0, 95.0, 96.0, 94.0, 97.0, 96.0, 97.0], "value": 96.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 97.0, 114.0, 85.0, 104.0, 147.0, 133.0, 100.0, 119.0, 81.0], "value": 107.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 56.0, 57.0, 56.0, 60.0, 58.0, 58.0, 69.0, 89.0], "value": 61.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 73.0, 68.0, 82.0, 76.0, 70.0, 71.0, 70.0, 71.0, 82.0], "value": 73.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 98.0, 105.0, 108.0, 86.0, 89.0, 92.0, 88.0, 91.0], "value": 94.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 160.0, 152.0, 151.0, 147.0, 148.0, 142.0, 138.0, 212.0, 178.0], "value": 158.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 171.0, 174.0, 173.0, 174.0, 171.0, 170.0, 169.0, 202.0, 173.0], "value": 175.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 87.0, 87.0, 90.0, 88.0, 87.0, 70.0, 68.0, 66.0, 68.0], "value": 78.3, "unit": "score"}]}]} 22:13:02 INFO - Return code: 0 22:13:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:13:02 ERROR - # TBPL SUCCESS # 22:13:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:13:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:13:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:13:02 INFO - 2016-12-18 22:13:02.574 screenresolution[1921:14539] starting screenresolution argv=screenresolution get 22:13:02 INFO - 2016-12-18 22:13:02.577 screenresolution[1921:14539] Display 0: 1600x1200x32@60 22:13:02 INFO - 2016-12-18 22:13:02.583 screenresolution[1922:14543] starting screenresolution argv=screenresolution list 22:13:02 INFO - Available Modes on Display 0 22:13:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:13:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:13:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:13:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:13:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:13:02 INFO - Intel Iris: 22:13:02 INFO - Chipset Model: Intel Iris 22:13:02 INFO - Type: GPU 22:13:02 INFO - Bus: Built-In 22:13:02 INFO - VRAM (Dynamic, Max): 1536 MB 22:13:02 INFO - Vendor: Intel (0x8086) 22:13:02 INFO - Device ID: 0x0a2e 22:13:02 INFO - Revision ID: 0x0009 22:13:02 INFO - Displays: 22:13:02 INFO - Display: 22:13:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:13:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:13:02 INFO - Display Serial Number: 1600x1200 60 22:13:02 INFO - Main Display: Yes 22:13:02 INFO - Mirror: Off 22:13:02 INFO - Online: Yes 22:13:02 INFO - Rotation: Supported 22:13:02 INFO - Return code: 0 22:13:02 INFO - Running post-action listener: _resource_record_post_action 22:13:02 INFO - [mozharness: 2016-12-19 06:13:02.817758Z] Finished run-tests step (success) 22:13:02 INFO - Running post-run listener: _resource_record_post_run 22:13:02 INFO - Total resource usage - Wall time: 1199s; CPU: 55.0%; Read bytes: 659456; Write bytes: 550934016; Read time: 9; Write time: 2053 22:13:02 INFO - TinderboxPrint: CPU usage
54.8% 22:13:02 INFO - TinderboxPrint: I/O read bytes / time
659,456 / 9 22:13:02 INFO - TinderboxPrint: I/O write bytes / time
550,934,016 / 2,053 22:13:02 INFO - TinderboxPrint: CPU idle
2,166.9 (45.2%) 22:13:02 INFO - TinderboxPrint: CPU system
969.7 (20.2%) 22:13:02 INFO - TinderboxPrint: CPU user
1,661.1 (34.6%) 22:13:02 INFO - TinderboxPrint: Swap in / out
250,093,568 / 0 22:13:02 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 230638080; Write bytes: 226297344; Read time: 16452; Write time: 480 22:13:02 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 659456; Write bytes: 297529344; Read time: 9; Write time: 1508 22:13:03 INFO - Running post-run listener: _upload_blobber_files 22:13:03 INFO - Blob upload gear active. 22:13:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:13:03 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 22:13:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:13:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:13:03 INFO - (blobuploader) - INFO - Open directory for files ... 22:13:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:13:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:13:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:13:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:13:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:13:04 INFO - (blobuploader) - INFO - Done attempting. 22:13:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:13:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:13:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:13:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:13:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:13:05 INFO - (blobuploader) - INFO - Done attempting. 22:13:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 22:13:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:13:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:13:07 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 22:13:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:13:07 INFO - (blobuploader) - INFO - Done attempting. 22:13:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:13:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:13:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:13:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:13:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:13:09 INFO - (blobuploader) - INFO - Done attempting. 22:13:09 INFO - (blobuploader) - INFO - Iteration through files over. 22:13:09 INFO - Return code: 0 22:13:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:13:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:13:09 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6937f0dbf537a6246db1528fc4d1c543404813a0a529e66649d2693bf1982e8f292eb5e5b75786685a9b4a760b5fa9b2d76da6981384bd21c5e247c27e735c4a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/94e9c37361cadbc05415c442e7c7018fe7e3b3fb9d6d5fd608ad2c0210d65ec0d78d9d652dfb165b1ee7bc56e801d56a7604c7b6820ecea2f662ffd88d00abf6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/123f54350e434990f71358cf16e8824b9a23ec28d6eec37c687820c687bba86658254da3f1135b990ba649cddb635a119c9984e9c50e9f65184b6351ee9d53a0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/789385d125819a0fba84f6ebf0dfe96bc4e76a84fbabc1daeb4a449b3c07e8b968939f8064a2a8f6b8075aa6e787872ca5a500fd56b94266024aeeb8fead1189"} 22:13:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:13:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:13:09 INFO - Contents: 22:13:09 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6937f0dbf537a6246db1528fc4d1c543404813a0a529e66649d2693bf1982e8f292eb5e5b75786685a9b4a760b5fa9b2d76da6981384bd21c5e247c27e735c4a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/94e9c37361cadbc05415c442e7c7018fe7e3b3fb9d6d5fd608ad2c0210d65ec0d78d9d652dfb165b1ee7bc56e801d56a7604c7b6820ecea2f662ffd88d00abf6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/123f54350e434990f71358cf16e8824b9a23ec28d6eec37c687820c687bba86658254da3f1135b990ba649cddb635a119c9984e9c50e9f65184b6351ee9d53a0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/789385d125819a0fba84f6ebf0dfe96bc4e76a84fbabc1daeb4a449b3c07e8b968939f8064a2a8f6b8075aa6e787872ca5a500fd56b94266024aeeb8fead1189"} 22:13:09 INFO - Running post-run listener: copy_logs_to_upload_dir 22:13:09 INFO - Copying logs to upload dir... 22:13:09 INFO - mkdir: /builds/slave/test/build/upload/logs 22:13:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.023833 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-12-18 22:13:10.053686) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-18 22:13:10.057445) ========= 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.BKhl6J7qsg/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.nzYHKsbBHH/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6937f0dbf537a6246db1528fc4d1c543404813a0a529e66649d2693bf1982e8f292eb5e5b75786685a9b4a760b5fa9b2d76da6981384bd21c5e247c27e735c4a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/94e9c37361cadbc05415c442e7c7018fe7e3b3fb9d6d5fd608ad2c0210d65ec0d78d9d652dfb165b1ee7bc56e801d56a7604c7b6820ecea2f662ffd88d00abf6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/123f54350e434990f71358cf16e8824b9a23ec28d6eec37c687820c687bba86658254da3f1135b990ba649cddb635a119c9984e9c50e9f65184b6351ee9d53a0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/789385d125819a0fba84f6ebf0dfe96bc4e76a84fbabc1daeb4a449b3c07e8b968939f8064a2a8f6b8075aa6e787872ca5a500fd56b94266024aeeb8fead1189"} build_url:https://queue.taskcluster.net/v1/task/b-1z4RHZQgSCbqNJohgJNA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011085 build_url: 'https://queue.taskcluster.net/v1/task/b-1z4RHZQgSCbqNJohgJNA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6937f0dbf537a6246db1528fc4d1c543404813a0a529e66649d2693bf1982e8f292eb5e5b75786685a9b4a760b5fa9b2d76da6981384bd21c5e247c27e735c4a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/94e9c37361cadbc05415c442e7c7018fe7e3b3fb9d6d5fd608ad2c0210d65ec0d78d9d652dfb165b1ee7bc56e801d56a7604c7b6820ecea2f662ffd88d00abf6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/123f54350e434990f71358cf16e8824b9a23ec28d6eec37c687820c687bba86658254da3f1135b990ba649cddb635a119c9984e9c50e9f65184b6351ee9d53a0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/789385d125819a0fba84f6ebf0dfe96bc4e76a84fbabc1daeb4a449b3c07e8b968939f8064a2a8f6b8075aa6e787872ca5a500fd56b94266024aeeb8fead1189"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-18 22:13:10.092834) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-18 22:13:10.093364) ========= 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.BKhl6J7qsg/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.nzYHKsbBHH/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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.005028 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-18 22:13:10.143993) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-18 22:13:10.144443) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-18 22:13:10.144905) ========= ========= Total master_lag: 0.18 =========