builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0204 starttime: 1483551818.7 results: success (0) buildid: 20170104075439 builduid: bf620d76b39049efa36d1077e6da3bb1 revision: 05a3443d658d29e77cae1da1915ca51814534d73 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-04 09:43:38.697080) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-04 09:43:38.697581) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-04 09:43:38.697880) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-04 09:43:38.723835) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 09:43:38.724230) ========= 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.yVAaPYKoqA/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.v2Af5p7F46/Listeners TMPDIR=/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-04 09:43:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 38.5M=0s 2017-01-04 09:43:38 (38.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127333 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 09:43:38.876429) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 09:43:38.876778) ========= 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.yVAaPYKoqA/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.v2Af5p7F46/Listeners TMPDIR=/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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.021271 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 09:43:38.925519) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 09:43:38.925881) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 05a3443d658d29e77cae1da1915ca51814534d73 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 05a3443d658d29e77cae1da1915ca51814534d73 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yVAaPYKoqA/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.v2Af5p7F46/Listeners TMPDIR=/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-04 09:43:39,012 truncating revision to first 12 chars 2017-01-04 09:43:39,012 Setting DEBUG logging. 2017-01-04 09:43:39,012 attempt 1/10 2017-01-04 09:43:39,012 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/05a3443d658d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-04 09:43:39,693 unpacking tar archive at: mozilla-aurora-05a3443d658d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.942241 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 09:43:39.891886) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-04 09:43:39.892201) ========= script_repo_revision: 05a3443d658d29e77cae1da1915ca51814534d73 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-04 09:43:39.892581) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-04 09:43:39.892858) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-04 09:43:39.913008) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2017-01-04 09:43:39.913381) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yVAaPYKoqA/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.v2Af5p7F46/Listeners TMPDIR=/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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 09:43:40 INFO - MultiFileLogger online at 20170104 09:43:40 in /builds/slave/test 09:43:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:43:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:43:40 INFO - {'append_to_log': False, 09:43:40 INFO - 'base_work_dir': '/builds/slave/test', 09:43:40 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:43:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:43:40 INFO - 'branch': 'Mozilla-Aurora', 09:43:40 INFO - 'buildbot_json_path': 'buildprops.json', 09:43:40 INFO - 'config_files': ('talos/mac_config.py',), 09:43:40 INFO - 'default_actions': ('clobber', 09:43:40 INFO - 'read-buildbot-config', 09:43:40 INFO - 'download-and-extract', 09:43:40 INFO - 'populate-webroot', 09:43:40 INFO - 'create-virtualenv', 09:43:40 INFO - 'install', 09:43:40 INFO - 'run-tests'), 09:43:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:43:40 INFO - 'download_minidump_stackwalk': True, 09:43:40 INFO - 'download_symbols': 'ondemand', 09:43:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:43:40 INFO - 'tooltool.py': '/tools/tooltool.py', 09:43:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:43:40 INFO - '/tools/misc-python/virtualenv.py')}, 09:43:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:43:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:43:40 INFO - 'installer_path': 'installer.exe', 09:43:40 INFO - 'log_level': 'info', 09:43:40 INFO - 'log_name': 'talos', 09:43:40 INFO - 'log_to_console': True, 09:43:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:43:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:43:40 INFO - 'opt_config_files': (), 09:43:40 INFO - 'pip_index': False, 09:43:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:43:40 INFO - 'cmd': ('bash', 09:43:40 INFO - '-c', 09:43:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:43:40 INFO - 'enabled': True, 09:43:40 INFO - 'halt_on_failure': False, 09:43:40 INFO - 'name': 'check_screen_resolution'},), 09:43:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:43:40 INFO - 'cmd': ('bash', 09:43:40 INFO - '-c', 09:43:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:43:40 INFO - 'enabled': True, 09:43:40 INFO - 'halt_on_failure': False, 09:43:40 INFO - 'name': 'check_screen_resolution'},), 09:43:40 INFO - 'run_cmd_checks_enabled': True, 09:43:40 INFO - 'sps_profile': False, 09:43:40 INFO - 'sps_profile_interval': 0, 09:43:40 INFO - 'suite': 'dromaeojs-e10s', 09:43:40 INFO - 'system_bits': '32', 09:43:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:43:40 INFO - 'title': 't-yosemite-r7-0204', 09:43:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:43:40 INFO - 'use_talos_json': True, 09:43:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:43:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:43:40 INFO - 'work_dir': 'build'} 09:43:40 INFO - [mozharness: 2017-01-04 17:43:40.119972Z] Running clobber step. 09:43:40 INFO - Running pre-action listener: _resource_record_pre_action 09:43:40 INFO - Running main action method: clobber 09:43:40 INFO - rmtree: /builds/slave/test/build 09:43:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:43:41 INFO - Running post-action listener: _resource_record_post_action 09:43:41 INFO - [mozharness: 2017-01-04 17:43:41.652984Z] Finished clobber step (success) 09:43:41 INFO - [mozharness: 2017-01-04 17:43:41.653240Z] Running read-buildbot-config step. 09:43:41 INFO - Running pre-action listener: _resource_record_pre_action 09:43:41 INFO - Running main action method: read_buildbot_config 09:43:41 INFO - Using buildbot properties: 09:43:41 INFO - { 09:43:41 INFO - "project": "", 09:43:41 INFO - "product": "firefox", 09:43:41 INFO - "script_repo_revision": "production", 09:43:41 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 09:43:41 INFO - "repository": "", 09:43:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 09:43:41 INFO - "buildid": "20170104075439", 09:43:41 INFO - "pgo_build": "False", 09:43:41 INFO - "basedir": "/builds/slave/test", 09:43:41 INFO - "buildnumber": 22, 09:43:41 INFO - "slavename": "t-yosemite-r7-0204", 09:43:41 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 09:43:41 INFO - "platform": "yosemite_r7", 09:43:41 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:43:41 INFO - "branch": "mozilla-aurora", 09:43:41 INFO - "repo_path": "releases/mozilla-aurora", 09:43:41 INFO - "revision": "05a3443d658d29e77cae1da1915ca51814534d73", 09:43:41 INFO - "stage_platform": "macosx64", 09:43:41 INFO - "builduid": "bf620d76b39049efa36d1077e6da3bb1", 09:43:41 INFO - "slavebuilddir": "test" 09:43:41 INFO - } 09:43:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 09:43:41 INFO - Running post-action listener: _resource_record_post_action 09:43:41 INFO - [mozharness: 2017-01-04 17:43:41.658421Z] Finished read-buildbot-config step (success) 09:43:41 INFO - [mozharness: 2017-01-04 17:43:41.658537Z] Running download-and-extract step. 09:43:41 INFO - Running pre-action listener: _resource_record_pre_action 09:43:41 INFO - Running main action method: download_and_extract 09:43:41 INFO - mkdir: /builds/slave/test/build/tests 09:43:41 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')]} 09:43:41 INFO - trying https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 09:43:41 INFO - Downloading https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 09:43:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 09:43:43 INFO - Downloaded 1199 bytes. 09:43:43 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 09:43:43 INFO - Using the following test package requirements: 09:43:43 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:43:43 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:43:43 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 09:43:43 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:43:43 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 09:43:43 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:43:43 INFO - u'jsshell-mac.zip'], 09:43:43 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:43:43 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 09:43:43 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:43:43 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:43:43 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 09:43:43 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:43:43 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 09:43:43 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:43:43 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 09:43:43 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:43:43 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 09:43:43 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:43:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:43:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:43:43 INFO - Fetch https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:43:43 INFO - Http code: 200 09:43:43 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 09:43:43 INFO - x-amz-cf-id: -fYH-1dmx-COmV4ZdRtaDalNx1W5IRTxxM8AMTwH7j3DpK1KoGkY7Q== 09:43:43 INFO - x-amz-version-id: xiQIi6vFqUzdDZVjrvUVueoOVn2pAt_3 09:43:43 INFO - Expected file size: 16269736 09:43:43 INFO - Obtained file size: 16269736 09:43:50 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 09:43:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:43:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:43:50 INFO - Fetch https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:43:51 INFO - Http code: 200 09:43:51 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 09:43:51 INFO - x-amz-cf-id: 30xDo9iygfmNPJWnOW0DIXQYNTO7BamPDx4G5kW7Nlc3zLKaYk2FUQ== 09:43:51 INFO - x-amz-version-id: xiQIi6vFqUzdDZVjrvUVueoOVn2pAt_3 09:43:51 INFO - Expected file size: 16269736 09:43:51 INFO - Obtained file size: 16269736 09:43:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 09:43:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:43:57 INFO - Fetch https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 09:43:58 INFO - Http code: 200 09:43:58 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 09:43:58 INFO - x-amz-cf-id: 4V0YIq0oXRSNzX3d0xSMY4AXV39LDN_8pzxUMR2wVS1laUe6_9HqJg== 09:43:58 INFO - x-amz-version-id: LIjeU08MbdcqBYZcOgmIInFI8zN.Pt7v 09:43:59 INFO - Expected file size: 14409038 09:43:59 INFO - Obtained file size: 14409038 09:43:59 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')]} 09:43:59 INFO - trying https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:43:59 INFO - Downloading https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 09:43:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 09:44:02 INFO - Downloaded 109833456 bytes. 09:44:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:44:02 INFO - mkdir: /builds/slave/test/properties 09:44:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:44:02 INFO - Writing to file /builds/slave/test/properties/build_url 09:44:02 INFO - Contents: 09:44:02 INFO - build_url:https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:44:02 INFO - Running post-action listener: _resource_record_post_action 09:44:02 INFO - Running post-action listener: set_extra_try_arguments 09:44:02 INFO - [mozharness: 2017-01-04 17:44:02.873652Z] Finished download-and-extract step (success) 09:44:02 INFO - [mozharness: 2017-01-04 17:44:02.873788Z] Running populate-webroot step. 09:44:02 INFO - Running pre-action listener: _resource_record_pre_action 09:44:02 INFO - Running main action method: populate_webroot 09:44:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:44:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:44:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:44:02 INFO - '--tppagecycles', 09:44:02 INFO - '7'], 09:44:02 INFO - 'tests': ['tsvgm']}}, 09:44:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:44:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:44:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:44:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:44:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:44:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:44:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:44:02 INFO - 'talos_options': ['--disable-e10s'], 09:44:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:44:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:44:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:44:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:44:02 INFO - 'talos_options': ['--disable-e10s'], 09:44:02 INFO - 'tests': ['damp', 'tps']}, 09:44:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:44:02 INFO - 'tests': ['damp', 'tps']}, 09:44:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:44:02 INFO - 'tests': ['dromaeo_dom']}, 09:44:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:44:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:44:02 INFO - 'tests': ['basic_compositor_video']}, 09:44:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:44:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:44:02 INFO - 'tests': ['a11yr', 09:44:02 INFO - 'ts_paint', 09:44:02 INFO - 'tpaint', 09:44:02 INFO - 'sessionrestore', 09:44:02 INFO - 'sessionrestore_no_auto_restore', 09:44:02 INFO - 'tabpaint']}, 09:44:02 INFO - 'other-e10s': {'tests': ['a11yr', 09:44:02 INFO - 'ts_paint', 09:44:02 INFO - 'tpaint', 09:44:02 INFO - 'sessionrestore', 09:44:02 INFO - 'sessionrestore_no_auto_restore', 09:44:02 INFO - 'tabpaint']}, 09:44:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:44:02 INFO - 'ts_paint', 09:44:02 INFO - 'tpaint', 09:44:02 INFO - 'sessionrestore', 09:44:02 INFO - 'sessionrestore_no_auto_restore', 09:44:02 INFO - 'tabpaint']}, 09:44:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:44:02 INFO - 'ts_paint', 09:44:02 INFO - 'tpaint', 09:44:02 INFO - 'sessionrestore', 09:44:02 INFO - 'sessionrestore_no_auto_restore', 09:44:02 INFO - 'tabpaint']}, 09:44:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:44:02 INFO - 'tests': ['a11yr', 09:44:02 INFO - 'ts_paint', 09:44:02 INFO - 'tpaint', 09:44:02 INFO - 'sessionrestore', 09:44:02 INFO - 'sessionrestore_no_auto_restore', 09:44:02 INFO - 'tabpaint']}, 09:44:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:44:02 INFO - 'tests': ['a11yr', 09:44:02 INFO - 'ts_paint', 09:44:02 INFO - 'tpaint', 09:44:02 INFO - 'sessionrestore', 09:44:02 INFO - 'sessionrestore_no_auto_restore', 09:44:02 INFO - 'tabpaint']}, 09:44:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:44:02 INFO - 'tests': ['tsvgx', 09:44:02 INFO - 'tsvgr_opacity', 09:44:02 INFO - 'tart', 09:44:02 INFO - 'tscrollx', 09:44:02 INFO - 'cart']}, 09:44:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:44:02 INFO - 'tsvgr_opacity', 09:44:02 INFO - 'tart', 09:44:02 INFO - 'tscrollx', 09:44:02 INFO - 'cart']}, 09:44:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:44:02 INFO - 'talos_options': ['--disable-e10s'], 09:44:02 INFO - 'tests': ['tp5o']}, 09:44:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:44:02 INFO - 'tests': ['tp5o']}, 09:44:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:44:02 INFO - 'talos_options': ['--disable-e10s', 09:44:02 INFO - '--xperf_path', 09:44:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:44:02 INFO - 'tests': ['tp5n']}, 09:44:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:44:02 INFO - 'talos_options': ['--xperf_path', 09:44:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:44:02 INFO - 'tests': ['tp5n']}}, 09:44:02 INFO - 'talos.zip': {'path': '', 09:44:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:44:02 INFO - Running post-action listener: _resource_record_post_action 09:44:02 INFO - [mozharness: 2017-01-04 17:44:02.892745Z] Finished populate-webroot step (success) 09:44:02 INFO - [mozharness: 2017-01-04 17:44:02.892851Z] Running create-virtualenv step. 09:44:02 INFO - Running pre-action listener: _resource_record_pre_action 09:44:02 INFO - Running main action method: create_virtualenv 09:44:02 INFO - Creating virtualenv /builds/slave/test/build/venv 09:44:02 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 09:44:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:44:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:44:03 INFO - Using real prefix '/tools/python27' 09:44:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:44:03 INFO - Installing distribute.............................................................................................................................................................................................done. 09:44:06 INFO - Installing pip.................done. 09:44:06 INFO - Return code: 0 09:44:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:44:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:44:06 INFO - Reading from file tmpfile_stdout 09:44:06 INFO - Output received: 09:44:06 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) 09:44:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:44:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:44:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:44:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f59e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f514570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d5855a950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3b2d30>, '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 0x10f218db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3aab20>, '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.yVAaPYKoqA/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.v2Af5p7F46/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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 09:44:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:44:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:44:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yVAaPYKoqA/Render', 09:44:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:06 INFO - 'HOME': '/Users/cltbld', 09:44:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:06 INFO - 'LOGNAME': 'cltbld', 09:44:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:06 INFO - 'MOZ_NO_REMOTE': '1', 09:44:06 INFO - 'NO_EM_RESTART': '1', 09:44:06 INFO - 'PAGER': '/bin/cat', 09:44:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:06 INFO - 'PWD': '/builds/slave/test', 09:44:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:06 INFO - 'SHELL': '/bin/bash', 09:44:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.v2Af5p7F46/Listeners', 09:44:06 INFO - 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/', 09:44:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:06 INFO - 'USER': 'cltbld', 09:44:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:06 INFO - 'XPC_FLAGS': '0x0', 09:44:06 INFO - 'XPC_SERVICE_NAME': '0', 09:44:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:07 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 09:44:07 INFO - Cleaning up... 09:44:07 INFO - Return code: 0 09:44:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:44:07 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')]} 09:44:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:07 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')]} 09:44:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:07 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 0x10f59e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f514570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d5855a950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3b2d30>, '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 0x10f218db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3aab20>, '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.yVAaPYKoqA/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.v2Af5p7F46/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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 09:44:07 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 09:44:07 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 09:44:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yVAaPYKoqA/Render', 09:44:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:07 INFO - 'HOME': '/Users/cltbld', 09:44:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:07 INFO - 'LOGNAME': 'cltbld', 09:44:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:07 INFO - 'MOZ_NO_REMOTE': '1', 09:44:07 INFO - 'NO_EM_RESTART': '1', 09:44:07 INFO - 'PAGER': '/bin/cat', 09:44:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:07 INFO - 'PWD': '/builds/slave/test', 09:44:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:07 INFO - 'SHELL': '/bin/bash', 09:44:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.v2Af5p7F46/Listeners', 09:44:07 INFO - 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/', 09:44:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:07 INFO - 'USER': 'cltbld', 09:44:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:07 INFO - 'XPC_FLAGS': '0x0', 09:44:07 INFO - 'XPC_SERVICE_NAME': '0', 09:44:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:07 INFO - Downloading/unpacking psutil>=3.1.1 09:44:07 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 09:44:07 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 09:44:07 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 09:44:07 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 09:44:07 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 09:44:07 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 09:44:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:44:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:44:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:44:10 INFO - Installing collected packages: psutil 09:44:10 INFO - Running setup.py install for psutil 09:44:10 INFO - building 'psutil._psutil_osx' extension 09:44:10 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 09:44:11 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 09:44:11 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 09:44:11 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 09:44:11 INFO - building 'psutil._psutil_posix' extension 09:44:11 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 09:44:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:44:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:44:11 INFO - ^ 09:44:11 INFO - 1 warning generated. 09:44:11 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 09:44:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:44:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:44:11 INFO - Successfully installed psutil 09:44:11 INFO - Cleaning up... 09:44:11 INFO - Return code: 0 09:44:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:44:11 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')]} 09:44:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:11 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')]} 09:44:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:11 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 0x10f59e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f514570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d5855a950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3b2d30>, '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 0x10f218db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3aab20>, '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.yVAaPYKoqA/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.v2Af5p7F46/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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 09:44:11 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 09:44:11 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 09:44:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yVAaPYKoqA/Render', 09:44:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:11 INFO - 'HOME': '/Users/cltbld', 09:44:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:11 INFO - 'LOGNAME': 'cltbld', 09:44:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:11 INFO - 'MOZ_NO_REMOTE': '1', 09:44:11 INFO - 'NO_EM_RESTART': '1', 09:44:11 INFO - 'PAGER': '/bin/cat', 09:44:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:11 INFO - 'PWD': '/builds/slave/test', 09:44:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:11 INFO - 'SHELL': '/bin/bash', 09:44:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.v2Af5p7F46/Listeners', 09:44:11 INFO - 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/', 09:44:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:11 INFO - 'USER': 'cltbld', 09:44:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:11 INFO - 'XPC_FLAGS': '0x0', 09:44:11 INFO - 'XPC_SERVICE_NAME': '0', 09:44:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:44:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:44:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:44:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:44:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:44:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:44:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:44:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:44:14 INFO - Installing collected packages: mozsystemmonitor 09:44:14 INFO - Running setup.py install for mozsystemmonitor 09:44:14 INFO - Successfully installed mozsystemmonitor 09:44:14 INFO - Cleaning up... 09:44:15 INFO - Return code: 0 09:44:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:44:15 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')]} 09:44:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:15 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')]} 09:44:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:15 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 0x10f59e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f514570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d5855a950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3b2d30>, '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 0x10f218db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3aab20>, '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.yVAaPYKoqA/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.v2Af5p7F46/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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 09:44:15 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 09:44:15 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 09:44:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yVAaPYKoqA/Render', 09:44:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:15 INFO - 'HOME': '/Users/cltbld', 09:44:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:15 INFO - 'LOGNAME': 'cltbld', 09:44:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:15 INFO - 'MOZ_NO_REMOTE': '1', 09:44:15 INFO - 'NO_EM_RESTART': '1', 09:44:15 INFO - 'PAGER': '/bin/cat', 09:44:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:15 INFO - 'PWD': '/builds/slave/test', 09:44:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:15 INFO - 'SHELL': '/bin/bash', 09:44:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.v2Af5p7F46/Listeners', 09:44:15 INFO - 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/', 09:44:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:15 INFO - 'USER': 'cltbld', 09:44:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:15 INFO - 'XPC_FLAGS': '0x0', 09:44:15 INFO - 'XPC_SERVICE_NAME': '0', 09:44:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:15 INFO - Downloading/unpacking jsonschema==2.5.1 09:44:15 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 09:44:15 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 09:44:15 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 09:44:15 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 09:44:15 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 09:44:15 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 09:44:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:44:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:44:17 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 09:44:17 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 09:44:17 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 09:44:17 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 09:44:17 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 09:44:17 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 09:44:18 INFO - Downloading functools32-3.2.3-2.tar.gz 09:44:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:44:18 INFO - warning: no files found matching '*.txt' 09:44:18 INFO - no previously-included directories found matching 'build' 09:44:18 INFO - no previously-included directories found matching 'dist' 09:44:18 INFO - no previously-included directories found matching '.git*' 09:44:18 INFO - Installing collected packages: jsonschema, functools32 09:44:18 INFO - Running setup.py install for functools32 09:44:18 INFO - warning: no files found matching '*.txt' 09:44:18 INFO - no previously-included directories found matching 'build' 09:44:18 INFO - no previously-included directories found matching 'dist' 09:44:18 INFO - no previously-included directories found matching '.git*' 09:44:18 INFO - Successfully installed jsonschema functools32 09:44:18 INFO - Cleaning up... 09:44:18 INFO - Return code: 0 09:44:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:44:18 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')]} 09:44:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:18 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')]} 09:44:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:18 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 0x10f59e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f514570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d5855a950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3b2d30>, '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 0x10f218db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3aab20>, '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.yVAaPYKoqA/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.v2Af5p7F46/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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 09:44:18 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 09:44:18 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 09:44:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yVAaPYKoqA/Render', 09:44:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:18 INFO - 'HOME': '/Users/cltbld', 09:44:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:18 INFO - 'LOGNAME': 'cltbld', 09:44:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:18 INFO - 'MOZ_NO_REMOTE': '1', 09:44:18 INFO - 'NO_EM_RESTART': '1', 09:44:18 INFO - 'PAGER': '/bin/cat', 09:44:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:18 INFO - 'PWD': '/builds/slave/test', 09:44:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:18 INFO - 'SHELL': '/bin/bash', 09:44:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.v2Af5p7F46/Listeners', 09:44:18 INFO - 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/', 09:44:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:18 INFO - 'USER': 'cltbld', 09:44:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:18 INFO - 'XPC_FLAGS': '0x0', 09:44:18 INFO - 'XPC_SERVICE_NAME': '0', 09:44:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:44:18 INFO - Cleaning up... 09:44:18 INFO - Return code: 0 09:44:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:44:18 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')]} 09:44:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:18 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')]} 09:44:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:19 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 0x10f59e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f514570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d5855a950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3b2d30>, '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 0x10f218db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3aab20>, '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.yVAaPYKoqA/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.v2Af5p7F46/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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 09:44:19 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 09:44:19 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 09:44:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yVAaPYKoqA/Render', 09:44:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:19 INFO - 'HOME': '/Users/cltbld', 09:44:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:19 INFO - 'LOGNAME': 'cltbld', 09:44:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:19 INFO - 'MOZ_NO_REMOTE': '1', 09:44:19 INFO - 'NO_EM_RESTART': '1', 09:44:19 INFO - 'PAGER': '/bin/cat', 09:44:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:19 INFO - 'PWD': '/builds/slave/test', 09:44:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:19 INFO - 'SHELL': '/bin/bash', 09:44:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.v2Af5p7F46/Listeners', 09:44:19 INFO - 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/', 09:44:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:19 INFO - 'USER': 'cltbld', 09:44:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:19 INFO - 'XPC_FLAGS': '0x0', 09:44:19 INFO - 'XPC_SERVICE_NAME': '0', 09:44:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:19 INFO - Downloading/unpacking blobuploader==1.2.4 09:44:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:44:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:44:19 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 09:44:19 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 09:44:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:44:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:44:21 INFO - Downloading blobuploader-1.2.4.tar.gz 09:44:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:44:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:44:22 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 09:44:22 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 09:44:22 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 09:44:22 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 09:44:22 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 09:44:22 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 09:44:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:44:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:44:22 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 09:44:22 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 09:44:22 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 09:44:22 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 09:44:22 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 09:44:22 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 09:44:23 INFO - Downloading docopt-0.6.1.tar.gz 09:44:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:44:23 INFO - Installing collected packages: blobuploader, requests, docopt 09:44:23 INFO - Running setup.py install for blobuploader 09:44:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:44:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:44:23 INFO - Running setup.py install for requests 09:44:24 INFO - Running setup.py install for docopt 09:44:24 INFO - Successfully installed blobuploader requests docopt 09:44:24 INFO - Cleaning up... 09:44:24 INFO - Return code: 0 09:44:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:44: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')]} 09:44:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44: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')]} 09:44:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:24 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 0x10f59e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f514570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d5855a950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3b2d30>, '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 0x10f218db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3aab20>, '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.yVAaPYKoqA/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.v2Af5p7F46/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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 09:44:24 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 09:44:24 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 09:44:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yVAaPYKoqA/Render', 09:44:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:24 INFO - 'HOME': '/Users/cltbld', 09:44:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:24 INFO - 'LOGNAME': 'cltbld', 09:44:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:24 INFO - 'MOZ_NO_REMOTE': '1', 09:44:24 INFO - 'NO_EM_RESTART': '1', 09:44:24 INFO - 'PAGER': '/bin/cat', 09:44:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:24 INFO - 'PWD': '/builds/slave/test', 09:44:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:24 INFO - 'SHELL': '/bin/bash', 09:44:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.v2Af5p7F46/Listeners', 09:44:24 INFO - 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/', 09:44:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:24 INFO - 'USER': 'cltbld', 09:44:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:24 INFO - 'XPC_FLAGS': '0x0', 09:44:24 INFO - 'XPC_SERVICE_NAME': '0', 09:44:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:44:24 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-vjSusN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:44:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:44:24 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-dXQgVc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:44:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:44:24 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-QAKApq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:44:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:44:24 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-BNT0OG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:44:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:44:24 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-0LooxD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:44:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:44:25 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-QW829a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:44:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:44:25 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-lf7xMV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:44:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:44:25 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-CSlWVH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:44:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:44:25 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-VejqCG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:44:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:44:25 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-2zORhg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:44:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:44:25 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-hhOjcY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:44:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:44:25 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-RGkQdO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:44:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:44:25 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-lAFQzR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:44:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:44:25 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-SzncDj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:44:26 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-3BZCkO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:44:26 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-DJgC0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:44:26 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-uOwNvt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:44:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:44:26 INFO - Running setup.py install for manifestparser 09:44:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:44:26 INFO - Running setup.py install for mozcrash 09:44:26 INFO - Running setup.py install for mozdebug 09:44:26 INFO - Running setup.py install for mozdevice 09:44:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:44:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:44:26 INFO - Running setup.py install for mozfile 09:44:27 INFO - Running setup.py install for mozhttpd 09:44:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:44:27 INFO - Running setup.py install for mozinfo 09:44:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:44:27 INFO - Running setup.py install for mozInstall 09:44:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:44:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:44:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:44:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:44:27 INFO - Running setup.py install for mozleak 09:44:27 INFO - Running setup.py install for mozlog 09:44:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:44:27 INFO - Running setup.py install for moznetwork 09:44:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:44:27 INFO - Running setup.py install for mozprocess 09:44:28 INFO - Running setup.py install for mozprofile 09:44:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:44:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:44:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:44:28 INFO - Running setup.py install for mozrunner 09:44:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:44:28 INFO - Running setup.py install for mozscreenshot 09:44:28 INFO - Running setup.py install for moztest 09:44:28 INFO - Running setup.py install for mozversion 09:44:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:44:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:44:28 INFO - Cleaning up... 09:44:28 INFO - Return code: 0 09:44:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:44: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')]} 09:44:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44: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')]} 09:44:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:28 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 0x10f59e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f514570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d5855a950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3b2d30>, '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 0x10f218db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3aab20>, '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.yVAaPYKoqA/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.v2Af5p7F46/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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 09:44:28 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 09:44:28 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 09:44:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yVAaPYKoqA/Render', 09:44:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:28 INFO - 'HOME': '/Users/cltbld', 09:44:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:28 INFO - 'LOGNAME': 'cltbld', 09:44:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:28 INFO - 'MOZ_NO_REMOTE': '1', 09:44:28 INFO - 'NO_EM_RESTART': '1', 09:44:28 INFO - 'PAGER': '/bin/cat', 09:44:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:28 INFO - 'PWD': '/builds/slave/test', 09:44:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:28 INFO - 'SHELL': '/bin/bash', 09:44:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.v2Af5p7F46/Listeners', 09:44:28 INFO - 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/', 09:44:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:28 INFO - 'USER': 'cltbld', 09:44:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:28 INFO - 'XPC_FLAGS': '0x0', 09:44:28 INFO - 'XPC_SERVICE_NAME': '0', 09:44:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:44:29 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-DvPjD0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:44:29 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)) 09:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:44:29 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-szj5NM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:44:29 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)) 09:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:44:29 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-bv4moX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:44:29 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)) 09:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:44:29 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-DbsCbv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:44:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 09:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:44:29 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-z_aeC0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:44:29 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)) 09:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:44:29 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-0BFDil-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:44:29 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)) 09:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:44:29 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-2Pdwml-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:44:29 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)) 09:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:44:29 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-azf_Kw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:44:30 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)) 09:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:44:30 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-7t9EmY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:44:30 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)) 09:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:44:30 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-zFzyOG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:44:30 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)) 09:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:44:30 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-GkUn5i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:44:30 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)) 09:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:44:30 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-M1WR4K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:44:30 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)) 09:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:44:30 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-enKDqh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:44:30 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)) 09:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:44:30 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-GrI6DR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:44:30 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)) 09:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:44:30 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-2SC0qt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:44:30 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)) 09:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:44:30 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-U5IPci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:44:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:44:30 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-Uk4hAo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:44:30 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)) 09:44:30 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)) 09:44:30 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)) 09:44:30 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)) 09:44:30 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:44:30 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:44:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:44:30 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 09:44:30 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 09:44:30 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 09:44:30 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 09:44:30 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 09:44:30 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 09:44:33 INFO - Downloading blessings-1.6.tar.gz 09:44:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:44:33 INFO - Installing collected packages: blessings 09:44:33 INFO - Running setup.py install for blessings 09:44:33 INFO - Successfully installed blessings 09:44:33 INFO - Cleaning up... 09:44:34 INFO - Return code: 0 09:44:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:44:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:44:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:44:34 INFO - Reading from file tmpfile_stdout 09:44:34 INFO - Current package versions: 09:44:34 INFO - blessings == 1.6 09:44:34 INFO - blobuploader == 1.2.4 09:44:34 INFO - docopt == 0.6.1 09:44:34 INFO - functools32 == 3.2.3-2 09:44:34 INFO - jsonschema == 2.5.1 09:44:34 INFO - manifestparser == 1.1 09:44:34 INFO - mozInstall == 1.12 09:44:34 INFO - mozcrash == 1.0 09:44:34 INFO - mozdebug == 0.1 09:44:34 INFO - mozdevice == 0.48 09:44:34 INFO - mozfile == 1.2 09:44:34 INFO - mozhttpd == 0.7 09:44:34 INFO - mozinfo == 0.9 09:44:34 INFO - mozleak == 0.1 09:44:34 INFO - mozlog == 3.4 09:44:34 INFO - moznetwork == 0.27 09:44:34 INFO - mozprocess == 0.23 09:44:34 INFO - mozprofile == 0.28 09:44:34 INFO - mozrunner == 6.13 09:44:34 INFO - mozscreenshot == 0.1 09:44:34 INFO - mozsystemmonitor == 0.3 09:44:34 INFO - moztest == 0.8 09:44:34 INFO - mozversion == 1.4 09:44:34 INFO - psutil == 3.1.1 09:44:34 INFO - requests == 1.2.3 09:44:34 INFO - wsgiref == 0.1.2 09:44:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:44:34 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')]} 09:44:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:34 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')]} 09:44:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:34 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 0x10f59e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f514570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d5855a950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3b2d30>, '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 0x10f218db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3aab20>, '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.yVAaPYKoqA/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.v2Af5p7F46/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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 09:44:34 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 09:44:34 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 09:44:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yVAaPYKoqA/Render', 09:44:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:34 INFO - 'HOME': '/Users/cltbld', 09:44:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:34 INFO - 'LOGNAME': 'cltbld', 09:44:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:34 INFO - 'MOZ_NO_REMOTE': '1', 09:44:34 INFO - 'NO_EM_RESTART': '1', 09:44:34 INFO - 'PAGER': '/bin/cat', 09:44:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:34 INFO - 'PWD': '/builds/slave/test', 09:44:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:34 INFO - 'SHELL': '/bin/bash', 09:44:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.v2Af5p7F46/Listeners', 09:44:34 INFO - 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/', 09:44:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:34 INFO - 'USER': 'cltbld', 09:44:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:34 INFO - 'XPC_FLAGS': '0x0', 09:44:34 INFO - 'XPC_SERVICE_NAME': '0', 09:44:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:34 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)) 09:44:34 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)) 09:44:34 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)) 09:44:34 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)) 09:44:34 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)) 09:44:34 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)) 09:44:34 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)) 09:44:34 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)) 09:44:34 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)) 09:44:34 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)) 09:44:34 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)) 09:44:34 INFO - Cleaning up... 09:44:34 INFO - Return code: 0 09:44:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:44:34 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')]} 09:44:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:34 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')]} 09:44:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f59e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f514570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d5855a950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3b2d30>, '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 0x10f218db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3aab20>, '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.yVAaPYKoqA/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.v2Af5p7F46/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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 09:44: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', 'jsonschema'] in /builds/slave/test/build 09:44: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 jsonschema 09:44:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yVAaPYKoqA/Render', 09:44:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:35 INFO - 'HOME': '/Users/cltbld', 09:44:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:35 INFO - 'LOGNAME': 'cltbld', 09:44:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:35 INFO - 'MOZ_NO_REMOTE': '1', 09:44:35 INFO - 'NO_EM_RESTART': '1', 09:44:35 INFO - 'PAGER': '/bin/cat', 09:44:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:35 INFO - 'PWD': '/builds/slave/test', 09:44:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:35 INFO - 'SHELL': '/bin/bash', 09:44:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.v2Af5p7F46/Listeners', 09:44:35 INFO - 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/', 09:44:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:35 INFO - 'USER': 'cltbld', 09:44:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:35 INFO - 'XPC_FLAGS': '0x0', 09:44:35 INFO - 'XPC_SERVICE_NAME': '0', 09:44:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:44:35 INFO - Cleaning up... 09:44:35 INFO - Return code: 0 09:44:35 INFO - Running post-action listener: _resource_record_post_action 09:44:35 INFO - Running post-action listener: _start_resource_monitoring 09:44:35 INFO - Starting resource monitoring. 09:44:35 INFO - [mozharness: 2017-01-04 17:44:35.572098Z] Finished create-virtualenv step (success) 09:44:35 INFO - [mozharness: 2017-01-04 17:44:35.573193Z] Running install step. 09:44:35 INFO - Running pre-action listener: _resource_record_pre_action 09:44:35 INFO - Running main action method: install 09:44:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:44:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:44:35 INFO - Reading from file tmpfile_stdout 09:44:35 INFO - Detecting whether we're running mozinstall >=1.0... 09:44:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:44:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:44:35 INFO - Reading from file tmpfile_stdout 09:44:35 INFO - Output received: 09:44:35 INFO - Usage: mozinstall [options] installer 09:44:35 INFO - Options: 09:44:35 INFO - -h, --help show this help message and exit 09:44:35 INFO - -d DEST, --destination=DEST 09:44:35 INFO - Directory to install application into. [default: 09:44:35 INFO - "/builds/slave/test"] 09:44:35 INFO - --app=APP Application being installed. [default: firefox] 09:44:35 INFO - mkdir: /builds/slave/test/build/application 09:44:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:44:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 09:45:07 INFO - Reading from file tmpfile_stdout 09:45:07 INFO - Output received: 09:45:07 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:45:07 INFO - Running post-action listener: _resource_record_post_action 09:45:07 INFO - [mozharness: 2017-01-04 17:45:07.130971Z] Finished install step (success) 09:45:07 INFO - [mozharness: 2017-01-04 17:45:07.131113Z] Running run-tests step. 09:45:07 INFO - Running pre-action listener: _resource_record_pre_action 09:45:07 INFO - Running main action method: run_tests 09:45:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:45:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:45:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:45:07 INFO - 2017-01-04 09:45:07.144 screenresolution[1900:17015] starting screenresolution argv=screenresolution get 09:45:07 INFO - 2017-01-04 09:45:07.146 screenresolution[1900:17015] Display 0: 1600x1200x32@60 09:45:07 INFO - 2017-01-04 09:45:07.152 screenresolution[1901:17018] starting screenresolution argv=screenresolution list 09:45:07 INFO - Available Modes on Display 0 09:45:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:45:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:45:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:45:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:45:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:45:07 INFO - Intel Iris: 09:45:07 INFO - Chipset Model: Intel Iris 09:45:07 INFO - Type: GPU 09:45:07 INFO - Bus: Built-In 09:45:07 INFO - VRAM (Dynamic, Max): 1536 MB 09:45:07 INFO - Vendor: Intel (0x8086) 09:45:07 INFO - Device ID: 0x0a2e 09:45:07 INFO - Revision ID: 0x0009 09:45:07 INFO - Displays: 09:45:07 INFO - Display: 09:45:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:45:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:45:07 INFO - Display Serial Number: 1600x1200 60 09:45:07 INFO - Main Display: Yes 09:45:07 INFO - Mirror: Off 09:45:07 INFO - Online: Yes 09:45:07 INFO - Rotation: Supported 09:45:07 INFO - Return code: 0 09:45:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:45:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:45:07 INFO - Python 2.7.3 09:45:07 INFO - Return code: 0 09:45:07 INFO - grabbing minidump binary from tooltool 09:45:07 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')]} 09:45:07 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 0x10f3b2d30>, '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 0x10f218db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3aab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:45:07 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 09:45:07 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 09:45:07 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 09:45:07 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:45:07 INFO - Return code: 0 09:45:07 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:45:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:45:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:45:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:45:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:45:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0204', '--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 09:45:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0204 --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 09:45:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yVAaPYKoqA/Render', 09:45:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:45:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:45:07 INFO - 'HOME': '/Users/cltbld', 09:45:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:45:07 INFO - 'LOGNAME': 'cltbld', 09:45:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:45:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:45:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:07 INFO - 'MOZ_NO_REMOTE': '1', 09:45:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:45:07 INFO - 'NO_EM_RESTART': '1', 09:45:07 INFO - 'PAGER': '/bin/cat', 09:45:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:45:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:07 INFO - 'PWD': '/builds/slave/test', 09:45:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:45:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:45:07 INFO - 'SHELL': '/bin/bash', 09:45:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.v2Af5p7F46/Listeners', 09:45:07 INFO - 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/', 09:45:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:45:07 INFO - 'USER': 'cltbld', 09:45:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:45:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:45:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:07 INFO - 'XPC_FLAGS': '0x0', 09:45:07 INFO - 'XPC_SERVICE_NAME': '0', 09:45:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:45:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0204', '--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 09:45:07 INFO - mozversion application_buildid: 20170104075439 09:45:07 INFO - mozversion application_changeset: 05a3443d658d29e77cae1da1915ca51814534d73 09:45:07 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:45:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:45:07 INFO - mozversion application_name: Firefox 09:45:07 INFO - mozversion application_remotingname: firefox-dev 09:45:07 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:45:07 INFO - mozversion application_vendor: Mozilla 09:45:07 INFO - mozversion application_version: 52.0a2 09:45:07 INFO - mozversion platform_buildid: 20170104075439 09:45:07 INFO - mozversion platform_changeset: 05a3443d658d29e77cae1da1915ca51814534d73 09:45:07 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:45:07 INFO - mozversion platform_version: 52.0a2 09:45:07 INFO - using testdate: 1483551907 09:45:07 INFO - actual date: 1483551907 09:45:07 INFO - starting webserver on 'localhost:53487' 09:45:07 INFO - SUITE-START | Running 2 tests 09:45:07 INFO - TEST-START | dromaeo_css 09:45:07 INFO - operating with platform_type : mac_ 09:45:07 INFO - Initialising browser for dromaeo_css test... 09:45:07 INFO - TEST-INFO | started process 1911 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/tmpfQqfqJ/profile http://localhost:53487/getInfo.html) 09:45:10 INFO - PROCESS | 1911 | 2017-01-04 09:45:10.864 plugin-container[1913:17187] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3f, name = 'com.apple.tsm.portname' 09:45:10 INFO - PROCESS | 1911 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:45:10 INFO - PROCESS | 1911 | 2017-01-04 09:45:10.867 plugin-container[1913:17187] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x491f, name = 'com.apple.CFPasteboardClient' 09:45:10 INFO - PROCESS | 1911 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:45:10 INFO - PROCESS | 1911 | 2017-01-04 09:45:10.867 plugin-container[1913:17187] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:45:10 INFO - PROCESS | 1911 | __metrics Screen width/height:1600/1200 09:45:10 INFO - PROCESS | 1911 | colorDepth:24 09:45:10 INFO - PROCESS | 1911 | Browser inner width/height: 1024/689 09:45:10 INFO - PROCESS | 1911 | __metrics 09:45:11 ERROR - PROCESS | 1911 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 09:45:11 INFO - TEST-INFO | 1911: exit 0 09:45:11 INFO - Browser initialized. 09:45:11 INFO - Running cycle 1/1 for dromaeo_css test... 09:45:11 INFO - TEST-INFO | started process 1914 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/tmpfQqfqJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:45:14 INFO - PROCESS | 1914 | 2017-01-04 09:45:14.766 plugin-container[1915:17313] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 09:45:14 INFO - PROCESS | 1914 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:45:14 INFO - PROCESS | 1914 | 2017-01-04 09:45:14.767 plugin-container[1915:17313] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471b, name = 'com.apple.CFPasteboardClient' 09:45:14 INFO - PROCESS | 1914 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:45:14 INFO - PROCESS | 1914 | 2017-01-04 09:45:14.767 plugin-container[1915:17313] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:45:24 INFO - PROCESS | 1914 | RSS: Main: 229494784 09:45:24 INFO - PROCESS | 1914 | 09:48:27 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:53487/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53487/tests/dromaeo/cssquery-ext.html) 09:48:29 INFO - PROCESS | 1914 | RSS: Main: 234213376 09:48:29 INFO - PROCESS | 1914 | 09:51:31 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:53487/tests/dromaeo/cssquery-ext.html (next: http://localhost:53487/tests/dromaeo/cssquery-jquery.html) 09:51:32 INFO - PROCESS | 1914 | RSS: Main: 243576832 09:51:32 INFO - PROCESS | 1914 | 09:54:34 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:53487/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53487/tests/dromaeo/cssquery-mootools.html) 09:54:34 INFO - PROCESS | 1914 | RSS: Main: 247664640 09:54:34 INFO - PROCESS | 1914 | 09:57:36 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:53487/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53487/tests/dromaeo/cssquery-prototype.html) 09:57:37 INFO - PROCESS | 1914 | RSS: Main: 246960128 09:57:37 INFO - PROCESS | 1914 | 10:00:40 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:53487/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53487/tests/dromaeo/cssquery-yui.html) 10:00:40 INFO - PROCESS | 1914 | RSS: Main: 249901056 10:00:40 INFO - PROCESS | 1914 | 10:03:43 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:53487/tests/dromaeo/cssquery-yui.html (next: http://localhost:53487/tests/dromaeo/cssquery-dojo.html) 10:03:43 INFO - PROCESS | 1914 | RSS: Main: 251641856 10:03:43 INFO - PROCESS | 1914 | 10:03:43 INFO - PROCESS | 1914 | __start_tp_report 10:03:43 INFO - PROCESS | 1914 | _x_x_mozilla_page_load 10:03:43 INFO - PROCESS | 1914 | _x_x_mozilla_page_load_details 10:03:43 INFO - PROCESS | 1914 | |i|pagename|runs| 10:03:43 INFO - PROCESS | 1914 | |0;dojo.html;3230.9353812923737;3277.1315601365636;3293.045016945086;3303.3063056758065;3307.751918606102;4729.030548737509;5225.163973764198;5335.093034184189;5359.196120581914;5419.864503387417;5453.754581043852;5479.53423958964;5483.945160548394;5500.80747173848;5501.697406642633;3797.9430308545393;4991.051700176964;5075.162598171298;5131.7434128293435;5143.074246635604;5082.440931497551;5106.31064806252;5112.769925353349;5125.410577783547;5148.922766158511;5106.744662766847;5121.952433325538;5127.230915362688;5133.82031628893;5188.533032027117;4934.9026663668155;4989.251612258154;4991.700497970097;5050.368703912011;5105.157648987913;5018.498150184989;5040.621953353513;5048.268001139851;5050.84847454577;5063.215201643714;5394.32570928634;5407.324084489439;5531.3362396512275;5538.806141785019;5541.639793413416;5707.1724599933195;5715.599908006441;5769.365369809318;5810.709464526757;5884.794032208895;5893.469587737081;5917.289925208959;5921.792737254218;5927.1405646181065;5936.792212272593;5410.594205434608;5438.53772429344;5438.7280635968045;5471.917921231185;5476.397596264408;5234.26720259164;5261.500157485033;5780.306363236438;5989.520838332923;6118.4364438206485;5759.568032397587;5766.798162064308;5768.077107662753;5784.392638772868;5789.247397838254;4560.4071470708595;5662.688552129673;5920.674362909996;5964.31410387811;5996.16053752475;5841.853953651108;5976.581639285295;5997.580169388189;5997.7001149942325;6002.519798416116;6016.338202797689;6016.789412370546;6027.30685971119;6050.42520960508;6084.969575152096;3934.468846705658;4621.237495813153;4651.720896746138;4677.929831052537;4719.95280047202;3311.453610154346;4170.937435938464;4183.581641835792;4204.222218889515;4213.831446742096;4659.277811939155;5506.146547285177;5547.417521160301;5673.914891276634;5726.742296596663;5917.852053698606;5934.1692163097205;5941.049432090931;5945.583809133319;6058.030715085653;5680.091185410314;5682.090865461506;5715.914261286084;5722.2561067061015;5752.07966725322;5188.844334669966;5191.974040129775;5193.195054766517;5209.244659524347;5216.087184742655;6125.845966769368;6165.475934545434;6189.81430557084;6205.503559715122;6206.782762603287;6085.57400981927;6100.206973093469;6117.571769976059;6125.1424800527075;6140.785072522441;6195;6222.0355844844125;6246.6876656167915;6259.34276900937;6278.90119229124;5648.689322087324;5704.1443783432815;5714.771409143587;5834.533237340917;6003.159557662018;5916.940830591639;5920.585559010828;5953.1367951647635;5990.9700451497465;6053.697315134137;5876.44173803488;5880.94119058804;5887.911681324698;5907.527397808251;5948.940510594839;5840.123981402823;5855.473007429352;5863.442972917566;5880.294364676266;5904.852378690567;4675.532446755297;4714.952850471452;4857.8299759508245;5582.748776304995;5670.461306175906;5747.51146152565;5771.394003629558;5782.942170578241;5785.82642520725;5889.5582831286965;5231.869203269949;5291.470852914677;5713.342965558848;5737.799177028784;5747.08046712524 10:03:43 INFO - PROCESS | 1914 | |1;ext.html;468.5428317931234;495.7793781767111;497.6416979774562;522.1775703267355;543.2014938041078;36841.44737828931;39261.01847453808;39405.40891886624;39450.605493945055;39659.00852478689;39671.801640991755;40128.19743605126;40306.395404068964;40370.394444083366;40477.392839107364;9291.070892910722;9460.432374057544;9485.905140948606;9507.857382139273;9538.90461095387;9414.152726254635;9497.620095196184;9504.097110774464;9519.143277105059;9538.90461095387;9467;9472.289578281656;9483.620655173785;9491.050894910488;9502.952485237563;9445.716628501155;9502.004579038341;9505.904940950571;9517.095875891779;9523.095305945924;7991.320737737241;9382.85925711115;9400.717978460658;9425.151736343762;9451.810963780754;38480.422793658116;38707.612923870685;38878.41682374767;39064.414033789515;39112.413313800316;3172.413103575846;3309.619393769723;3319.71782398494;3381.543491628623;3674.9193965688874;29010.41979160426;29375.41249175026;30965.845170774002;31196.220094497592;31383.843080784678;2759.4619049285398;3174.6190357232076;3188.1073299476184;3220.5974253218346;3244.772865899388;493435;498180;500340;501580;510806;44365.77817110894;53844.461555384725;59234.70382648059;60387.39612603906;60757.392426076054;51867;62647.06029409562;63946;64187.03719444212;64246;16124.919375403048;16357.509274721539;16371.918140409221;16375.426860060079;16436.917815410845;10409.739756506147;12565.748685026248;12670.556530521388;12672.873271267354;12686.555970540987;15022.924885375503;17080.65838683244;17592.824071759118;17804.198811053535;17828.732569011474;3173.809571425722;3271.726150325501;3295.5880514935634;6319.273283572448;6401.050265550056;798.4211446701095;888.813349196676;891.2023738753779;1019.2864994503877;1034.984475232872;21649;28389.71610283912;29809.552856707167;30046.54930176049;30467.390652187274;793.730131755206;999.9450030248406;1012.2003617142523;1056.3872953686844;1107.5071593140974;15383.846161538466;15703.450379236672;15833.762493562606;16004.199790010452;16137.919310403373;577872;683404;683683;686684;688836;57795.13307300394;57845.13232301519;58478.53803654943;58511.12233316504;58563.70718146382;1322.08115359824;1337.9264140472173;1349.3590544491485;1358.0357945858354;1365.7162267468746;29077.563836542045;29832;29849.25376865596;29904.25239368946;30484.695153048186;42461.363079553215;51054;51080.48919510757;51815;52048.47951520588;631522;632599;636087;636331;638480.807595959;25723.614145787455;26322.86838565795;26768.33079173036;26786.19641410761;26793.732062679122;45853.54146458493;46321.76839115783;46619;46687.76656116698;47328.290075648205;37975;38328;39028.25455505704;39654.60345396624;39739.60260397359;38074;38737.99616671793;38903.80548097241;38997.805010974764;39485;37017;37096.44355334618;38055.80972095122;38333.23333533258;39632.40551391789;33141.502877456376;34608.65391346054;34649.480257796655;34795.73084182874;35063.948081557595;22035.889820550794;25227.621585676596;25760.227193184233;25914.093006744675;26069.087581934542 10:03:43 INFO - PROCESS | 1914 | |2;jquery.html;282.6989256441888;323.88502081760976;348.258210012673;361.2738395824393;367.84182801395406;18854;19084.41797703576;19590.706139407903;20076;20330.288439904605;19494.902525487352;19938.504612154073;20250.797492025074;20566.280180193695;20694.068766905486;5560.304961879765;5770.855728606776;5792.478676919077;5798.884022319551;5801.680907550083;5621.943780562204;7158.35574798268;7550.848983020336;7588.203238659944;7590.54456732595;7399.778006659782;7537.434692398065;7565;7582.090149182079;7654.349380302681;7485.5508669480005;7700.614969251543;7743.612819359009;7755.922440775605;7779.922200778033;7524.661390237453;7553.660085296175;7563.243675632448;7575.053118360205;7582.962085189594;24370.634440483405;26133;27423.04019359313;27641.723582764316;27678.44643107147;2223.0774228695072;2390.7728765767397;2437.3419176822335;2442.914497992562;2807.9719202807914;14603.342849571689;14919.701605967928;15214.315355809014;15557.144357060357;16149;1768.8054314025446;1795.9191836367395;1841.9539511512185;1847.7043673012254;1857.1828395505934;119691;121562.78437215603;124249;126973.36513317467;127849;21013.579728405348;21589.24437644675;21644.4588885276;21760.062391577252;21914.123435062418;34692.826535867156;34903.12742181466;35188.12029699278;35506.64493355085;35560;9150.40522366044;10612.946935265429;10675.46622668879;10683.679489615324;10709.839352409721;7230.566166030055;8834.911650883409;8835.249003834746;8961.731148065568;9040.276777857756;8000.319972802377;9609.231261499064;9654.27592930533;9821.508924553887;9830.361026533252;3826;3826.9858487500833;3839.212961342918;3937.822797974098;4017.738846974937;827.8509868223778;839.6599377252222;928.2713070239829;936.8454205056225;948.3029972969862;16876.071816050237;20651.69022464664;21328;21739.67390489144;22802.771972280065;762.4510352546158;888.1695614600369;896.0770406481334;933.4072863731444;975.5805003848413;10631.893681063246;10983.725406864733;10994.725131871768;10999;11050.447477626247;639404;640018;644795;659903;665360;133293;134975;138751;139865;140149;931.6739141300491;994.9104580587782;999.1008092716468;1000.9749756256006;1003.4180175498193;17463.214155362784;18275.81724182741;18588.88466692003;18636;18773.812261877752;49777.75111124566;52285.215721763445;53180.73409632927;54087.188692168864;54403.18395223995;74144.25855741589;76148;76631.2336876624;78620.21379786129;79201;6746.392824645805;6801.591904485746;6948.062011628365;7848.136704962564;7901.051873775184;10803.297785643908;12492.687682808002;12768.936155319165;13050.4127314273;13147.60557183286;10974.945125274322;11024.559017639205;11113.44432778374;11135.220534562503;11190.992810647082;10913.18151138652;10981.61564345244;11228.045616122394;11260.324380537195;11382.94308528452;10685.465726713788;11167.944160279147;11354.205205635857;11537.48081336325;11677.357745323749;7932.953682234991;8082.797930051795;8241.175882411711;8339.06962359948;8422.536760478233;9621.27840411958;10615;10670.62652807148;11046.779064418828;11057.72355691082 10:03:43 INFO - PROCESS | 1914 | |3;mootools.html;1237.5359240284893;1239.5785432952798;1530.8928375013754;1739.2086600596729;1747.6242607839313;7466.328030477256;7727.574983375912;8158.999999999993;8552.48685078895;8552.871706924394;7990.640421181046;8301.833963320745;8351.707690230844;8394.748157555268;8396.91603083969;2194.3526659635404;2205.7904499072642;2217.068831090946;2225.9888700556476;2229.0860747093693;2197.1321328075483;2237.7314722233355;2246.73039235292;2249.6737972993906;2252.8423010389197;2065.5249292662697;2074.7406574178226;2091.8640288381284;2092.9058192381303;2096.255829180634;1856.7214917762308;2201.801837834603;2205.305328821424;2211.1155537784853;2211.513467037249;2155.1594877997595;2211.845170838042;2212.8008479236787;2214;2215.490437199453;8369.3304535637;8378.874316885252;8422.747317580483;8476.194761497709;8494.193051660082;5079.415867175285;5212.635115541876;5254.4220135785035;5255.86860328491;5256.132738098209;3323.9501407478906;3411.829408529564;3515.5605549306338;3584.6773790358993;3592.982035089834;1045.9215558833118;1075.2043487819035;1091.317926296065;1096.9780604387947;1098.3245304137977;10411.375317480899;10782.191335649779;10810.945945270223;10854.185936054748;11056.668299951014;4345.956540434619;4418.0942906704695;4488.820447182076;4523.7285762854335;4542.091581683677;7393.704251829974;8538.829223415496;8674.392792504465;8692.869606955901;8696.956515217384;4650.302454631832;4864.902701946012;5123.795048198031;5136.614753893473;5165.483451654804;4243.384709217146;4480.977595112069;4509.413776209072;4819.012102519046;4826.276058591239;3286.5891763529557;3701.1117331840164;3767.0770661188185;3788.1287303920253;3793;1152.8731839497648;1153.411760002405;1166.5916929074756;1170.052257671289;1170.0990237517235;595.2767387624058;798.5248776977689;910.4764760262743;929.4980710416435;934.1779234273931;4930.1865192243595;5554.416786237387;5615.241942337732;5703.00634711762;5828.300603927556;430.5285712145196;440.94929083155205;446.176803796994;448.37004009366893;450.526946705964;3372.7976321420792;3626.437902125175;3814.5994670559194;3832;3873.283442563135;8146.918530814616;8552.144785521523;9815.773028371454;10291;10300.484975751182;9950.900490994998;10261.179105671528;10289.022542858416;10291.279610427348;10294.742631434125;6092.786752463642;7674;7819.882701759479;7858.1356050834365;7880.330171935335;5655.858603534944;5697.507464688676;5834.737436815439;5859.794907178229;5952.107183922446;9052.23056040244;9382.718518444457;9389.108034736963;9426.95286523563;9551.617935282511;7736.806579835549;9478.24174066087;9497.810043799223;9624.422534647943;9652;6595.274519802721;6778.288279730755;6970.37266645984;7072.787816365517;7258.782236532701;7704.152543220353;7902.565358905315;7948.602569871599;7953.244441778022;7959.9602001989615;7042;7182.497225194186;7200.747973820891;7213.422926165789;7307.123145222395;6998.921552963716;7103.822404439724;7119.75080872167;7240.456965727486;7274.054372931485;6617.735290588323;6791.04925310447;6794.014867844234;6807.523473356818;6812.182538095461;4869.415670119608;4877.951240483313;4906.582940450102;4915.483874193158;4926.064047830901;4799.328094066903;4833.3958255218095;4845.103655823684;4871.2206047032305;4959.776810043484 10:03:43 INFO - PROCESS | 1914 | |4;prototype.html;283.4953782267564;300.9759219262459;305.7951172714281;308.1433614551546;313.4452019924732;5049.343585333911;5163.3545806774155;5234.5027222413855;5271.499207575279;5282.603804714642;4187.811548480318;5028.371453568304;5138.383393992717;5179.922301165486;5200.895982080356;4001.3397789364794;4004.939925901114;4006.459128017724;4006.479157709494;4023.9738866588978;3440.690337869591;3860;3987.7407968482103;4007.3588225883864;4049.9595004050025;3286.457734473821;3803.543574771031;3978.264021156081;3985.481887354654;3989.6608788253034;3431.571053618285;3851.879977218448;3978.244133614604;4000.0999525225575;4003.8598649047435;2908.9272768180754;3042.1329920972416;3486.581610206766;3503.807290599016;3506.8597256109724;3980;4973.7513124344;5015.949840501585;5065.214891691794;5082.822101226439;4525.977370113162;4531.86404407865;4541.591256786905;4569.794359253841;4590;5460.726963651801;5462.629896083117;5468.090315424491;5480.808171713971;5494.395616482184;3381.932361352759;3586.3544561978833;3760.4241094850117;3810.618938106195;3822.4266360045935;4923.286123512071;5597.3003374578175;5615.578831587647;5629.690367029771;5705;3382.712469440076;3798.848046078126;3818.5417749870194;3830.1040984712745;3868.903277418087;3560.928781424357;3701.482392219231;3822.2737679840743;3969.6824254059607;3977.40045259041;4294.720843145185;4449.688521803443;4500.3249512573375;4504.842330518416;4540.455145382509;4779.402574678165;5504.394516603171;5513.531349835309;5519;5523.834284971457;1042.3433237060756;1108.41808050774;1126.7915435644431;1217.3608855351013;1217.7990631545695;873.7461742399677;886.9556522173995;895.6909866096187;897.717219075989;909.7043460875242;5049.57078648319;5054.1407960646775;5063.8480845574695;5076.416212135578;5078.720670363092;4199.887029937069;4414.300494309714;4776.044791041805;4902.019596080798;5422.5119739223655;4051.959480405158;4149.730267532601;4866.635002374836;5021.610412971229;5038.345015148007;2140.5504843982626;2446.1683027770287;2865.7564107051135;2868.928276793055;2881.3372924227547;4654.557817007379;5444.727763611803;5617.185508101303;5818.709064546756;5825.854353641193;4721.858344249678;5312.802885720238;5604.551635869202;5612.719364031782;5649.548036157097;5126.026904395516;5270.208129236002;5487.670739755627;5501.73615448023;5782;5230.959925629084;5458.9454105458435;5461.208124821799;5465.863353416196;5478.315210598676;5367.570594352364;5370.62405631602;5388.218708287355;5685.118806584986;5730.770769169187;6118.504831581277;6123.540734444845;6142.600730952474;6175.505959523317;6181.949068658429;3198.5202219667235;3440.3807314683595;3833.4824798651825;3856.2480316338047;3858.1897801462005;4141.8964525887095;5062.519060689228;5374.677519348852;5434.83695489136;5680.176374425602;4599.44806623194;4869.926951095807;5449.891002179855;5462.808801691922;5573.665580065209;5577.637453565505;5583.972080139573;5587.69267690281;5594.608377413542;5608.158776183605;5144.819931302386;5316.3354580677415;5468.562514998711;5481.890362192814;5497.39528651856;4580.450345958507;4767.570918617341;5422.972885135549;5551.417101204315;5575.972120139373;4268.679849011274;5054.444011158696;5452.509274165344;5457.972710136424;5519.834404967857 10:03:43 INFO - PROCESS | 1914 | |5;yui.html;628.5882746800845;692.4287462843153;708.585477495665;716.3982254905878;717.5013365710827;12707.04697147714;13570.59288221355;13682;13691.589252322434;13721.794173087412;10674.839877401846;12543.372831358442;12640.747185056316;12643.367831608428;12667.176633518815;634.7778277602848;653.2357142143703;785.9607019649023;794.5907857453416;811.2293321344746;799.604195923016;804.3082948664144;811.9106898241218;823.2056065896409;824.5547404401616;868.6178081644088;869.7738587967142;869.8129902071054;870.4646642314995;872.5811610426999;830.0620299062082;859.5530324231427;865.0354854337431;866.1165611076698;868.1665601023026;634.0204384226381;644.136856612142;644.5005121031238;645.2676212498801;645.2740666749906;12563.56027539041;12946.611601651966;13017.544385946541;13019.349032548334;13055.934720326433;11943.641690749291;12060.276383417024;12132.393380330948;12141.332226727527;12142.392880355947;10143.13783328418;10371.340585506283;10413.635522756667;10485.475726213659;10509.894901050968;3423.9315213695586;3427.8114703691285;3429.3141371725505;3614.8915532534065;3668.413053911388;86367.56816215941;88060.55969720174;88457.11542884553;89003;89535.65696514492;9386.483743394043;9566.856497152548;9667.323287369958;9687.806243875082;9705.417674939526;18827.246910123715;19060.71408928867;19079.80920190808;19101.90449047746;19349;6788.8302792429595;6897.89653155193;7015.088038555057;7045.89431158533;7111.395531379787;3938.389549619756;3994.320965435883;4028.2144981728866;4072.2669919414197;4131.35963925592;3134.2791158033324;3198.328351046306;3227.289996200832;3228.257500774788;3232.224266176148;543.6873797566415;615.8183335915916;617.5121653893464;619.454879705856;678.4335080207983;540.4082529630049;551.0879494436714;648.9902651460231;667.0760996020449;668.9665516724242;8075.475094118863;8158;8189.181081891763;8313.916860831436;8447.746567602982;501.51102674892417;579.5421616922669;597.8909123575792;602.6956387024525;693.743314973463;3010.9397812043635;3611.880317101707;4008.7193896427552;4042.959570404317;4060.167665628539;95626.56560151603;97202;98123.50938245263;98809;99245;78410.60794696104;79731;84504.57747711345;87505.68741468883;90390;67732;75964.86052709214;77995;79102;79312.81030784543;8556.358273129665;8740.868886966573;9003.099690030944;9032.187103160748;9043.457392556467;40748.777536673944;40959.59040409558;45845.54154458413;50672.49327506825;52985.735071324394;71515.64242178756;72689.6365518169;74827;76183;76736.61631691805;11009.559617615205;11267;11347.432628368713;11508.88491115078;11615;12489.937550312554;13123.475060997453;13214.07501474926;13258;13272.933635331761;11500.769984600094;11624.302541847515;11693.941530292293;11844.052475802087;12105.273683579013;10612.310199836986;10628;11179.217454778087;11690.941545292219;11726.65466906605;11704.297742135159;11785.277146354121;11895.10786691019;12067.637970860887;12385.814212786996;9744.851213002476;9911.306208565331;9938.801223975624;10111.292209545261;10208.591656333663;10039.849402259119;10185.74535636615;10255.897441025792;10325.070743633109;10370.896291036994 10:03:43 INFO - PROCESS | 1914 | __end_tp_report 10:03:43 INFO - PROCESS | 1914 | __start_cc_report 10:03:43 INFO - PROCESS | 1914 | _x_x_mozilla_cycle_collect,381 10:03:43 INFO - PROCESS | 1914 | __end_cc_report 10:03:43 INFO - PROCESS | 1914 | __startTimestamp1483553023734__endTimestamp 10:03:43 INFO - PROCESS | 1914 | 10:03:43 INFO - PROCESS | 1914 | ------- Summary: start ------- 10:03:43 INFO - PROCESS | 1914 | Number of tests: 6 10:03:43 INFO - PROCESS | 1914 | 10:03:43 INFO - PROCESS | 1914 | [#0] dojo.html Cycles:165 Average:5458.73 Median:5682.09 stddev:656.91 (11.6%) stddev-sans-first:635.25 10:03:43 INFO - PROCESS | 1914 | Values: 3230.9 3277.1 3293.0 3303.3 3307.8 4729.0 5225.2 5335.1 5359.2 5419.9 5453.8 5479.5 5483.9 5500.8 5501.7 3797.9 4991.1 5075.2 5131.7 5143.1 5082.4 5106.3 5112.8 5125.4 5148.9 5106.7 5122.0 5127.2 5133.8 5188.5 4934.9 4989.3 4991.7 5050.4 5105.2 5018.5 5040.6 5048.3 5050.8 5063.2 5394.3 5407.3 5531.3 5538.8 5541.6 5707.2 5715.6 5769.4 5810.7 5884.8 5893.5 5917.3 5921.8 5927.1 5936.8 5410.6 5438.5 5438.7 5471.9 5476.4 5234.3 5261.5 5780.3 5989.5 6118.4 5759.6 5766.8 5768.1 5784.4 5789.2 4560.4 5662.7 5920.7 5964.3 5996.2 5841.9 5976.6 5997.6 5997.7 6002.5 6016.3 6016.8 6027.3 6050.4 6085.0 3934.5 4621.2 4651.7 4677.9 4720.0 3311.5 4170.9 4183.6 4204.2 4213.8 4659.3 5506.1 5547.4 5673.9 5726.7 5917.9 5934.2 5941.0 5945.6 6058.0 5680.1 5682.1 5715.9 5722.3 5752.1 5188.8 5192.0 5193.2 5209.2 5216.1 6125.8 6165.5 6189.8 6205.5 6206.8 6085.6 6100.2 6117.6 6125.1 6140.8 6195.0 6222.0 6246.7 6259.3 6278.9 5648.7 5704.1 5714.8 5834.5 6003.2 5916.9 5920.6 5953.1 5991.0 6053.7 5876.4 5880.9 5887.9 5907.5 5948.9 5840.1 5855.5 5863.4 5880.3 5904.9 4675.5 4715.0 4857.8 5582.7 5670.5 5747.5 5771.4 5782.9 5785.8 5889.6 5231.9 5291.5 5713.3 5737.8 5747.1 10:03:43 INFO - PROCESS | 1914 | 10:03:43 INFO - PROCESS | 1914 | [#1] ext.html Cycles:180 Average:72299.54 Median:39333.21 stddev:161998.04 (411.9%) stddev-sans-first:162362.71 10:03:43 INFO - PROCESS | 1914 | Values: 468.5 495.8 497.6 522.2 543.2 36841.4 39261.0 39405.4 39450.6 39659.0 39671.8 40128.2 40306.4 40370.4 40477.4 9291.1 9460.4 9485.9 9507.9 9538.9 9414.2 9497.6 9504.1 9519.1 9538.9 9467.0 9472.3 9483.6 9491.1 9503.0 9445.7 9502.0 9505.9 9517.1 9523.1 7991.3 9382.9 9400.7 9425.2 9451.8 38480.4 38707.6 38878.4 39064.4 39112.4 3172.4 3309.6 3319.7 3381.5 3674.9 29010.4 29375.4 30965.8 31196.2 31383.8 2759.5 3174.6 3188.1 3220.6 3244.8 493435.0 498180.0 500340.0 501580.0 510806.0 44365.8 53844.5 59234.7 60387.4 60757.4 51867.0 62647.1 63946.0 64187.0 64246.0 16124.9 16357.5 16371.9 16375.4 16436.9 10409.7 12565.7 12670.6 12672.9 12686.6 15022.9 17080.7 17592.8 17804.2 17828.7 3173.8 3271.7 3295.6 6319.3 6401.1 798.4 888.8 891.2 1019.3 1035.0 21649.0 28389.7 29809.6 30046.5 30467.4 793.7 999.9 1012.2 1056.4 1107.5 15383.8 15703.5 15833.8 16004.2 16137.9 577872.0 683404.0 683683.0 686684.0 688836.0 57795.1 57845.1 58478.5 58511.1 58563.7 1322.1 1337.9 1349.4 1358.0 1365.7 29077.6 29832.0 29849.3 29904.3 30484.7 42461.4 51054.0 51080.5 51815.0 52048.5 631522.0 632599.0 636087.0 636331.0 638480.8 25723.6 26322.9 26768.3 26786.2 26793.7 45853.5 46321.8 46619.0 46687.8 47328.3 37975.0 38328.0 39028.3 39654.6 39739.6 38074.0 38738.0 38903.8 38997.8 39485.0 37017.0 37096.4 38055.8 38333.2 39632.4 33141.5 34608.7 34649.5 34795.7 35063.9 22035.9 25227.6 25760.2 25914.1 26069.1 10:03:43 INFO - PROCESS | 1914 | 10:03:43 INFO - PROCESS | 1914 | [#2] jquery.html Cycles:180 Average:38244.01 Median:303.29 stddev:108116.47 (35647.7%) stddev-sans-first:108382.19 10:03:43 INFO - PROCESS | 1914 | Values: 282.7 323.9 348.3 361.3 367.8 18854.0 19084.4 19590.7 20076.0 20330.3 19494.9 19938.5 20250.8 20566.3 20694.1 5560.3 5770.9 5792.5 5798.9 5801.7 5621.9 7158.4 7550.8 7588.2 7590.5 7399.8 7537.4 7565.0 7582.1 7654.3 7485.6 7700.6 7743.6 7755.9 7779.9 7524.7 7553.7 7563.2 7575.1 7583.0 24370.6 26133.0 27423.0 27641.7 27678.4 2223.1 2390.8 2437.3 2442.9 2808.0 14603.3 14919.7 15214.3 15557.1 16149.0 1768.8 1795.9 1842.0 1847.7 1857.2 119691.0 121562.8 124249.0 126973.4 127849.0 21013.6 21589.2 21644.5 21760.1 21914.1 34692.8 34903.1 35188.1 35506.6 35560.0 9150.4 10612.9 10675.5 10683.7 10709.8 7230.6 8834.9 8835.2 8961.7 9040.3 8000.3 9609.2 9654.3 9821.5 9830.4 3826.0 3827.0 3839.2 3937.8 4017.7 827.9 839.7 928.3 936.8 948.3 16876.1 20651.7 21328.0 21739.7 22802.8 762.5 888.2 896.1 933.4 975.6 10631.9 10983.7 10994.7 10999.0 11050.4 639404.0 640018.0 644795.0 659903.0 665360.0 133293.0 134975.0 138751.0 139865.0 140149.0 931.7 994.9 999.1 1001.0 1003.4 17463.2 18275.8 18588.9 18636.0 18773.8 49777.8 52285.2 53180.7 54087.2 54403.2 74144.3 76148.0 76631.2 78620.2 79201.0 6746.4 6801.6 6948.1 7848.1 7901.1 10803.3 12492.7 12768.9 13050.4 13147.6 10974.9 11024.6 11113.4 11135.2 11191.0 10913.2 10981.6 11228.0 11260.3 11382.9 10685.5 11167.9 11354.2 11537.5 11677.4 7933.0 8082.8 8241.2 8339.1 8422.5 9621.3 10615.0 10670.6 11046.8 11057.7 10:03:43 INFO - PROCESS | 1914 | 10:03:43 INFO - PROCESS | 1914 | [#3] mootools.html Cycles:180 Average:5351.65 Median:4920.77 stddev:2976.76 (60.5%) stddev-sans-first:2969.05 10:03:43 INFO - PROCESS | 1914 | Values: 1237.5 1239.6 1530.9 1739.2 1747.6 7466.3 7727.6 8159.0 8552.5 8552.9 7990.6 8301.8 8351.7 8394.7 8396.9 2194.4 2205.8 2217.1 2226.0 2229.1 2197.1 2237.7 2246.7 2249.7 2252.8 2065.5 2074.7 2091.9 2092.9 2096.3 1856.7 2201.8 2205.3 2211.1 2211.5 2155.2 2211.8 2212.8 2214.0 2215.5 8369.3 8378.9 8422.7 8476.2 8494.2 5079.4 5212.6 5254.4 5255.9 5256.1 3324.0 3411.8 3515.6 3584.7 3593.0 1045.9 1075.2 1091.3 1097.0 1098.3 10411.4 10782.2 10810.9 10854.2 11056.7 4346.0 4418.1 4488.8 4523.7 4542.1 7393.7 8538.8 8674.4 8692.9 8697.0 4650.3 4864.9 5123.8 5136.6 5165.5 4243.4 4481.0 4509.4 4819.0 4826.3 3286.6 3701.1 3767.1 3788.1 3793.0 1152.9 1153.4 1166.6 1170.1 1170.1 595.3 798.5 910.5 929.5 934.2 4930.2 5554.4 5615.2 5703.0 5828.3 430.5 440.9 446.2 448.4 450.5 3372.8 3626.4 3814.6 3832.0 3873.3 8146.9 8552.1 9815.8 10291.0 10300.5 9950.9 10261.2 10289.0 10291.3 10294.7 6092.8 7674.0 7819.9 7858.1 7880.3 5655.9 5697.5 5834.7 5859.8 5952.1 9052.2 9382.7 9389.1 9427.0 9551.6 7736.8 9478.2 9497.8 9624.4 9652.0 6595.3 6778.3 6970.4 7072.8 7258.8 7704.2 7902.6 7948.6 7953.2 7960.0 7042.0 7182.5 7200.7 7213.4 7307.1 6998.9 7103.8 7119.8 7240.5 7274.1 6617.7 6791.0 6794.0 6807.5 6812.2 4869.4 4878.0 4906.6 4915.5 4926.1 4799.3 4833.4 4845.1 4871.2 4959.8 10:03:43 INFO - PROCESS | 1914 | 10:03:43 INFO - PROCESS | 1914 | [#4] prototype.html Cycles:180 Average:4400.20 Median:5018.78 stddev:1377.21 (27.4%) stddev-sans-first:1345.97 10:03:43 INFO - PROCESS | 1914 | Values: 283.5 301.0 305.8 308.1 313.4 5049.3 5163.4 5234.5 5271.5 5282.6 4187.8 5028.4 5138.4 5179.9 5200.9 4001.3 4004.9 4006.5 4006.5 4024.0 3440.7 3860.0 3987.7 4007.4 4050.0 3286.5 3803.5 3978.3 3985.5 3989.7 3431.6 3851.9 3978.2 4000.1 4003.9 2908.9 3042.1 3486.6 3503.8 3506.9 3980.0 4973.8 5015.9 5065.2 5082.8 4526.0 4531.9 4541.6 4569.8 4590.0 5460.7 5462.6 5468.1 5480.8 5494.4 3381.9 3586.4 3760.4 3810.6 3822.4 4923.3 5597.3 5615.6 5629.7 5705.0 3382.7 3798.8 3818.5 3830.1 3868.9 3560.9 3701.5 3822.3 3969.7 3977.4 4294.7 4449.7 4500.3 4504.8 4540.5 4779.4 5504.4 5513.5 5519.0 5523.8 1042.3 1108.4 1126.8 1217.4 1217.8 873.7 887.0 895.7 897.7 909.7 5049.6 5054.1 5063.8 5076.4 5078.7 4199.9 4414.3 4776.0 4902.0 5422.5 4052.0 4149.7 4866.6 5021.6 5038.3 2140.6 2446.2 2865.8 2868.9 2881.3 4654.6 5444.7 5617.2 5818.7 5825.9 4721.9 5312.8 5604.6 5612.7 5649.5 5126.0 5270.2 5487.7 5501.7 5782.0 5231.0 5458.9 5461.2 5465.9 5478.3 5367.6 5370.6 5388.2 5685.1 5730.8 6118.5 6123.5 6142.6 6175.5 6181.9 3198.5 3440.4 3833.5 3856.2 3858.2 4141.9 5062.5 5374.7 5434.8 5680.2 4599.4 4869.9 5449.9 5462.8 5573.7 5577.6 5584.0 5587.7 5594.6 5608.2 5144.8 5316.3 5468.6 5481.9 5497.4 4580.5 4767.6 5423.0 5551.4 5576.0 4268.7 5054.4 5452.5 5458.0 5519.8 10:03:43 INFO - PROCESS | 1914 | 10:03:43 INFO - PROCESS | 1914 | [#5] yui.html Cycles:180 Average:18921.65 Median:565.32 stddev:27685.25 (4897.3%) stddev-sans-first:27728.84 10:03:43 INFO - PROCESS | 1914 | Values: 628.6 692.4 708.6 716.4 717.5 12707.0 13570.6 13682.0 13691.6 13721.8 10674.8 12543.4 12640.7 12643.4 12667.2 634.8 653.2 786.0 794.6 811.2 799.6 804.3 811.9 823.2 824.6 868.6 869.8 869.8 870.5 872.6 830.1 859.6 865.0 866.1 868.2 634.0 644.1 644.5 645.3 645.3 12563.6 12946.6 13017.5 13019.3 13055.9 11943.6 12060.3 12132.4 12141.3 12142.4 10143.1 10371.3 10413.6 10485.5 10509.9 3423.9 3427.8 3429.3 3614.9 3668.4 86367.6 88060.6 88457.1 89003.0 89535.7 9386.5 9566.9 9667.3 9687.8 9705.4 18827.2 19060.7 19079.8 19101.9 19349.0 6788.8 6897.9 7015.1 7045.9 7111.4 3938.4 3994.3 4028.2 4072.3 4131.4 3134.3 3198.3 3227.3 3228.3 3232.2 543.7 615.8 617.5 619.5 678.4 540.4 551.1 649.0 667.1 669.0 8075.5 8158.0 8189.2 8313.9 8447.7 501.5 579.5 597.9 602.7 693.7 3010.9 3611.9 4008.7 4043.0 4060.2 95626.6 97202.0 98123.5 98809.0 99245.0 78410.6 79731.0 84504.6 87505.7 90390.0 67732.0 75964.9 77995.0 79102.0 79312.8 8556.4 8740.9 9003.1 9032.2 9043.5 40748.8 40959.6 45845.5 50672.5 52985.7 71515.6 72689.6 74827.0 76183.0 76736.6 11009.6 11267.0 11347.4 11508.9 11615.0 12489.9 13123.5 13214.1 13258.0 13272.9 11500.8 11624.3 11693.9 11844.1 12105.3 10612.3 10628.0 11179.2 11690.9 11726.7 11704.3 11785.3 11895.1 12067.6 12385.8 9744.9 9911.3 9938.8 10111.3 10208.6 10039.8 10185.7 10255.9 10325.1 10370.9 10:03:43 INFO - PROCESS | 1914 | -------- Summary: end -------- 10:03:43 INFO - PROCESS | 1914 | 10:03:44 INFO - TEST-INFO | 1914: exit 0 10:03:44 INFO - TEST-OK | dromaeo_css | took 1116647ms 10:03:44 INFO - TEST-START | kraken 10:03:44 INFO - operating with platform_type : mac_ 10:03:44 INFO - Initialising browser for kraken test... 10:03:44 INFO - TEST-INFO | started process 1920 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/tmpLKJ7EB/profile http://localhost:53487/getInfo.html) 10:03:47 INFO - PROCESS | 1920 | 2017-01-04 10:03:47.344 plugin-container[1921:19494] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x853f, name = 'com.apple.tsm.portname' 10:03:47 INFO - PROCESS | 1920 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:03:47 INFO - PROCESS | 1920 | 2017-01-04 10:03:47.346 plugin-container[1921:19494] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x890b, name = 'com.apple.CFPasteboardClient' 10:03:47 INFO - PROCESS | 1920 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:03:47 INFO - PROCESS | 1920 | 2017-01-04 10:03:47.346 plugin-container[1921:19494] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:03:47 INFO - PROCESS | 1920 | __metrics Screen width/height:1600/1200 10:03:47 INFO - PROCESS | 1920 | colorDepth:24 10:03:47 INFO - PROCESS | 1920 | Browser inner width/height: 1024/689 10:03:47 INFO - PROCESS | 1920 | __metrics 10:03:47 ERROR - PROCESS | 1920 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 10:03:48 INFO - TEST-INFO | 1920: exit 0 10:03:48 INFO - Browser initialized. 10:03:48 INFO - Running cycle 1/1 for kraken test... 10:03:48 INFO - TEST-INFO | started process 1922 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/tmpLKJ7EB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:03:51 INFO - PROCESS | 1922 | 2017-01-04 10:03:51.222 plugin-container[1923:19615] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x833f, name = 'com.apple.tsm.portname' 10:03:51 INFO - PROCESS | 1922 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:03:51 INFO - PROCESS | 1922 | 2017-01-04 10:03:51.223 plugin-container[1923:19615] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 10:03:51 INFO - PROCESS | 1922 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:03:51 INFO - PROCESS | 1922 | 2017-01-04 10:03:51.223 plugin-container[1923:19615] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:04:00 INFO - PROCESS | 1922 | RSS: Main: 229208064 10:04:00 INFO - PROCESS | 1922 | 10:04:03 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:53487/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53487/tests/kraken/driver.html?testName=audio-beat-detection) 10:04:04 INFO - PROCESS | 1922 | RSS: Main: 241147904 10:04:04 INFO - PROCESS | 1922 | 10:04:07 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:53487/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53487/tests/kraken/driver.html?testName=audio-dft) 10:04:08 INFO - PROCESS | 1922 | RSS: Main: 248852480 10:04:08 INFO - PROCESS | 1922 | 10:04:11 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:53487/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53487/tests/kraken/driver.html?testName=audio-fft) 10:04:12 INFO - PROCESS | 1922 | RSS: Main: 250798080 10:04:12 INFO - PROCESS | 1922 | 10:04:14 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:53487/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53487/tests/kraken/driver.html?testName=audio-oscillator) 10:04:14 INFO - PROCESS | 1922 | RSS: Main: 253321216 10:04:14 INFO - PROCESS | 1922 | 10:04:17 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:53487/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53487/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:04:17 INFO - PROCESS | 1922 | RSS: Main: 253616128 10:04:17 INFO - PROCESS | 1922 | 10:04:20 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:53487/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53487/tests/kraken/driver.html?testName=imaging-darkroom) 10:04:21 INFO - PROCESS | 1922 | RSS: Main: 258162688 10:04:21 INFO - PROCESS | 1922 | 10:04:24 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:53487/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53487/tests/kraken/driver.html?testName=imaging-desaturate) 10:04:24 INFO - PROCESS | 1922 | RSS: Main: 257269760 10:04:24 INFO - PROCESS | 1922 | 10:04:27 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:53487/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53487/tests/kraken/driver.html?testName=json-parse-financial) 10:04:28 INFO - PROCESS | 1922 | RSS: Main: 257675264 10:04:28 INFO - PROCESS | 1922 | 10:04:30 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:53487/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53487/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:04:30 INFO - PROCESS | 1922 | RSS: Main: 256565248 10:04:30 INFO - PROCESS | 1922 | 10:04:32 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:53487/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53487/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:04:33 INFO - PROCESS | 1922 | RSS: Main: 256593920 10:04:33 INFO - PROCESS | 1922 | 10:04:35 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:53487/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53487/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:04:36 INFO - PROCESS | 1922 | RSS: Main: 256622592 10:04:36 INFO - PROCESS | 1922 | 10:04:39 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:53487/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53487/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:04:40 INFO - PROCESS | 1922 | RSS: Main: 256933888 10:04:40 INFO - PROCESS | 1922 | 10:04:42 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:53487/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53487/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:04:43 INFO - PROCESS | 1922 | RSS: Main: 256806912 10:04:43 INFO - PROCESS | 1922 | 10:04:45 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:53487/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53487/tests/kraken/driver.html?testName=ai-astar) 10:04:45 INFO - PROCESS | 1922 | RSS: Main: 258600960 10:04:45 INFO - PROCESS | 1922 | 10:04:45 INFO - PROCESS | 1922 | __start_tp_report 10:04:45 INFO - PROCESS | 1922 | _x_x_mozilla_page_load 10:04:45 INFO - PROCESS | 1922 | _x_x_mozilla_page_load_details 10:04:45 INFO - PROCESS | 1922 | |i|pagename|runs| 10:04:45 INFO - PROCESS | 1922 | |0;ai-astar;104;170;176;118;117;119;119;118;118;117 10:04:45 INFO - PROCESS | 1922 | |1;audio-beat-detection;174;140;127;155;128;113;119;143;137;116 10:04:45 INFO - PROCESS | 1922 | |2;audio-dft;268;256;199;191;201;217;211;254;237;225 10:04:45 INFO - PROCESS | 1922 | |3;audio-fft;82;58;60;82;60;60;60;59;89;84 10:04:45 INFO - PROCESS | 1922 | |4;audio-oscillator;84;71;89;74;79;101;121;99;70;88 10:04:45 INFO - PROCESS | 1922 | |5;imaging-gaussian-blur;89;87;85;102;80;130;127;89;78;129 10:04:45 INFO - PROCESS | 1922 | |6;imaging-darkroom;171;127;99;100;109;97;99;99;100;115 10:04:45 INFO - PROCESS | 1922 | |7;imaging-desaturate;95;122;137;102;80;134;149;129;91;94 10:04:45 INFO - PROCESS | 1922 | |8;json-parse-financial;57;58;57;58;59;61;60;60;69;86 10:04:45 INFO - PROCESS | 1922 | |9;json-stringify-tinderbox;68;77;71;78;77;73;72;95;105;89 10:04:45 INFO - PROCESS | 1922 | |10;stanford-crypto-aes;86;84;84;90;87;82;88;90;89;88 10:04:45 INFO - PROCESS | 1922 | |11;stanford-crypto-ccm;142;145;157;152;153;156;161;185;229;160 10:04:45 INFO - PROCESS | 1922 | |12;stanford-crypto-pbkdf2;168;172;167;165;169;167;168;170;166;168 10:04:45 INFO - PROCESS | 1922 | |13;stanford-crypto-sha256-iterative;67;69;67;70;80;87;90;82;89;90 10:04:45 INFO - PROCESS | 1922 | __end_tp_report 10:04:45 INFO - PROCESS | 1922 | __start_cc_report 10:04:45 INFO - PROCESS | 1922 | _x_x_mozilla_cycle_collect,969 10:04:45 INFO - PROCESS | 1922 | __end_cc_report 10:04:45 INFO - PROCESS | 1922 | __startTimestamp1483553085538__endTimestamp 10:04:45 INFO - PROCESS | 1922 | 10:04:45 INFO - PROCESS | 1922 | ------- Summary: start ------- 10:04:45 INFO - PROCESS | 1922 | Number of tests: 14 10:04:45 INFO - PROCESS | 1922 | 10:04:45 INFO - PROCESS | 1922 | [#0] ai-astar Cycles:10 Average:127.60 Median:118.50 stddev:24.37 (20.6%) stddev-sans-first:24.31 10:04:45 INFO - PROCESS | 1922 | Values: 104.0 170.0 176.0 118.0 117.0 119.0 119.0 118.0 118.0 117.0 10:04:45 INFO - PROCESS | 1922 | 10:04:45 INFO - PROCESS | 1922 | [#1] audio-beat-detection Cycles:10 Average:135.20 Median:138.50 stddev:18.94 (13.7%) stddev-sans-first:13.94 10:04:45 INFO - PROCESS | 1922 | Values: 174.0 140.0 127.0 155.0 128.0 113.0 119.0 143.0 137.0 116.0 10:04:45 INFO - PROCESS | 1922 | 10:04:45 INFO - PROCESS | 1922 | [#2] audio-dft Cycles:10 Average:225.90 Median:231.00 stddev:26.78 (11.6%) stddev-sans-first:23.68 10:04:45 INFO - PROCESS | 1922 | Values: 268.0 256.0 199.0 191.0 201.0 217.0 211.0 254.0 237.0 225.0 10:04:45 INFO - PROCESS | 1922 | 10:04:45 INFO - PROCESS | 1922 | [#3] audio-fft Cycles:10 Average:69.40 Median:71.00 stddev:12.94 (18.2%) stddev-sans-first:12.89 10:04:45 INFO - PROCESS | 1922 | Values: 82.0 58.0 60.0 82.0 60.0 60.0 60.0 59.0 89.0 84.0 10:04:45 INFO - PROCESS | 1922 | 10:04:45 INFO - PROCESS | 1922 | [#4] audio-oscillator Cycles:10 Average:87.60 Median:81.50 stddev:15.93 (19.5%) stddev-sans-first:16.84 10:04:45 INFO - PROCESS | 1922 | Values: 84.0 71.0 89.0 74.0 79.0 101.0 121.0 99.0 70.0 88.0 10:04:45 INFO - PROCESS | 1922 | 10:04:45 INFO - PROCESS | 1922 | [#5] imaging-gaussian-blur Cycles:10 Average:99.60 Median:82.50 stddev:21.06 (25.5%) stddev-sans-first:21.99 10:04:45 INFO - PROCESS | 1922 | Values: 89.0 87.0 85.0 102.0 80.0 130.0 127.0 89.0 78.0 129.0 10:04:45 INFO - PROCESS | 1922 | 10:04:45 INFO - PROCESS | 1922 | [#6] imaging-darkroom Cycles:10 Average:111.60 Median:134.00 stddev:22.96 (17.1%) stddev-sans-first:10.14 10:04:45 INFO - PROCESS | 1922 | Values: 171.0 127.0 99.0 100.0 109.0 97.0 99.0 99.0 100.0 115.0 10:04:45 INFO - PROCESS | 1922 | 10:04:45 INFO - PROCESS | 1922 | [#7] imaging-desaturate Cycles:10 Average:113.30 Median:114.50 stddev:23.64 (20.6%) stddev-sans-first:24.12 10:04:45 INFO - PROCESS | 1922 | Values: 95.0 122.0 137.0 102.0 80.0 134.0 149.0 129.0 91.0 94.0 10:04:45 INFO - PROCESS | 1922 | 10:04:45 INFO - PROCESS | 1922 | [#8] json-parse-financial Cycles:10 Average:62.50 Median:60.00 stddev:8.96 (14.9%) stddev-sans-first:9.28 10:04:45 INFO - PROCESS | 1922 | Values: 57.0 58.0 57.0 58.0 59.0 61.0 60.0 60.0 69.0 86.0 10:04:45 INFO - PROCESS | 1922 | 10:04:45 INFO - PROCESS | 1922 | [#9] json-stringify-tinderbox Cycles:10 Average:80.50 Median:77.00 stddev:11.97 (15.5%) stddev-sans-first:11.81 10:04:45 INFO - PROCESS | 1922 | Values: 68.0 77.0 71.0 78.0 77.0 73.0 72.0 95.0 105.0 89.0 10:04:45 INFO - PROCESS | 1922 | 10:04:45 INFO - PROCESS | 1922 | [#10] stanford-crypto-aes Cycles:10 Average:86.80 Median:88.00 stddev:2.74 (3.1%) stddev-sans-first:2.89 10:04:45 INFO - PROCESS | 1922 | Values: 86.0 84.0 84.0 90.0 87.0 82.0 88.0 90.0 89.0 88.0 10:04:45 INFO - PROCESS | 1922 | 10:04:45 INFO - PROCESS | 1922 | [#11] stanford-crypto-ccm Cycles:10 Average:164.00 Median:158.50 stddev:25.63 (16.2%) stddev-sans-first:25.92 10:04:45 INFO - PROCESS | 1922 | Values: 142.0 145.0 157.0 152.0 153.0 156.0 161.0 185.0 229.0 160.0 10:04:45 INFO - PROCESS | 1922 | 10:04:45 INFO - PROCESS | 1922 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.00 Median:168.00 stddev:2.00 (1.2%) stddev-sans-first:2.12 10:04:45 INFO - PROCESS | 1922 | Values: 168.0 172.0 167.0 165.0 169.0 167.0 168.0 170.0 166.0 168.0 10:04:45 INFO - PROCESS | 1922 | 10:04:45 INFO - PROCESS | 1922 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.10 Median:84.50 stddev:9.92 (11.7%) stddev-sans-first:9.50 10:04:45 INFO - PROCESS | 1922 | Values: 67.0 69.0 67.0 70.0 80.0 87.0 90.0 82.0 89.0 90.0 10:04:45 INFO - PROCESS | 1922 | -------- Summary: end -------- 10:04:45 INFO - PROCESS | 1922 | 10:04:46 INFO - TEST-INFO | 1922: exit 0 10:04:46 INFO - TEST-OK | kraken | took 62207ms 10:04:46 INFO - SUITE-END | took 1178s 10:04:46 INFO - Completed test suite (00:19:38) 10:04:46 INFO - javascript benchmark 10:04:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7115.66441538024, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3230.9353812923737, 3277.1315601365636, 3293.045016945086, 3303.3063056758065, 3307.751918606102, 4729.030548737509, 5225.163973764198, 5335.093034184189, 5359.196120581914, 5419.864503387417, 5453.754581043852, 5479.53423958964, 5483.945160548394, 5500.80747173848, 5501.697406642633, 3797.9430308545393, 4991.051700176964, 5075.162598171298, 5131.7434128293435, 5143.074246635604, 5082.440931497551, 5106.31064806252, 5112.769925353349, 5125.410577783547, 5148.922766158511, 5106.744662766847, 5121.952433325538, 5127.230915362688, 5133.82031628893, 5188.533032027117, 4934.9026663668155, 4989.251612258154, 4991.700497970097, 5050.368703912011, 5105.157648987913, 5018.498150184989, 5040.621953353513, 5048.268001139851, 5050.84847454577, 5063.215201643714, 5394.32570928634, 5407.324084489439, 5531.3362396512275, 5538.806141785019, 5541.639793413416, 5707.1724599933195, 5715.599908006441, 5769.365369809318, 5810.709464526757, 5884.794032208895, 5893.469587737081, 5917.289925208959, 5921.792737254218, 5927.1405646181065, 5936.792212272593, 5410.594205434608, 5438.53772429344, 5438.7280635968045, 5471.917921231185, 5476.397596264408, 5234.26720259164, 5261.500157485033, 5780.306363236438, 5989.520838332923, 6118.4364438206485, 5759.568032397587, 5766.798162064308, 5768.077107662753, 5784.392638772868, 5789.247397838254, 4560.4071470708595, 5662.688552129673, 5920.674362909996, 5964.31410387811, 5996.16053752475, 5841.853953651108, 5976.581639285295, 5997.580169388189, 5997.7001149942325, 6002.519798416116, 6016.338202797689, 6016.789412370546, 6027.30685971119, 6050.42520960508, 6084.969575152096, 3934.468846705658, 4621.237495813153, 4651.720896746138, 4677.929831052537, 4719.95280047202, 3311.453610154346, 4170.937435938464, 4183.581641835792, 4204.222218889515, 4213.831446742096, 4659.277811939155, 5506.146547285177, 5547.417521160301, 5673.914891276634, 5726.742296596663, 5917.852053698606, 5934.1692163097205, 5941.049432090931, 5945.583809133319, 6058.030715085653, 5680.091185410314, 5682.090865461506, 5715.914261286084, 5722.2561067061015, 5752.07966725322, 5188.844334669966, 5191.974040129775, 5193.195054766517, 5209.244659524347, 5216.087184742655, 6125.845966769368, 6165.475934545434, 6189.81430557084, 6205.503559715122, 6206.782762603287, 6085.57400981927, 6100.206973093469, 6117.571769976059, 6125.1424800527075, 6140.785072522441, 6195.0, 6222.0355844844125, 6246.6876656167915, 6259.34276900937, 6278.90119229124, 5648.689322087324, 5704.1443783432815, 5714.771409143587, 5834.533237340917, 6003.159557662018, 5916.940830591639, 5920.585559010828, 5953.1367951647635, 5990.9700451497465, 6053.697315134137, 5876.44173803488, 5880.94119058804, 5887.911681324698, 5907.527397808251, 5948.940510594839, 5840.123981402823, 5855.473007429352, 5863.442972917566, 5880.294364676266, 5904.852378690567, 4675.532446755297, 4714.952850471452, 4857.8299759508245, 5582.748776304995, 5670.461306175906, 5747.51146152565, 5771.394003629558, 5782.942170578241, 5785.82642520725, 5889.5582831286965, 5231.869203269949, 5291.470852914677, 5713.342965558848, 5737.799177028784, 5747.08046712524], "value": 5417.36916483668, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [468.5428317931234, 495.7793781767111, 497.6416979774562, 522.1775703267355, 543.2014938041078, 36841.44737828931, 39261.01847453808, 39405.40891886624, 39450.605493945055, 39659.00852478689, 39671.801640991755, 40128.19743605126, 40306.395404068964, 40370.394444083366, 40477.392839107364, 9291.070892910722, 9460.432374057544, 9485.905140948606, 9507.857382139273, 9538.90461095387, 9414.152726254635, 9497.620095196184, 9504.097110774464, 9519.143277105059, 9538.90461095387, 9467.0, 9472.289578281656, 9483.620655173785, 9491.050894910488, 9502.952485237563, 9445.716628501155, 9502.004579038341, 9505.904940950571, 9517.095875891779, 9523.095305945924, 7991.320737737241, 9382.85925711115, 9400.717978460658, 9425.151736343762, 9451.810963780754, 38480.422793658116, 38707.612923870685, 38878.41682374767, 39064.414033789515, 39112.413313800316, 3172.413103575846, 3309.619393769723, 3319.71782398494, 3381.543491628623, 3674.9193965688874, 29010.41979160426, 29375.41249175026, 30965.845170774002, 31196.220094497592, 31383.843080784678, 2759.4619049285398, 3174.6190357232076, 3188.1073299476184, 3220.5974253218346, 3244.772865899388, 493435.0, 498180.0, 500340.0, 501580.0, 510806.0, 44365.77817110894, 53844.461555384725, 59234.70382648059, 60387.39612603906, 60757.392426076054, 51867.0, 62647.06029409562, 63946.0, 64187.03719444212, 64246.0, 16124.919375403048, 16357.509274721539, 16371.918140409221, 16375.426860060079, 16436.917815410845, 10409.739756506147, 12565.748685026248, 12670.556530521388, 12672.873271267354, 12686.555970540987, 15022.924885375503, 17080.65838683244, 17592.824071759118, 17804.198811053535, 17828.732569011474, 3173.809571425722, 3271.726150325501, 3295.5880514935634, 6319.273283572448, 6401.050265550056, 798.4211446701095, 888.813349196676, 891.2023738753779, 1019.2864994503877, 1034.984475232872, 21649.0, 28389.71610283912, 29809.552856707167, 30046.54930176049, 30467.390652187274, 793.730131755206, 999.9450030248406, 1012.2003617142523, 1056.3872953686844, 1107.5071593140974, 15383.846161538466, 15703.450379236672, 15833.762493562606, 16004.199790010452, 16137.919310403373, 577872.0, 683404.0, 683683.0, 686684.0, 688836.0, 57795.13307300394, 57845.13232301519, 58478.53803654943, 58511.12233316504, 58563.70718146382, 1322.08115359824, 1337.9264140472173, 1349.3590544491485, 1358.0357945858354, 1365.7162267468746, 29077.563836542045, 29832.0, 29849.25376865596, 29904.25239368946, 30484.695153048186, 42461.363079553215, 51054.0, 51080.48919510757, 51815.0, 52048.47951520588, 631522.0, 632599.0, 636087.0, 636331.0, 638480.807595959, 25723.614145787455, 26322.86838565795, 26768.33079173036, 26786.19641410761, 26793.732062679122, 45853.54146458493, 46321.76839115783, 46619.0, 46687.76656116698, 47328.290075648205, 37975.0, 38328.0, 39028.25455505704, 39654.60345396624, 39739.60260397359, 38074.0, 38737.99616671793, 38903.80548097241, 38997.805010974764, 39485.0, 37017.0, 37096.44355334618, 38055.80972095122, 38333.23333533258, 39632.40551391789, 33141.502877456376, 34608.65391346054, 34649.480257796655, 34795.73084182874, 35063.948081557595, 22035.889820550794, 25227.621585676596, 25760.227193184233, 25914.093006744675, 26069.087581934542], "value": 19408.768221497678, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [282.6989256441888, 323.88502081760976, 348.258210012673, 361.2738395824393, 367.84182801395406, 18854.0, 19084.41797703576, 19590.706139407903, 20076.0, 20330.288439904605, 19494.902525487352, 19938.504612154073, 20250.797492025074, 20566.280180193695, 20694.068766905486, 5560.304961879765, 5770.855728606776, 5792.478676919077, 5798.884022319551, 5801.680907550083, 5621.943780562204, 7158.35574798268, 7550.848983020336, 7588.203238659944, 7590.54456732595, 7399.778006659782, 7537.434692398065, 7565.0, 7582.090149182079, 7654.349380302681, 7485.5508669480005, 7700.614969251543, 7743.612819359009, 7755.922440775605, 7779.922200778033, 7524.661390237453, 7553.660085296175, 7563.243675632448, 7575.053118360205, 7582.962085189594, 24370.634440483405, 26133.0, 27423.04019359313, 27641.723582764316, 27678.44643107147, 2223.0774228695072, 2390.7728765767397, 2437.3419176822335, 2442.914497992562, 2807.9719202807914, 14603.342849571689, 14919.701605967928, 15214.315355809014, 15557.144357060357, 16149.0, 1768.8054314025446, 1795.9191836367395, 1841.9539511512185, 1847.7043673012254, 1857.1828395505934, 119691.0, 121562.78437215603, 124249.0, 126973.36513317467, 127849.0, 21013.579728405348, 21589.24437644675, 21644.4588885276, 21760.062391577252, 21914.123435062418, 34692.826535867156, 34903.12742181466, 35188.12029699278, 35506.64493355085, 35560.0, 9150.40522366044, 10612.946935265429, 10675.46622668879, 10683.679489615324, 10709.839352409721, 7230.566166030055, 8834.911650883409, 8835.249003834746, 8961.731148065568, 9040.276777857756, 8000.319972802377, 9609.231261499064, 9654.27592930533, 9821.508924553887, 9830.361026533252, 3826.0, 3826.9858487500833, 3839.212961342918, 3937.822797974098, 4017.738846974937, 827.8509868223778, 839.6599377252222, 928.2713070239829, 936.8454205056225, 948.3029972969862, 16876.071816050237, 20651.69022464664, 21328.0, 21739.67390489144, 22802.771972280065, 762.4510352546158, 888.1695614600369, 896.0770406481334, 933.4072863731444, 975.5805003848413, 10631.893681063246, 10983.725406864733, 10994.725131871768, 10999.0, 11050.447477626247, 639404.0, 640018.0, 644795.0, 659903.0, 665360.0, 133293.0, 134975.0, 138751.0, 139865.0, 140149.0, 931.6739141300491, 994.9104580587782, 999.1008092716468, 1000.9749756256006, 1003.4180175498193, 17463.214155362784, 18275.81724182741, 18588.88466692003, 18636.0, 18773.812261877752, 49777.75111124566, 52285.215721763445, 53180.73409632927, 54087.188692168864, 54403.18395223995, 74144.25855741589, 76148.0, 76631.2336876624, 78620.21379786129, 79201.0, 6746.392824645805, 6801.591904485746, 6948.062011628365, 7848.136704962564, 7901.051873775184, 10803.297785643908, 12492.687682808002, 12768.936155319165, 13050.4127314273, 13147.60557183286, 10974.945125274322, 11024.559017639205, 11113.44432778374, 11135.220534562503, 11190.992810647082, 10913.18151138652, 10981.61564345244, 11228.045616122394, 11260.324380537195, 11382.94308528452, 10685.465726713788, 11167.944160279147, 11354.205205635857, 11537.48081336325, 11677.357745323749, 7932.953682234991, 8082.797930051795, 8241.175882411711, 8339.06962359948, 8422.536760478233, 9621.27840411958, 10615.0, 10670.62652807148, 11046.779064418828, 11057.72355691082], "value": 10825.631204302541, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1237.5359240284893, 1239.5785432952798, 1530.8928375013754, 1739.2086600596729, 1747.6242607839313, 7466.328030477256, 7727.574983375912, 8158.999999999993, 8552.48685078895, 8552.871706924394, 7990.640421181046, 8301.833963320745, 8351.707690230844, 8394.748157555268, 8396.91603083969, 2194.3526659635404, 2205.7904499072642, 2217.068831090946, 2225.9888700556476, 2229.0860747093693, 2197.1321328075483, 2237.7314722233355, 2246.73039235292, 2249.6737972993906, 2252.8423010389197, 2065.5249292662697, 2074.7406574178226, 2091.8640288381284, 2092.9058192381303, 2096.255829180634, 1856.7214917762308, 2201.801837834603, 2205.305328821424, 2211.1155537784853, 2211.513467037249, 2155.1594877997595, 2211.845170838042, 2212.8008479236787, 2214.0, 2215.490437199453, 8369.3304535637, 8378.874316885252, 8422.747317580483, 8476.194761497709, 8494.193051660082, 5079.415867175285, 5212.635115541876, 5254.4220135785035, 5255.86860328491, 5256.132738098209, 3323.9501407478906, 3411.829408529564, 3515.5605549306338, 3584.6773790358993, 3592.982035089834, 1045.9215558833118, 1075.2043487819035, 1091.317926296065, 1096.9780604387947, 1098.3245304137977, 10411.375317480899, 10782.191335649779, 10810.945945270223, 10854.185936054748, 11056.668299951014, 4345.956540434619, 4418.0942906704695, 4488.820447182076, 4523.7285762854335, 4542.091581683677, 7393.704251829974, 8538.829223415496, 8674.392792504465, 8692.869606955901, 8696.956515217384, 4650.302454631832, 4864.902701946012, 5123.795048198031, 5136.614753893473, 5165.483451654804, 4243.384709217146, 4480.977595112069, 4509.413776209072, 4819.012102519046, 4826.276058591239, 3286.5891763529557, 3701.1117331840164, 3767.0770661188185, 3788.1287303920253, 3793.0, 1152.8731839497648, 1153.411760002405, 1166.5916929074756, 1170.052257671289, 1170.0990237517235, 595.2767387624058, 798.5248776977689, 910.4764760262743, 929.4980710416435, 934.1779234273931, 4930.1865192243595, 5554.416786237387, 5615.241942337732, 5703.00634711762, 5828.300603927556, 430.5285712145196, 440.94929083155205, 446.176803796994, 448.37004009366893, 450.526946705964, 3372.7976321420792, 3626.437902125175, 3814.5994670559194, 3832.0, 3873.283442563135, 8146.918530814616, 8552.144785521523, 9815.773028371454, 10291.0, 10300.484975751182, 9950.900490994998, 10261.179105671528, 10289.022542858416, 10291.279610427348, 10294.742631434125, 6092.786752463642, 7674.0, 7819.882701759479, 7858.1356050834365, 7880.330171935335, 5655.858603534944, 5697.507464688676, 5834.737436815439, 5859.794907178229, 5952.107183922446, 9052.23056040244, 9382.718518444457, 9389.108034736963, 9426.95286523563, 9551.617935282511, 7736.806579835549, 9478.24174066087, 9497.810043799223, 9624.422534647943, 9652.0, 6595.274519802721, 6778.288279730755, 6970.37266645984, 7072.787816365517, 7258.782236532701, 7704.152543220353, 7902.565358905315, 7948.602569871599, 7953.244441778022, 7959.9602001989615, 7042.0, 7182.497225194186, 7200.747973820891, 7213.422926165789, 7307.123145222395, 6998.921552963716, 7103.822404439724, 7119.75080872167, 7240.456965727486, 7274.054372931485, 6617.735290588323, 6791.04925310447, 6794.014867844234, 6807.523473356818, 6812.182538095461, 4869.415670119608, 4877.951240483313, 4906.582940450102, 4915.483874193158, 4926.064047830901, 4799.328094066903, 4833.3958255218095, 4845.103655823684, 4871.2206047032305, 4959.776810043484], "value": 4242.487020044727, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [283.4953782267564, 300.9759219262459, 305.7951172714281, 308.1433614551546, 313.4452019924732, 5049.343585333911, 5163.3545806774155, 5234.5027222413855, 5271.499207575279, 5282.603804714642, 4187.811548480318, 5028.371453568304, 5138.383393992717, 5179.922301165486, 5200.895982080356, 4001.3397789364794, 4004.939925901114, 4006.459128017724, 4006.479157709494, 4023.9738866588978, 3440.690337869591, 3860.0, 3987.7407968482103, 4007.3588225883864, 4049.9595004050025, 3286.457734473821, 3803.543574771031, 3978.264021156081, 3985.481887354654, 3989.6608788253034, 3431.571053618285, 3851.879977218448, 3978.244133614604, 4000.0999525225575, 4003.8598649047435, 2908.9272768180754, 3042.1329920972416, 3486.581610206766, 3503.807290599016, 3506.8597256109724, 3980.0, 4973.7513124344, 5015.949840501585, 5065.214891691794, 5082.822101226439, 4525.977370113162, 4531.86404407865, 4541.591256786905, 4569.794359253841, 4590.0, 5460.726963651801, 5462.629896083117, 5468.090315424491, 5480.808171713971, 5494.395616482184, 3381.932361352759, 3586.3544561978833, 3760.4241094850117, 3810.618938106195, 3822.4266360045935, 4923.286123512071, 5597.3003374578175, 5615.578831587647, 5629.690367029771, 5705.0, 3382.712469440076, 3798.848046078126, 3818.5417749870194, 3830.1040984712745, 3868.903277418087, 3560.928781424357, 3701.482392219231, 3822.2737679840743, 3969.6824254059607, 3977.40045259041, 4294.720843145185, 4449.688521803443, 4500.3249512573375, 4504.842330518416, 4540.455145382509, 4779.402574678165, 5504.394516603171, 5513.531349835309, 5519.0, 5523.834284971457, 1042.3433237060756, 1108.41808050774, 1126.7915435644431, 1217.3608855351013, 1217.7990631545695, 873.7461742399677, 886.9556522173995, 895.6909866096187, 897.717219075989, 909.7043460875242, 5049.57078648319, 5054.1407960646775, 5063.8480845574695, 5076.416212135578, 5078.720670363092, 4199.887029937069, 4414.300494309714, 4776.044791041805, 4902.019596080798, 5422.5119739223655, 4051.959480405158, 4149.730267532601, 4866.635002374836, 5021.610412971229, 5038.345015148007, 2140.5504843982626, 2446.1683027770287, 2865.7564107051135, 2868.928276793055, 2881.3372924227547, 4654.557817007379, 5444.727763611803, 5617.185508101303, 5818.709064546756, 5825.854353641193, 4721.858344249678, 5312.802885720238, 5604.551635869202, 5612.719364031782, 5649.548036157097, 5126.026904395516, 5270.208129236002, 5487.670739755627, 5501.73615448023, 5782.0, 5230.959925629084, 5458.9454105458435, 5461.208124821799, 5465.863353416196, 5478.315210598676, 5367.570594352364, 5370.62405631602, 5388.218708287355, 5685.118806584986, 5730.770769169187, 6118.504831581277, 6123.540734444845, 6142.600730952474, 6175.505959523317, 6181.949068658429, 3198.5202219667235, 3440.3807314683595, 3833.4824798651825, 3856.2480316338047, 3858.1897801462005, 4141.8964525887095, 5062.519060689228, 5374.677519348852, 5434.83695489136, 5680.176374425602, 4599.44806623194, 4869.926951095807, 5449.891002179855, 5462.808801691922, 5573.665580065209, 5577.637453565505, 5583.972080139573, 5587.69267690281, 5594.608377413542, 5608.158776183605, 5144.819931302386, 5316.3354580677415, 5468.562514998711, 5481.890362192814, 5497.39528651856, 4580.450345958507, 4767.570918617341, 5422.972885135549, 5551.417101204315, 5575.972120139373, 4268.679849011274, 5054.444011158696, 5452.509274165344, 5457.972710136424, 5519.834404967857], "value": 3960.5032513370156, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [628.5882746800845, 692.4287462843153, 708.585477495665, 716.3982254905878, 717.5013365710827, 12707.04697147714, 13570.59288221355, 13682.0, 13691.589252322434, 13721.794173087412, 10674.839877401846, 12543.372831358442, 12640.747185056316, 12643.367831608428, 12667.176633518815, 634.7778277602848, 653.2357142143703, 785.9607019649023, 794.5907857453416, 811.2293321344746, 799.604195923016, 804.3082948664144, 811.9106898241218, 823.2056065896409, 824.5547404401616, 868.6178081644088, 869.7738587967142, 869.8129902071054, 870.4646642314995, 872.5811610426999, 830.0620299062082, 859.5530324231427, 865.0354854337431, 866.1165611076698, 868.1665601023026, 634.0204384226381, 644.136856612142, 644.5005121031238, 645.2676212498801, 645.2740666749906, 12563.56027539041, 12946.611601651966, 13017.544385946541, 13019.349032548334, 13055.934720326433, 11943.641690749291, 12060.276383417024, 12132.393380330948, 12141.332226727527, 12142.392880355947, 10143.13783328418, 10371.340585506283, 10413.635522756667, 10485.475726213659, 10509.894901050968, 3423.9315213695586, 3427.8114703691285, 3429.3141371725505, 3614.8915532534065, 3668.413053911388, 86367.56816215941, 88060.55969720174, 88457.11542884553, 89003.0, 89535.65696514492, 9386.483743394043, 9566.856497152548, 9667.323287369958, 9687.806243875082, 9705.417674939526, 18827.246910123715, 19060.71408928867, 19079.80920190808, 19101.90449047746, 19349.0, 6788.8302792429595, 6897.89653155193, 7015.088038555057, 7045.89431158533, 7111.395531379787, 3938.389549619756, 3994.320965435883, 4028.2144981728866, 4072.2669919414197, 4131.35963925592, 3134.2791158033324, 3198.328351046306, 3227.289996200832, 3228.257500774788, 3232.224266176148, 543.6873797566415, 615.8183335915916, 617.5121653893464, 619.454879705856, 678.4335080207983, 540.4082529630049, 551.0879494436714, 648.9902651460231, 667.0760996020449, 668.9665516724242, 8075.475094118863, 8158.0, 8189.181081891763, 8313.916860831436, 8447.746567602982, 501.51102674892417, 579.5421616922669, 597.8909123575792, 602.6956387024525, 693.743314973463, 3010.9397812043635, 3611.880317101707, 4008.7193896427552, 4042.959570404317, 4060.167665628539, 95626.56560151603, 97202.0, 98123.50938245263, 98809.0, 99245.0, 78410.60794696104, 79731.0, 84504.57747711345, 87505.68741468883, 90390.0, 67732.0, 75964.86052709214, 77995.0, 79102.0, 79312.81030784543, 8556.358273129665, 8740.868886966573, 9003.099690030944, 9032.187103160748, 9043.457392556467, 40748.777536673944, 40959.59040409558, 45845.54154458413, 50672.49327506825, 52985.735071324394, 71515.64242178756, 72689.6365518169, 74827.0, 76183.0, 76736.61631691805, 11009.559617615205, 11267.0, 11347.432628368713, 11508.88491115078, 11615.0, 12489.937550312554, 13123.475060997453, 13214.07501474926, 13258.0, 13272.933635331761, 11500.769984600094, 11624.302541847515, 11693.941530292293, 11844.052475802087, 12105.273683579013, 10612.310199836986, 10628.0, 11179.217454778087, 11690.941545292219, 11726.65466906605, 11704.297742135159, 11785.277146354121, 11895.10786691019, 12067.637970860887, 12385.814212786996, 9744.851213002476, 9911.306208565331, 9938.801223975624, 10111.292209545261, 10208.591656333663, 10039.849402259119, 10185.74535636615, 10255.897441025792, 10325.070743633109, 10370.896291036994], "value": 6786.248417774461, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1611.0999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 170.0, 176.0, 118.0, 117.0, 119.0, 119.0, 118.0, 118.0, 117.0], "value": 127.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 140.0, 127.0, 155.0, 128.0, 113.0, 119.0, 143.0, 137.0, 116.0], "value": 135.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [268.0, 256.0, 199.0, 191.0, 201.0, 217.0, 211.0, 254.0, 237.0, 225.0], "value": 225.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 58.0, 60.0, 82.0, 60.0, 60.0, 60.0, 59.0, 89.0, 84.0], "value": 69.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 71.0, 89.0, 74.0, 79.0, 101.0, 121.0, 99.0, 70.0, 88.0], "value": 87.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 85.0, 102.0, 80.0, 130.0, 127.0, 89.0, 78.0, 129.0], "value": 99.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 127.0, 99.0, 100.0, 109.0, 97.0, 99.0, 99.0, 100.0, 115.0], "value": 111.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 122.0, 137.0, 102.0, 80.0, 134.0, 149.0, 129.0, 91.0, 94.0], "value": 113.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 57.0, 58.0, 59.0, 61.0, 60.0, 60.0, 69.0, 86.0], "value": 62.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 77.0, 71.0, 78.0, 77.0, 73.0, 72.0, 95.0, 105.0, 89.0], "value": 80.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 84.0, 90.0, 87.0, 82.0, 88.0, 90.0, 89.0, 88.0], "value": 86.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 145.0, 157.0, 152.0, 153.0, 156.0, 161.0, 185.0, 229.0, 160.0], "value": 164.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 172.0, 167.0, 165.0, 169.0, 167.0, 168.0, 170.0, 166.0, 168.0], "value": 168.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 67.0, 70.0, 80.0, 87.0, 90.0, 82.0, 89.0, 90.0], "value": 79.1, "unit": "score"}]}]} 10:04:46 INFO - Return code: 0 10:04:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:04:46 ERROR - # TBPL SUCCESS # 10:04:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:04:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:04:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:04:46 INFO - 2017-01-04 10:04:46.857 screenresolution[1927:19991] starting screenresolution argv=screenresolution get 10:04:46 INFO - 2017-01-04 10:04:46.860 screenresolution[1927:19991] Display 0: 1600x1200x32@60 10:04:46 INFO - 2017-01-04 10:04:46.866 screenresolution[1928:19994] starting screenresolution argv=screenresolution list 10:04:46 INFO - Available Modes on Display 0 10:04:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:04:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:04:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:04:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:04:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:04:47 INFO - Intel Iris: 10:04:47 INFO - Chipset Model: Intel Iris 10:04:47 INFO - Type: GPU 10:04:47 INFO - Bus: Built-In 10:04:47 INFO - VRAM (Dynamic, Max): 1536 MB 10:04:47 INFO - Vendor: Intel (0x8086) 10:04:47 INFO - Device ID: 0x0a2e 10:04:47 INFO - Revision ID: 0x0009 10:04:47 INFO - Displays: 10:04:47 INFO - Display: 10:04:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:04:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:04:47 INFO - Display Serial Number: 1600x1200 60 10:04:47 INFO - Main Display: Yes 10:04:47 INFO - Mirror: Off 10:04:47 INFO - Online: Yes 10:04:47 INFO - Rotation: Supported 10:04:47 INFO - Return code: 0 10:04:47 INFO - Running post-action listener: _resource_record_post_action 10:04:47 INFO - [mozharness: 2017-01-04 18:04:47.107627Z] Finished run-tests step (success) 10:04:47 INFO - Running post-run listener: _resource_record_post_run 10:04:47 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 2397696; Write bytes: 659363840; Read time: 16; Write time: 1909 10:04:47 INFO - TinderboxPrint: CPU usage
54.1% 10:04:47 INFO - TinderboxPrint: I/O read bytes / time
2,397,696 / 16 10:04:47 INFO - TinderboxPrint: I/O write bytes / time
659,363,840 / 1,909 10:04:47 INFO - TinderboxPrint: CPU idle
2,222.3 (45.9%) 10:04:47 INFO - TinderboxPrint: CPU system
977.7 (20.2%) 10:04:47 INFO - TinderboxPrint: CPU user
1,645.8 (34.0%) 10:04:47 INFO - TinderboxPrint: Swap in / out
351,875,072 / 0 10:04:47 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 344443392; Write bytes: 346150400; Read time: 26811; Write time: 708 10:04:47 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 1533440; Write bytes: 306344448; Read time: 14; Write time: 1173 10:04:47 INFO - Running post-run listener: _upload_blobber_files 10:04:47 INFO - Blob upload gear active. 10:04:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:04:47 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 10:04:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:04:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:04:48 INFO - (blobuploader) - INFO - Open directory for files ... 10:04:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:04:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:04:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:04:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:04:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:04:48 INFO - (blobuploader) - INFO - Done attempting. 10:04:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:04:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:04:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:04:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:04:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:04:49 INFO - (blobuploader) - INFO - Done attempting. 10:04:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:04:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:04:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:04:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:04:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:04:51 INFO - (blobuploader) - INFO - Done attempting. 10:04:51 INFO - (blobuploader) - INFO - Iteration through files over. 10:04:51 INFO - Return code: 0 10:04:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:04:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:04:51 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/caa4e4fd19d04e31fe76b9069f4210bbe5a6117cc9b8510c3df9465860d4bcc51a533d56f5f16fde7ccc319ed78c44589bf52df8d9af9f07232f5946ebd04a9f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b06ac0f892c045287a76202403f6505ace7f7cdaa6c68b818c2708b8df129070bcc8479158897820f6b05d989caf6c98220595d0f4b86e588da10ebd8ede0fba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b9fd17a279f5f4a726596b4a55297257fc7acf51184e7f9561362101db363399cbffa4e95b862cf5e6542e3a20c672b1e8ff66bde918d29a497f7393999b49e9"} 10:04:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:04:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:04:51 INFO - Contents: 10:04:51 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/caa4e4fd19d04e31fe76b9069f4210bbe5a6117cc9b8510c3df9465860d4bcc51a533d56f5f16fde7ccc319ed78c44589bf52df8d9af9f07232f5946ebd04a9f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b06ac0f892c045287a76202403f6505ace7f7cdaa6c68b818c2708b8df129070bcc8479158897820f6b05d989caf6c98220595d0f4b86e588da10ebd8ede0fba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b9fd17a279f5f4a726596b4a55297257fc7acf51184e7f9561362101db363399cbffa4e95b862cf5e6542e3a20c672b1e8ff66bde918d29a497f7393999b49e9"} 10:04:51 INFO - Running post-run listener: copy_logs_to_upload_dir 10:04:51 INFO - Copying logs to upload dir... 10:04:51 INFO - mkdir: /builds/slave/test/build/upload/logs 10:04:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.013229 ========= master_lag: 0.10 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2017-01-04 10:04:52.027815) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-04 10:04:52.031285) ========= 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.yVAaPYKoqA/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.v2Af5p7F46/Listeners TMPDIR=/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/caa4e4fd19d04e31fe76b9069f4210bbe5a6117cc9b8510c3df9465860d4bcc51a533d56f5f16fde7ccc319ed78c44589bf52df8d9af9f07232f5946ebd04a9f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b06ac0f892c045287a76202403f6505ace7f7cdaa6c68b818c2708b8df129070bcc8479158897820f6b05d989caf6c98220595d0f4b86e588da10ebd8ede0fba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b9fd17a279f5f4a726596b4a55297257fc7acf51184e7f9561362101db363399cbffa4e95b862cf5e6542e3a20c672b1e8ff66bde918d29a497f7393999b49e9"} build_url:https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011192 build_url: 'https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/caa4e4fd19d04e31fe76b9069f4210bbe5a6117cc9b8510c3df9465860d4bcc51a533d56f5f16fde7ccc319ed78c44589bf52df8d9af9f07232f5946ebd04a9f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b06ac0f892c045287a76202403f6505ace7f7cdaa6c68b818c2708b8df129070bcc8479158897820f6b05d989caf6c98220595d0f4b86e588da10ebd8ede0fba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b9fd17a279f5f4a726596b4a55297257fc7acf51184e7f9561362101db363399cbffa4e95b862cf5e6542e3a20c672b1e8ff66bde918d29a497f7393999b49e9"}' ========= master_lag: 0.70 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-04 10:04:52.741599) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 10:04:52.741979) ========= 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.yVAaPYKoqA/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.v2Af5p7F46/Listeners TMPDIR=/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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.005394 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 10:04:52.790930) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-04 10:04:52.791250) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-04 10:04:52.791594) ========= ========= Total master_lag: 0.92 =========