builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0084 starttime: 1475494957.72 results: success (0) buildid: 20161003030438 builduid: cafe1550781b4784a55738e065856182 revision: 955840bfd3c20eb24dd5a01be27bdc55c489a285 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-03 04:42:37.715595) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-03 04:42:37.715969) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-03 04:42:37.716239) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-03 04:42:37.912414) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 04:42:37.912699) ========= 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.vzifQsTBou/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.diw2i6RTuY/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-03 04:42:37-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 29.2M=0s 2016-10-03 04:42:38 (29.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.501990 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 04:42:38.437485) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 04:42:38.437759) ========= 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.vzifQsTBou/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.diw2i6RTuY/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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.029830 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 04:42:38.488513) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-03 04:42:38.488812) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 955840bfd3c20eb24dd5a01be27bdc55c489a285 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 955840bfd3c20eb24dd5a01be27bdc55c489a285 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vzifQsTBou/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.diw2i6RTuY/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-03 04:42:38,559 truncating revision to first 12 chars 2016-10-03 04:42:38,560 Setting DEBUG logging. 2016-10-03 04:42:38,560 attempt 1/10 2016-10-03 04:42:38,560 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/955840bfd3c2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-03 04:42:39,331 unpacking tar archive at: mozilla-central-955840bfd3c2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.037102 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-03 04:42:39.548250) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-03 04:42:39.548548) ========= script_repo_revision: 955840bfd3c20eb24dd5a01be27bdc55c489a285 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-03 04:42:39.548921) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-03 04:42:39.550109) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-03 04:42:39.568502) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-10-03 04:42:39.568776) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vzifQsTBou/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.diw2i6RTuY/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 04:42:39 INFO - MultiFileLogger online at 20161003 04:42:39 in /builds/slave/test 04:42:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:42:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:42:39 INFO - {'append_to_log': False, 04:42:39 INFO - 'base_work_dir': '/builds/slave/test', 04:42:39 INFO - 'blob_upload_branch': 'Firefox', 04:42:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:42:39 INFO - 'branch': 'Firefox', 04:42:39 INFO - 'buildbot_json_path': 'buildprops.json', 04:42:39 INFO - 'config_files': ('talos/mac_config.py',), 04:42:39 INFO - 'default_actions': ('clobber', 04:42:39 INFO - 'read-buildbot-config', 04:42:39 INFO - 'download-and-extract', 04:42:39 INFO - 'populate-webroot', 04:42:39 INFO - 'create-virtualenv', 04:42:39 INFO - 'install', 04:42:39 INFO - 'run-tests'), 04:42:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:42:39 INFO - 'download_minidump_stackwalk': True, 04:42:39 INFO - 'download_symbols': 'ondemand', 04:42:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:42:39 INFO - 'tooltool.py': '/tools/tooltool.py', 04:42:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:42:39 INFO - '/tools/misc-python/virtualenv.py')}, 04:42:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:42:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:42:39 INFO - 'installer_path': 'installer.exe', 04:42:39 INFO - 'log_level': 'info', 04:42:39 INFO - 'log_name': 'talos', 04:42:39 INFO - 'log_to_console': True, 04:42:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:42:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:42:39 INFO - 'opt_config_files': (), 04:42:39 INFO - 'pip_index': False, 04:42:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:42:39 INFO - 'cmd': ('bash', 04:42:39 INFO - '-c', 04:42:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:42:39 INFO - 'enabled': True, 04:42:39 INFO - 'halt_on_failure': False, 04:42:39 INFO - 'name': 'check_screen_resolution'},), 04:42:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:42:39 INFO - 'cmd': ('bash', 04:42:39 INFO - '-c', 04:42:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:42:39 INFO - 'enabled': True, 04:42:39 INFO - 'halt_on_failure': False, 04:42:39 INFO - 'name': 'check_screen_resolution'},), 04:42:39 INFO - 'run_cmd_checks_enabled': True, 04:42:39 INFO - 'sps_profile': False, 04:42:39 INFO - 'sps_profile_interval': 0, 04:42:39 INFO - 'suite': 'dromaeojs-e10s', 04:42:39 INFO - 'system_bits': '32', 04:42:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:42:39 INFO - 'title': 't-yosemite-r7-0084', 04:42:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:42:39 INFO - 'use_talos_json': True, 04:42:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:42:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:42:39 INFO - 'work_dir': 'build'} 04:42:39 INFO - [mozharness: 2016-10-03 11:42:39.770171Z] Running clobber step. 04:42:39 INFO - Running pre-action listener: _resource_record_pre_action 04:42:39 INFO - Running main action method: clobber 04:42:39 INFO - rmtree: /builds/slave/test/build 04:42:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:42:42 INFO - Running post-action listener: _resource_record_post_action 04:42:42 INFO - [mozharness: 2016-10-03 11:42:42.025283Z] Finished clobber step (success) 04:42:42 INFO - [mozharness: 2016-10-03 11:42:42.025446Z] Running read-buildbot-config step. 04:42:42 INFO - Running pre-action listener: _resource_record_pre_action 04:42:42 INFO - Running main action method: read_buildbot_config 04:42:42 INFO - Using buildbot properties: 04:42:42 INFO - { 04:42:42 INFO - "project": "", 04:42:42 INFO - "product": "firefox", 04:42:42 INFO - "script_repo_revision": "production", 04:42:42 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 04:42:42 INFO - "repository": "", 04:42:42 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 04:42:42 INFO - "buildid": "20161003030438", 04:42:42 INFO - "stage_platform": "macosx64", 04:42:42 INFO - "basedir": "/builds/slave/test", 04:42:42 INFO - "nightly_build": "True", 04:42:42 INFO - "buildnumber": 306, 04:42:42 INFO - "slavename": "t-yosemite-r7-0084", 04:42:42 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 04:42:42 INFO - "platform": "yosemite_r7", 04:42:42 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:42:42 INFO - "branch": "mozilla-central", 04:42:42 INFO - "repo_path": "mozilla-central", 04:42:42 INFO - "revision": "955840bfd3c20eb24dd5a01be27bdc55c489a285", 04:42:42 INFO - "pgo_build": "False", 04:42:42 INFO - "builduid": "cafe1550781b4784a55738e065856182", 04:42:42 INFO - "slavebuilddir": "test" 04:42:42 INFO - } 04:42:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/HWkL42RrSsidisVAntOtNg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 04:42:42 INFO - Running post-action listener: _resource_record_post_action 04:42:42 INFO - [mozharness: 2016-10-03 11:42:42.028935Z] Finished read-buildbot-config step (success) 04:42:42 INFO - [mozharness: 2016-10-03 11:42:42.029050Z] Running download-and-extract step. 04:42:42 INFO - Running pre-action listener: _resource_record_pre_action 04:42:42 INFO - Running main action method: download_and_extract 04:42:42 INFO - mkdir: /builds/slave/test/build/tests 04:42:42 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')]} 04:42:42 INFO - trying https://queue.taskcluster.net/v1/task/HWkL42RrSsidisVAntOtNg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 04:42:42 INFO - Downloading https://queue.taskcluster.net/v1/task/HWkL42RrSsidisVAntOtNg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 04:42:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HWkL42RrSsidisVAntOtNg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 04:42:43 INFO - Downloaded 1199 bytes. 04:42:43 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 04:42:43 INFO - Using the following test package requirements: 04:42:43 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 04:42:43 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:42:43 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 04:42:43 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:42:43 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 04:42:43 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:42:43 INFO - u'jsshell-mac.zip'], 04:42:43 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:42:43 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 04:42:43 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 04:42:43 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:42:43 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 04:42:43 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:42:43 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 04:42:43 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:42:43 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 04:42:43 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:42:43 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 04:42:43 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:42:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HWkL42RrSsidisVAntOtNg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 04:42:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HWkL42RrSsidisVAntOtNg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:42:43 INFO - Fetch https://queue.taskcluster.net/v1/task/HWkL42RrSsidisVAntOtNg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 04:42:43 INFO - Http code: 200 04:42:43 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 04:42:43 INFO - x-amz-cf-id: PB56k18Yw__J3MUqL7waFkTqRufdHWw935qsZ-RTBPcqN4cUTItLMw== 04:42:43 INFO - x-amz-version-id: Bnvym11rvUx4bDPe2Ildf044m24fUmc. 04:42:44 INFO - Expected file size: 16476463 04:42:44 INFO - Obtained file size: 16476463 04:42:51 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:42:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HWkL42RrSsidisVAntOtNg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 04:42:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HWkL42RrSsidisVAntOtNg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:42:51 INFO - Fetch https://queue.taskcluster.net/v1/task/HWkL42RrSsidisVAntOtNg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 04:42:51 INFO - Http code: 200 04:42:51 INFO - via: 1.1 c0740de9f16cf32ffedfd2f9806b8483.cloudfront.net (CloudFront) 04:42:51 INFO - x-amz-cf-id: z2_sJh0pbu_T5u5Y6nTA1JzRPmEJPILUyLKv6wYVp0cGhMazACgh1w== 04:42:51 INFO - x-amz-version-id: Bnvym11rvUx4bDPe2Ildf044m24fUmc. 04:42:52 INFO - Expected file size: 16476463 04:42:52 INFO - Obtained file size: 16476463 04:42:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HWkL42RrSsidisVAntOtNg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 04:42:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HWkL42RrSsidisVAntOtNg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:42:58 INFO - Fetch https://queue.taskcluster.net/v1/task/HWkL42RrSsidisVAntOtNg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 04:42:59 INFO - Http code: 200 04:42:59 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 04:42:59 INFO - x-amz-cf-id: WfqD_UnmisA5HHfx7DamU2Qwhqs08Kl0WXtI3CFf1k-P_jJONtCskA== 04:42:59 INFO - x-amz-version-id: iWk7iiFgGVbUbVg7mIx1F006EqSmRcXG 04:43:00 INFO - Expected file size: 14408522 04:43:00 INFO - Obtained file size: 14408522 04:43:00 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')]} 04:43:00 INFO - trying https://queue.taskcluster.net/v1/task/HWkL42RrSsidisVAntOtNg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:43:00 INFO - Downloading https://queue.taskcluster.net/v1/task/HWkL42RrSsidisVAntOtNg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 04:43:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HWkL42RrSsidisVAntOtNg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 04:43:04 INFO - Downloaded 111873039 bytes. 04:43:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HWkL42RrSsidisVAntOtNg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:43:04 INFO - mkdir: /builds/slave/test/properties 04:43:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:43:04 INFO - Writing to file /builds/slave/test/properties/build_url 04:43:04 INFO - Contents: 04:43:04 INFO - build_url:https://queue.taskcluster.net/v1/task/HWkL42RrSsidisVAntOtNg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:43:05 INFO - Running post-action listener: _resource_record_post_action 04:43:05 INFO - Running post-action listener: set_extra_try_arguments 04:43:05 INFO - [mozharness: 2016-10-03 11:43:05.277648Z] Finished download-and-extract step (success) 04:43:05 INFO - [mozharness: 2016-10-03 11:43:05.277806Z] Running populate-webroot step. 04:43:05 INFO - Running pre-action listener: _resource_record_pre_action 04:43:05 INFO - Running main action method: populate_webroot 04:43:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:43:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:43:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:43:05 INFO - '--tppagecycles', 04:43:05 INFO - '7'], 04:43:05 INFO - 'tests': ['tsvgm']}}, 04:43:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:43:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:43:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:43:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:43:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:43:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:43:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:43:05 INFO - 'talos_options': ['--disable-e10s'], 04:43:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:43:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:43:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:43:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:43:05 INFO - 'talos_options': ['--disable-e10s'], 04:43:05 INFO - 'tests': ['damp', 'tps']}, 04:43:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:43:05 INFO - 'tests': ['damp', 'tps']}, 04:43:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:43:05 INFO - 'tests': ['dromaeo_dom']}, 04:43:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:43:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:43:05 INFO - 'tests': ['basic_compositor_video']}, 04:43:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:43:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:43:05 INFO - 'tests': ['a11yr', 04:43:05 INFO - 'ts_paint', 04:43:05 INFO - 'tpaint', 04:43:05 INFO - 'sessionrestore', 04:43:05 INFO - 'sessionrestore_no_auto_restore', 04:43:05 INFO - 'tabpaint']}, 04:43:05 INFO - 'other-e10s': {'tests': ['a11yr', 04:43:05 INFO - 'ts_paint', 04:43:05 INFO - 'tpaint', 04:43:05 INFO - 'sessionrestore', 04:43:05 INFO - 'sessionrestore_no_auto_restore', 04:43:05 INFO - 'tabpaint']}, 04:43:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:43:05 INFO - 'ts_paint', 04:43:05 INFO - 'tpaint', 04:43:05 INFO - 'sessionrestore', 04:43:05 INFO - 'sessionrestore_no_auto_restore', 04:43:05 INFO - 'tabpaint']}, 04:43:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:43:05 INFO - 'ts_paint', 04:43:05 INFO - 'tpaint', 04:43:05 INFO - 'sessionrestore', 04:43:05 INFO - 'sessionrestore_no_auto_restore', 04:43:05 INFO - 'tabpaint']}, 04:43:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:43:05 INFO - 'tests': ['a11yr', 04:43:05 INFO - 'ts_paint', 04:43:05 INFO - 'tpaint', 04:43:05 INFO - 'sessionrestore', 04:43:05 INFO - 'sessionrestore_no_auto_restore', 04:43:05 INFO - 'tabpaint']}, 04:43:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:43:05 INFO - 'tests': ['a11yr', 04:43:05 INFO - 'ts_paint', 04:43:05 INFO - 'tpaint', 04:43:05 INFO - 'sessionrestore', 04:43:05 INFO - 'sessionrestore_no_auto_restore', 04:43:05 INFO - 'tabpaint']}, 04:43:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:43:05 INFO - 'tests': ['tsvgx', 04:43:05 INFO - 'tsvgr_opacity', 04:43:05 INFO - 'tart', 04:43:05 INFO - 'tscrollx', 04:43:05 INFO - 'cart']}, 04:43:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:43:05 INFO - 'tsvgr_opacity', 04:43:05 INFO - 'tart', 04:43:05 INFO - 'tscrollx', 04:43:05 INFO - 'cart']}, 04:43:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:43:05 INFO - 'talos_options': ['--disable-e10s'], 04:43:05 INFO - 'tests': ['tp5o']}, 04:43:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:43:05 INFO - 'tests': ['tp5o']}, 04:43:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:43:05 INFO - 'talos_options': ['--disable-e10s', 04:43:05 INFO - '--xperf_path', 04:43:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:43:05 INFO - 'tests': ['tp5n']}, 04:43:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:43:05 INFO - 'talos_options': ['--xperf_path', 04:43:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:43:05 INFO - 'tests': ['tp5n']}}, 04:43:05 INFO - 'talos.zip': {'path': '', 04:43:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:43:05 INFO - Running post-action listener: _resource_record_post_action 04:43:05 INFO - [mozharness: 2016-10-03 11:43:05.296777Z] Finished populate-webroot step (success) 04:43:05 INFO - [mozharness: 2016-10-03 11:43:05.296923Z] Running create-virtualenv step. 04:43:05 INFO - Running pre-action listener: _resource_record_pre_action 04:43:05 INFO - Running main action method: create_virtualenv 04:43:05 INFO - Creating virtualenv /builds/slave/test/build/venv 04:43:05 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 04:43:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:43:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:43:05 INFO - Using real prefix '/tools/python27' 04:43:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:43:06 INFO - Installing distribute.............................................................................................................................................................................................done. 04:43:09 INFO - Installing pip.................done. 04:43:09 INFO - Return code: 0 04:43:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:43:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:43:09 INFO - Reading from file tmpfile_stdout 04:43:09 INFO - Output received: 04:43:09 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) 04:43:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:43:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:43:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:43:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1088db490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108851570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98a24dd2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086efd30>, '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 0x108515db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086e7b20>, '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.vzifQsTBou/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.diw2i6RTuY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 04:43:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:43:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:43:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vzifQsTBou/Render', 04:43:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:43:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:43:09 INFO - 'HOME': '/Users/cltbld', 04:43:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:43:09 INFO - 'LOGNAME': 'cltbld', 04:43:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:09 INFO - 'MOZ_NO_REMOTE': '1', 04:43:09 INFO - 'NO_EM_RESTART': '1', 04:43:09 INFO - 'PAGER': '/bin/cat', 04:43:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:43:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:09 INFO - 'PWD': '/builds/slave/test', 04:43:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:43:09 INFO - 'SHELL': '/bin/bash', 04:43:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.diw2i6RTuY/Listeners', 04:43:09 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 04:43:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:43:09 INFO - 'USER': 'cltbld', 04:43:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:43:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:43:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:09 INFO - 'XPC_FLAGS': '0x0', 04:43:09 INFO - 'XPC_SERVICE_NAME': '0', 04:43:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:43:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:09 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 04:43:09 INFO - Cleaning up... 04:43:09 INFO - Return code: 0 04:43:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:43:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:43:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:43:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1088db490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108851570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98a24dd2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086efd30>, '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 0x108515db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086e7b20>, '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.vzifQsTBou/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.diw2i6RTuY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 04:43:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:43:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:43:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vzifQsTBou/Render', 04:43:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:43:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:43:09 INFO - 'HOME': '/Users/cltbld', 04:43:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:43:09 INFO - 'LOGNAME': 'cltbld', 04:43:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:09 INFO - 'MOZ_NO_REMOTE': '1', 04:43:09 INFO - 'NO_EM_RESTART': '1', 04:43:09 INFO - 'PAGER': '/bin/cat', 04:43:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:43:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:09 INFO - 'PWD': '/builds/slave/test', 04:43:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:43:09 INFO - 'SHELL': '/bin/bash', 04:43:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.diw2i6RTuY/Listeners', 04:43:09 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 04:43:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:43:09 INFO - 'USER': 'cltbld', 04:43:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:43:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:43:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:09 INFO - 'XPC_FLAGS': '0x0', 04:43:09 INFO - 'XPC_SERVICE_NAME': '0', 04:43:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:43:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:09 INFO - Downloading/unpacking psutil>=3.1.1 04:43:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:43:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:43:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:43:13 INFO - Installing collected packages: psutil 04:43:13 INFO - Running setup.py install for psutil 04:43:13 INFO - building 'psutil._psutil_osx' extension 04:43:13 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 04:43:14 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 04:43:14 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 04:43:14 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 04:43:14 INFO - building 'psutil._psutil_posix' extension 04:43:14 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 04:43:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:43:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:43:14 INFO - ^ 04:43:14 INFO - 1 warning generated. 04:43:14 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 04:43:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:43:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:43:14 INFO - Successfully installed psutil 04:43:14 INFO - Cleaning up... 04:43:14 INFO - Return code: 0 04:43:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:43:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:43:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:43:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1088db490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108851570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98a24dd2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086efd30>, '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 0x108515db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086e7b20>, '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.vzifQsTBou/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.diw2i6RTuY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 04:43:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:43:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:43:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vzifQsTBou/Render', 04:43:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:43:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:43:14 INFO - 'HOME': '/Users/cltbld', 04:43:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:43:14 INFO - 'LOGNAME': 'cltbld', 04:43:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:14 INFO - 'MOZ_NO_REMOTE': '1', 04:43:14 INFO - 'NO_EM_RESTART': '1', 04:43:14 INFO - 'PAGER': '/bin/cat', 04:43:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:43:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:14 INFO - 'PWD': '/builds/slave/test', 04:43:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:43:14 INFO - 'SHELL': '/bin/bash', 04:43:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.diw2i6RTuY/Listeners', 04:43:14 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 04:43:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:43:14 INFO - 'USER': 'cltbld', 04:43:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:43:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:43:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:14 INFO - 'XPC_FLAGS': '0x0', 04:43:14 INFO - 'XPC_SERVICE_NAME': '0', 04:43:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:43:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:43: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 04:43: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 04:43: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 04:43: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 04:43: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 04:43: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 04:43:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:43:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:43:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:43:17 INFO - Installing collected packages: mozsystemmonitor 04:43:17 INFO - Running setup.py install for mozsystemmonitor 04:43:17 INFO - Successfully installed mozsystemmonitor 04:43:17 INFO - Cleaning up... 04:43:17 INFO - Return code: 0 04:43:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:43:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:43:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:43:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:17 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 0x1088db490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108851570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98a24dd2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086efd30>, '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 0x108515db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086e7b20>, '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.vzifQsTBou/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.diw2i6RTuY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 04:43:17 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 04:43:17 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 04:43:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vzifQsTBou/Render', 04:43:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:43:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:43:17 INFO - 'HOME': '/Users/cltbld', 04:43:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:43:17 INFO - 'LOGNAME': 'cltbld', 04:43:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:17 INFO - 'MOZ_NO_REMOTE': '1', 04:43:17 INFO - 'NO_EM_RESTART': '1', 04:43:17 INFO - 'PAGER': '/bin/cat', 04:43:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:43:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:17 INFO - 'PWD': '/builds/slave/test', 04:43:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:43:17 INFO - 'SHELL': '/bin/bash', 04:43:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.diw2i6RTuY/Listeners', 04:43:17 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 04:43:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:43:17 INFO - 'USER': 'cltbld', 04:43:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:43:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:43:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:17 INFO - 'XPC_FLAGS': '0x0', 04:43:17 INFO - 'XPC_SERVICE_NAME': '0', 04:43:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:43:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:18 INFO - Downloading/unpacking jsonschema==2.5.1 04:43:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:43:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:43:20 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 04:43:20 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 04:43:20 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 04:43:20 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 04:43:20 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 04:43:20 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 04:43:21 INFO - Downloading functools32-3.2.3-2.tar.gz 04:43:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:43:21 INFO - warning: no files found matching '*.txt' 04:43:21 INFO - no previously-included directories found matching 'build' 04:43:21 INFO - no previously-included directories found matching 'dist' 04:43:21 INFO - no previously-included directories found matching '.git*' 04:43:21 INFO - Installing collected packages: jsonschema, functools32 04:43:21 INFO - Running setup.py install for functools32 04:43:21 INFO - warning: no files found matching '*.txt' 04:43:21 INFO - no previously-included directories found matching 'build' 04:43:21 INFO - no previously-included directories found matching 'dist' 04:43:21 INFO - no previously-included directories found matching '.git*' 04:43:21 INFO - Successfully installed jsonschema functools32 04:43:21 INFO - Cleaning up... 04:43:21 INFO - Return code: 0 04:43:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:43:21 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')]} 04:43:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:21 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')]} 04:43:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:21 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 0x1088db490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108851570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98a24dd2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086efd30>, '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 0x108515db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086e7b20>, '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.vzifQsTBou/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.diw2i6RTuY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 04:43:21 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 04:43:21 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 04:43:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vzifQsTBou/Render', 04:43:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:43:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:43:21 INFO - 'HOME': '/Users/cltbld', 04:43:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:43:21 INFO - 'LOGNAME': 'cltbld', 04:43:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:21 INFO - 'MOZ_NO_REMOTE': '1', 04:43:21 INFO - 'NO_EM_RESTART': '1', 04:43:21 INFO - 'PAGER': '/bin/cat', 04:43:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:43:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:21 INFO - 'PWD': '/builds/slave/test', 04:43:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:43:21 INFO - 'SHELL': '/bin/bash', 04:43:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.diw2i6RTuY/Listeners', 04:43:21 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 04:43:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:43:21 INFO - 'USER': 'cltbld', 04:43:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:43:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:43:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:21 INFO - 'XPC_FLAGS': '0x0', 04:43:21 INFO - 'XPC_SERVICE_NAME': '0', 04:43:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:43:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:43:21 INFO - Cleaning up... 04:43:21 INFO - Return code: 0 04:43:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:43:21 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')]} 04:43:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:21 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')]} 04:43:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:21 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 0x1088db490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108851570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98a24dd2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086efd30>, '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 0x108515db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086e7b20>, '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.vzifQsTBou/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.diw2i6RTuY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 04:43:21 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 04:43:21 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 04:43:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vzifQsTBou/Render', 04:43:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:43:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:43:21 INFO - 'HOME': '/Users/cltbld', 04:43:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:43:21 INFO - 'LOGNAME': 'cltbld', 04:43:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:21 INFO - 'MOZ_NO_REMOTE': '1', 04:43:21 INFO - 'NO_EM_RESTART': '1', 04:43:21 INFO - 'PAGER': '/bin/cat', 04:43:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:43:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:21 INFO - 'PWD': '/builds/slave/test', 04:43:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:43:21 INFO - 'SHELL': '/bin/bash', 04:43:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.diw2i6RTuY/Listeners', 04:43:21 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 04:43:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:43:21 INFO - 'USER': 'cltbld', 04:43:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:43:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:43:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:21 INFO - 'XPC_FLAGS': '0x0', 04:43:21 INFO - 'XPC_SERVICE_NAME': '0', 04:43:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:43:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:21 INFO - Downloading/unpacking blobuploader==1.2.4 04:43:21 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 04:43:21 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 04:43:21 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 04:43:21 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 04:43:21 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 04:43:21 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 04:43:24 INFO - Downloading blobuploader-1.2.4.tar.gz 04:43:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:43:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:43:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:43:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:43:25 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 04:43:25 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 04:43:25 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 04:43:25 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 04:43:25 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 04:43:25 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 04:43:25 INFO - Downloading docopt-0.6.1.tar.gz 04:43:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:43:25 INFO - Installing collected packages: blobuploader, requests, docopt 04:43:25 INFO - Running setup.py install for blobuploader 04:43:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:43:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:43:25 INFO - Running setup.py install for requests 04:43:26 INFO - Running setup.py install for docopt 04:43:26 INFO - Successfully installed blobuploader requests docopt 04:43:26 INFO - Cleaning up... 04:43:26 INFO - Return code: 0 04:43:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:43:26 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')]} 04:43:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:26 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')]} 04:43:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:26 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 0x1088db490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108851570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98a24dd2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086efd30>, '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 0x108515db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086e7b20>, '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.vzifQsTBou/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.diw2i6RTuY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 04:43:26 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 04:43:26 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 04:43:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vzifQsTBou/Render', 04:43:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:43:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:43:26 INFO - 'HOME': '/Users/cltbld', 04:43:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:43:26 INFO - 'LOGNAME': 'cltbld', 04:43:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:26 INFO - 'MOZ_NO_REMOTE': '1', 04:43:26 INFO - 'NO_EM_RESTART': '1', 04:43:26 INFO - 'PAGER': '/bin/cat', 04:43:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:43:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:26 INFO - 'PWD': '/builds/slave/test', 04:43:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:43:26 INFO - 'SHELL': '/bin/bash', 04:43:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.diw2i6RTuY/Listeners', 04:43:26 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 04:43:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:43:26 INFO - 'USER': 'cltbld', 04:43:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:43:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:43:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:26 INFO - 'XPC_FLAGS': '0x0', 04:43:26 INFO - 'XPC_SERVICE_NAME': '0', 04:43:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:43:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:43:26 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-tocMeK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:43:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:43:26 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-GwfQCE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:43:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:43:27 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-vEZ4nz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:43:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:43:27 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-0tV86w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:43:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:43:27 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-Ucn8BG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:43:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:43:27 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-cfeBXz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:43:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:43:27 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-ixTUOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:43:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:43:27 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-los35Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:43:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:43:27 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-yU9h9z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:43:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:43:27 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-wKdeNP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:43:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:43:27 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-M1DfWq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:43:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:43:28 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-UOTyEq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:43:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:43:28 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-LeVl_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:43:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:43:28 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-zA8Sd_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:43:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:43:28 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-8Ns7Fj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:43:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:43:28 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-mflAIJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:43:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:43:28 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-jTp1JJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:43:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:43:28 INFO - Running setup.py install for manifestparser 04:43:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:43:28 INFO - Running setup.py install for mozcrash 04:43:28 INFO - Running setup.py install for mozdebug 04:43:29 INFO - Running setup.py install for mozdevice 04:43:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:43:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:43:29 INFO - Running setup.py install for mozfile 04:43:29 INFO - Running setup.py install for mozhttpd 04:43:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:43:29 INFO - Running setup.py install for mozinfo 04:43:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:43:29 INFO - Running setup.py install for mozInstall 04:43:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:43:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:43:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:43:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:43:29 INFO - Running setup.py install for mozleak 04:43:29 INFO - Running setup.py install for mozlog 04:43:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:43:30 INFO - Running setup.py install for moznetwork 04:43:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:43:30 INFO - Running setup.py install for mozprocess 04:43:30 INFO - Running setup.py install for mozprofile 04:43:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:43:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:43:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:43:30 INFO - Running setup.py install for mozrunner 04:43:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:43:30 INFO - Running setup.py install for mozscreenshot 04:43:30 INFO - Running setup.py install for moztest 04:43:31 INFO - Running setup.py install for mozversion 04:43:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:43:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:43:31 INFO - Cleaning up... 04:43:31 INFO - Return code: 0 04:43:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:43:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:43:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:43:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:31 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 0x1088db490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108851570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98a24dd2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086efd30>, '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 0x108515db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086e7b20>, '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.vzifQsTBou/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.diw2i6RTuY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 04:43:31 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 04:43:31 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 04:43:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vzifQsTBou/Render', 04:43:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:43:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:43:31 INFO - 'HOME': '/Users/cltbld', 04:43:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:43:31 INFO - 'LOGNAME': 'cltbld', 04:43:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:31 INFO - 'MOZ_NO_REMOTE': '1', 04:43:31 INFO - 'NO_EM_RESTART': '1', 04:43:31 INFO - 'PAGER': '/bin/cat', 04:43:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:43:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:31 INFO - 'PWD': '/builds/slave/test', 04:43:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:43:31 INFO - 'SHELL': '/bin/bash', 04:43:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.diw2i6RTuY/Listeners', 04:43:31 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 04:43:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:43:31 INFO - 'USER': 'cltbld', 04:43:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:43:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:43:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:31 INFO - 'XPC_FLAGS': '0x0', 04:43:31 INFO - 'XPC_SERVICE_NAME': '0', 04:43:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:43:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:43:31 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-rJqd73-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:43:31 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)) 04:43:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:43:31 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-lVzkuB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:43:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 04:43:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:43:31 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-Q23CsH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:43:31 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)) 04:43:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:43:31 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-BOteva-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:43:31 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)) 04:43:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:43:32 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-jxF8ah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:43:32 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)) 04:43:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:43:32 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-htQvtD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:43:32 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)) 04:43:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:43:32 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-JDgYym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:43:32 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)) 04:43:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:43:32 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-Kr0hvs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:43:32 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)) 04:43:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:43:32 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-fbLMqJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:43:32 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)) 04:43:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:43:32 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-kBJAeZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:43:32 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)) 04:43:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:43:32 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-7vAKIy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:43:32 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)) 04:43:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:43:32 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-4y92s7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:43:32 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)) 04:43:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:43:32 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-e6VJAI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:43:32 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)) 04:43:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:43:32 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-veD0Ky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:43:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 04:43:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:43:33 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-L4KBzg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:43:33 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)) 04:43:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:43:33 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-gK9NNm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:43:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:43:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:43:33 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-YBrtrF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:43:33 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)) 04:43:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:43:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:43:33 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)) 04:43:33 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)) 04:43:33 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)) 04:43:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:43:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:35 INFO - Downloading blessings-1.6.tar.gz 04:43:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:43:35 INFO - Installing collected packages: blessings 04:43:35 INFO - Running setup.py install for blessings 04:43:36 INFO - Successfully installed blessings 04:43:36 INFO - Cleaning up... 04:43:36 INFO - Return code: 0 04:43:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:43:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:43:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:43:36 INFO - Reading from file tmpfile_stdout 04:43:36 INFO - Current package versions: 04:43:36 INFO - blessings == 1.6 04:43:36 INFO - blobuploader == 1.2.4 04:43:36 INFO - docopt == 0.6.1 04:43:36 INFO - functools32 == 3.2.3-2 04:43:36 INFO - jsonschema == 2.5.1 04:43:36 INFO - manifestparser == 1.1 04:43:36 INFO - mozInstall == 1.12 04:43:36 INFO - mozcrash == 0.17 04:43:36 INFO - mozdebug == 0.1 04:43:36 INFO - mozdevice == 0.48 04:43:36 INFO - mozfile == 1.2 04:43:36 INFO - mozhttpd == 0.7 04:43:36 INFO - mozinfo == 0.9 04:43:36 INFO - mozleak == 0.1 04:43:36 INFO - mozlog == 3.4 04:43:36 INFO - moznetwork == 0.27 04:43:36 INFO - mozprocess == 0.23 04:43:36 INFO - mozprofile == 0.28 04:43:36 INFO - mozrunner == 6.12 04:43:36 INFO - mozscreenshot == 0.1 04:43:36 INFO - mozsystemmonitor == 0.3 04:43:36 INFO - moztest == 0.7 04:43:36 INFO - mozversion == 1.4 04:43:36 INFO - psutil == 3.1.1 04:43:36 INFO - requests == 1.2.3 04:43:36 INFO - wsgiref == 0.1.2 04:43:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:43:36 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')]} 04:43:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:36 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')]} 04:43:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:36 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 0x1088db490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108851570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98a24dd2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086efd30>, '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 0x108515db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086e7b20>, '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.vzifQsTBou/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.diw2i6RTuY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 04:43:36 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 04:43:36 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 04:43:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vzifQsTBou/Render', 04:43:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:43:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:43:36 INFO - 'HOME': '/Users/cltbld', 04:43:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:43:36 INFO - 'LOGNAME': 'cltbld', 04:43:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:36 INFO - 'MOZ_NO_REMOTE': '1', 04:43:36 INFO - 'NO_EM_RESTART': '1', 04:43:36 INFO - 'PAGER': '/bin/cat', 04:43:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:43:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:36 INFO - 'PWD': '/builds/slave/test', 04:43:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:43:36 INFO - 'SHELL': '/bin/bash', 04:43:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.diw2i6RTuY/Listeners', 04:43:36 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 04:43:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:43:36 INFO - 'USER': 'cltbld', 04:43:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:43:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:43:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:36 INFO - 'XPC_FLAGS': '0x0', 04:43:36 INFO - 'XPC_SERVICE_NAME': '0', 04:43:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:43:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:36 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)) 04:43:36 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)) 04:43:36 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)) 04:43:36 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)) 04:43:36 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)) 04:43:36 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)) 04:43:36 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)) 04:43:36 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)) 04:43:36 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)) 04:43:36 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)) 04:43:36 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)) 04:43:36 INFO - Cleaning up... 04:43:36 INFO - Return code: 0 04:43:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:43:36 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')]} 04:43:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:36 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')]} 04:43:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:36 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 0x1088db490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108851570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98a24dd2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086efd30>, '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 0x108515db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086e7b20>, '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.vzifQsTBou/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.diw2i6RTuY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 04:43:36 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 04:43:36 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 04:43:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vzifQsTBou/Render', 04:43:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:43:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:43:36 INFO - 'HOME': '/Users/cltbld', 04:43:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:43:36 INFO - 'LOGNAME': 'cltbld', 04:43:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:36 INFO - 'MOZ_NO_REMOTE': '1', 04:43:36 INFO - 'NO_EM_RESTART': '1', 04:43:36 INFO - 'PAGER': '/bin/cat', 04:43:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:43:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:36 INFO - 'PWD': '/builds/slave/test', 04:43:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:43:36 INFO - 'SHELL': '/bin/bash', 04:43:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.diw2i6RTuY/Listeners', 04:43:36 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 04:43:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:43:36 INFO - 'USER': 'cltbld', 04:43:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:43:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:43:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:36 INFO - 'XPC_FLAGS': '0x0', 04:43:36 INFO - 'XPC_SERVICE_NAME': '0', 04:43:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:43:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:43:37 INFO - Cleaning up... 04:43:37 INFO - Return code: 0 04:43:37 INFO - Running post-action listener: _resource_record_post_action 04:43:37 INFO - Running post-action listener: _start_resource_monitoring 04:43:37 INFO - Starting resource monitoring. 04:43:37 INFO - [mozharness: 2016-10-03 11:43:37.100102Z] Finished create-virtualenv step (success) 04:43:37 INFO - [mozharness: 2016-10-03 11:43:37.101175Z] Running install step. 04:43:37 INFO - Running pre-action listener: _resource_record_pre_action 04:43:37 INFO - Running main action method: install 04:43:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:43:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:43:37 INFO - Reading from file tmpfile_stdout 04:43:37 INFO - Detecting whether we're running mozinstall >=1.0... 04:43:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:43:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:43:37 INFO - Reading from file tmpfile_stdout 04:43:37 INFO - Output received: 04:43:37 INFO - Usage: mozinstall [options] installer 04:43:37 INFO - Options: 04:43:37 INFO - -h, --help show this help message and exit 04:43:37 INFO - -d DEST, --destination=DEST 04:43:37 INFO - Directory to install application into. [default: 04:43:37 INFO - "/builds/slave/test"] 04:43:37 INFO - --app=APP Application being installed. [default: firefox] 04:43:37 INFO - mkdir: /builds/slave/test/build/application 04:43:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:43:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:44:11 INFO - Reading from file tmpfile_stdout 04:44:11 INFO - Output received: 04:44:11 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:44:11 INFO - Running post-action listener: _resource_record_post_action 04:44:11 INFO - [mozharness: 2016-10-03 11:44:11.967628Z] Finished install step (success) 04:44:11 INFO - [mozharness: 2016-10-03 11:44:11.967759Z] Running run-tests step. 04:44:11 INFO - Running pre-action listener: _resource_record_pre_action 04:44:11 INFO - Running main action method: run_tests 04:44:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:44:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:44:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:44:11 INFO - 2016-10-03 04:44:11.980 screenresolution[1653:8866] starting screenresolution argv=screenresolution get 04:44:11 INFO - 2016-10-03 04:44:11.982 screenresolution[1653:8866] Display 0: 1600x1200x32@60 04:44:11 INFO - 2016-10-03 04:44:11.988 screenresolution[1654:8868] starting screenresolution argv=screenresolution list 04:44:11 INFO - Available Modes on Display 0 04:44:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:44:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:44:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:44:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:44:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:44:12 INFO - Intel Iris: 04:44:12 INFO - Chipset Model: Intel Iris 04:44:12 INFO - Type: GPU 04:44:12 INFO - Bus: Built-In 04:44:12 INFO - VRAM (Dynamic, Max): 1536 MB 04:44:12 INFO - Vendor: Intel (0x8086) 04:44:12 INFO - Device ID: 0x0a2e 04:44:12 INFO - Revision ID: 0x0009 04:44:12 INFO - Displays: 04:44:12 INFO - Display: 04:44:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:44:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:44:12 INFO - Display Serial Number: 1600x1200 60 04:44:12 INFO - Main Display: Yes 04:44:12 INFO - Mirror: Off 04:44:12 INFO - Online: Yes 04:44:12 INFO - Rotation: Supported 04:44:12 INFO - Return code: 0 04:44:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:44:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:44:12 INFO - Python 2.7.3 04:44:12 INFO - Return code: 0 04:44:12 INFO - grabbing minidump binary from tooltool 04:44:12 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')]} 04:44:12 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 0x1086efd30>, '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 0x108515db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086e7b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:44:12 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 04:44:12 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 04:44:12 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 04:44:12 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:44:12 INFO - Return code: 0 04:44:12 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:44:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:44:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:44:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:44:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:44:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HWkL42RrSsidisVAntOtNg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0084', '--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 04:44:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HWkL42RrSsidisVAntOtNg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0084 --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 04:44:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vzifQsTBou/Render', 04:44:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:12 INFO - 'HOME': '/Users/cltbld', 04:44:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:12 INFO - 'LOGNAME': 'cltbld', 04:44:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:44:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:44:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:12 INFO - 'MOZ_NO_REMOTE': '1', 04:44:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:44:12 INFO - 'NO_EM_RESTART': '1', 04:44:12 INFO - 'PAGER': '/bin/cat', 04:44:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:12 INFO - 'PWD': '/builds/slave/test', 04:44:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:44:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:12 INFO - 'SHELL': '/bin/bash', 04:44:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.diw2i6RTuY/Listeners', 04:44:12 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 04:44:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:12 INFO - 'USER': 'cltbld', 04:44:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:12 INFO - 'XPC_FLAGS': '0x0', 04:44:12 INFO - 'XPC_SERVICE_NAME': '0', 04:44:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HWkL42RrSsidisVAntOtNg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0084', '--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 04:44:12 INFO - mozversion application_buildid: 20161003030438 04:44:12 INFO - mozversion application_changeset: 955840bfd3c20eb24dd5a01be27bdc55c489a285 04:44:12 INFO - mozversion application_display_name: Nightly 04:44:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:44:12 INFO - mozversion application_name: Firefox 04:44:12 INFO - mozversion application_remotingname: firefox 04:44:12 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:44:12 INFO - mozversion application_vendor: Mozilla 04:44:12 INFO - mozversion application_version: 52.0a1 04:44:12 INFO - mozversion platform_buildid: 20161003030438 04:44:12 INFO - mozversion platform_changeset: 955840bfd3c20eb24dd5a01be27bdc55c489a285 04:44:12 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:44:12 INFO - mozversion platform_version: 52.0a1 04:44:12 INFO - using testdate: 1475495052 04:44:12 INFO - actual date: 1475495052 04:44:12 INFO - starting webserver on 'localhost:49274' 04:44:12 INFO - SUITE-START | Running 2 tests 04:44:12 INFO - TEST-START | dromaeo_css 04:44:12 INFO - operating with platform_type : mac_ 04:44:12 INFO - Initialising browser for dromaeo_css test... 04:44:12 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmpVk8Qtl/profile http://localhost:49274/getInfo.html) 04:44:15 INFO - PROCESS | 1664 | 2016-10-03 04:44:15.829 plugin-container[1668:9057] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c4f, name = 'com.apple.tsm.portname' 04:44:15 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:44:15 INFO - PROCESS | 1664 | 2016-10-03 04:44:15.830 plugin-container[1668:9057] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x970b, name = 'com.apple.CFPasteboardClient' 04:44:15 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:44:15 INFO - PROCESS | 1664 | 2016-10-03 04:44:15.830 plugin-container[1668:9057] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:44:15 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 04:44:15 INFO - PROCESS | 1664 | colorDepth:24 04:44:15 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 04:44:15 INFO - PROCESS | 1664 | __metrics 04:44:15 ERROR - PROCESS | 1664 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3079: TypeError: this.tabs is undefined 04:44:16 INFO - TEST-INFO | 1664: exit 0 04:44:16 INFO - Browser initialized. 04:44:16 INFO - Running cycle 1/1 for dromaeo_css test... 04:44:16 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmpVk8Qtl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:44:18 INFO - PROCESS | 1669 | 2016-10-03 04:44:18.932 plugin-container[1670:9175] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a1f, name = 'com.apple.tsm.portname' 04:44:18 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:44:18 INFO - PROCESS | 1669 | 2016-10-03 04:44:18.933 plugin-container[1670:9175] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9003, name = 'com.apple.CFPasteboardClient' 04:44:18 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:44:18 INFO - PROCESS | 1669 | 2016-10-03 04:44:18.933 plugin-container[1670:9175] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:44:28 INFO - PROCESS | 1669 | RSS: Main: 240422912 04:44:28 INFO - PROCESS | 1669 | 04:45:18 INFO - PROCESS | 1669 | 1475495118262 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 04:47:31 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 04:47:33 INFO - PROCESS | 1669 | RSS: Main: 255852544 04:47:33 INFO - PROCESS | 1669 | 04:50:35 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 04:50:36 INFO - PROCESS | 1669 | RSS: Main: 266088448 04:50:36 INFO - PROCESS | 1669 | 04:53:38 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 04:53:38 INFO - PROCESS | 1669 | RSS: Main: 270823424 04:53:38 INFO - PROCESS | 1669 | 04:56:40 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 04:56:41 INFO - PROCESS | 1669 | RSS: Main: 270934016 04:56:41 INFO - PROCESS | 1669 | 04:59:44 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 04:59:44 INFO - PROCESS | 1669 | RSS: Main: 276344832 04:59:44 INFO - PROCESS | 1669 | 05:02:47 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 05:02:48 INFO - PROCESS | 1669 | RSS: Main: 279040000 05:02:48 INFO - PROCESS | 1669 | 05:02:48 INFO - PROCESS | 1669 | __start_tp_report 05:02:48 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 05:02:48 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 05:02:48 INFO - PROCESS | 1669 | |i|pagename|runs| 05:02:48 INFO - PROCESS | 1669 | |0;dojo.html;2955.9408811823764;3424.657534246576;3434.9141271468225;3435.604905435874;3474.7567670263084;5698.658080515172;5856.47291743743;5877.206577112089;5880.1767752514625;5916.467517923386;5641.18202860585;5665.8583535411635;5724.284464441945;5756.568257380693;5816.796412125576;5206.114960456732;5250.212468129773;5250.212468129783;5358.490943360374;5372.355317361922;4903.065565853319;5366.785328586851;5395.136778115502;5409.945060713162;5487.588430867681;5197.272381866542;5233.188855727369;5336.51971322579;5358.91961620576;5369.463053694639;5274.208868669692;5286.127788914824;5293.7617807198385;5295.4969277918535;5341.946580534183;4294.785260736982;4869.952960113593;5235.816746413857;5250.133727934886;5272.525472707474;4623.144718227105;5703.31560212773;5727.455891690324;5751.798687045975;5790.449907258803;6153.815385538441;6209.192804935375;6227.657478838662;6236.469900058501;6241.719122639447;6176.258848938156;6228.190335256388;6235.844103897393;6239.532035097386;6258.906116408258;5868.4424979626865;5899.439553242392;5904;5921.585489015813;5960.254968128984;6211.534134939807;6230.283517395557;6295.433410993032;6315.810525684236;6367.331430199855;6174.753009879554;6238.937610623836;6245.18812554365;6247.250329960434;6262.780802671885;6076.088586712029;6259.906101408483;6263.71813268404;6276.968615156895;6310.1796766420075;6109.586862300737;6195.2255968004;6251.468625166821;6274;6288.402601752826;6245.812625621239;6277.24673039229;6285.402886725754;6300.873982520416;6318.589291696025;4994.3507344045765;4999.325091112726;5002.624803139779;5009.674371165863;5011.84964451067;4408.360787685768;4429.712068715523;4433.733975961452;4445.844395446144;4501.3022981437925;5067.771950262173;5697.971510142423;5980.581359304894;5994.190784244071;6035.547333949971;6155.815325540241;6224.128621992924;6227.221597300338;6229.844253893689;6240.563160578716;5870.559708021916;5924.259467566554;5924.7333869975955;5995.730192141364;6008.128821320884;5429.67421954684;5485.670859748428;5504.7522861470525;5541.6674999499355;5560.749766260528;6334.5565810393755;6385.680715964183;6389.456896163878;6408.423241908251;6455.064015717789;5003.699778013331;5049.57078648319;5124.948750512447;6196.3803619639475;6231.189945307081;5814.592978491465;6065.181200537871;6302.149209856794;6310.400511951358;6435.22777266731;6181.041938499539;6215.409536094064;6229.813105606839;6243.063540468785;6272.937270627235;6138.754449822136;6278.434940855345;6285.1515045470105;6294.213223347082;6303.338149494237;5407.864803379947;5977.462028417463;6104.004449388181;6114.844865410725;6139.662318572521;5108.029474399852;5556.416576259435;5769.365369809401;6131.325554188947;6166.321704612579;5648.858778530405;5923.992921203319;6039.63762174271;6045.909311360421;6095.420935011167;6128.228138398502;6139.584134579464;6198.2252218472695;6214.875702485834;6217.720202590985;5277.656952298088;5804.2744656917885;6071.332153463204;6076.1493390924425;6105.419985101408 05:02:48 INFO - PROCESS | 1669 | |1;ext.html;499.4019138755977;506.05115408608856;515.778215367392;529.3171808367206;554.9639273447226;36994.63005369945;37851.05372365692;40415;40431.99999999996;40520.392194117114;41953.37069943953;42415.3637695434;42482.36276455856;42502;42630;9520.76198095048;9723.611055557769;9738.951305243463;9764.951175244114;9785.217182625373;9592.760180995496;9684.951575242114;9686.46724430156;9688.75778105546;9700.854487182698;9679.27405444591;9709.174720148796;9710.980347063563;9733;9735.464549449778;9657.71026869195;9693.321467497279;9699.757506062335;9717.90282097177;9748.415095094282;9592.66425675098;9685.079917407906;9700.75748106296;9703.272254580934;9718.854217186748;30083.849580752176;36224.73213437517;37990.620093798985;39540.406893896616;40467.98830029243;2818.408134291801;2951.409718056376;3089.4130115278026;3484.337975784593;3683.189698266377;30823.69176308253;31145.688543114506;31519.527207091913;32056;32374.6762532374;3111.533270009494;3266.4283750343593;3275.033865009828;3323.9501407478906;3385.6783605557434;516183;516633;518692;524013;524306;63982.68008659927;64014.679926601;64314.67842660757;64381.71236575242;64507;54888.176677349875;65498;66034.66982665056;66039.33960660428;66341;15929.761053584205;16517.421890234025;16671.333146674;16713.832861671228;16790.58023549421;12804.231746095078;12925.482980680856;12940.805887911689;12945.482180712666;12948.741025179444;17338.91330543339;17448.47654570365;18520.629587408177;18580.163892624863;19344.936028518565;7595.3164215220895;7649.808754781064;7725.343345815556;7794.3764498839955;7799.025121859768;698.5958223969756;954.8615450759601;997.8104160209537;1017.3539802225492;1029.1920842138884;29565.704342956724;30007;30060.69939300579;30769.846150769103;31117.222069448446;873.2402809555728;1017.6896046705825;1078.5847448732138;1096.276457538021;1126.9380184089953;15242.390304387922;15617.141057241734;15633.687326253412;15713.607159821097;15884.60288492797;730571;737860.6604504683;739908;741805;742115;57900.710496447246;58330.708346458;58384.70807645935;58485;58687.70656146692;1317.2754984758344;1324.735052989406;1361.46902707942;1364.379207460603;1369.7465968795805;28398.43203135967;29312;29451.558226626188;29852;29966.70033299639;50410.74794626003;50653.227137050024;50707.74646126894;50827.23759143542;50857.74571127121;599008;602388;603993;604540;605502;26634.600480993187;26745.33136671599;26773.732262677902;27173;27293.181204563894;45261.54738452574;46071;46472.444015579465;46941;47646;38303.808480957414;38569.42145867758;38861.6113838858;39846;40182.59817401788;37267.25465490621;38297.808510957264;39028.41457378085;39371.606283936795;40704.796476018615;32510.51234231441;33025.66974330226;35919.82040089783;38521.22957540889;38849.41725874058;31888.681113189497;33218.003459896245;33702.66297336995;34010;34104.31791364209;20623.690644640043;25477.745222548278;25660.871695641403;25703.485930281662;25861.870690646425 05:02:48 INFO - PROCESS | 1669 | |2;jquery.html;365.5649776765647;367.82712125301106;375.7707798243071;379.79870668545647;398.01689826129467;17341.91329043356;18827.811145141397;20184.587078904482;20448;21038.263660771896;15985.520434386957;16726;17020.48938531846;19703.817770933754;20101;5897.4692277695;5932.169496270515;6201.503879689614;6206.441420272175;6209.751609935597;5975.52195824333;6119.908201376983;6127.35662755411;6159.7536098556;6164.969175154118;4805.303231031498;6034.69826508675;6060.818175454743;6101.298350689661;6123.357047509992;6109.969450152743;6168.969155154218;6171.259448866164;6171.444569988723;6183.505319574423;5143.12566863634;5416.566674666017;5474.7810087596445;5512.889742205173;6005.309389420229;12648.55730049453;12775.23348599087;12800.48798048077;13007.934960325138;13008.349582520836;2108.325335892515;2216.710971184737;2255.6912826207554;2296.437372843647;2381.5117900830287;9702.417854928726;10248.64129755455;13063.86936130636;14809.92595037018;15479.5356139316;1799.199356286453;1893.8958357290344;1901.6767149584605;1921.625283069802;1922.3406212551436;107755.45301384291;109007.90992090057;121480;121719;124365;11501.309921404743;11510.194286399872;11553.653390398302;11687.415629218674;11787.941060294643;14088.647783805282;14091.647708807363;14096.647583810487;14114.294285285694;14154.079984800956;8276.751697449086;8426.873596896052;8531.872021919677;8547.888774459405;8635.6977505787;7015.719371225094;8465;8613.353998450142;8695.304375649934;8701.347398945105;4448.221561226773;4489.416375871116;4537.659675524349;4618.930716039262;4626.398568186181;1933.825955663969;1961.8626696131134;1965.4571161638046;1999.4201681512197;2015.0932080563805;841.7895526118471;865.2861389353808;865.6710450029075;879.8680197970356;946.5456580841235;20037.799622003593;20544;21045;21050;21221.15115395398;880.2693764175718;953.7377221264207;954.5513608603945;966.4104896013426;1004.5881188712593;10668;10872.734846906826;11036.944815275872;11076.11391088711;11099.44502774858;482018;559675;559715;591017;605285;121641;122378;124322;125772;125828;829.344817594106;958.8226178157063;962.3744566031995;985.5269470653983;988.6243227573476;17962.730559041887;17995.460136195936;18079.909600451912;18085.377620245254;18138.637227255644;43908.78045609879;54211.72894135504;54453.1832022512;54618.18072728992;55338;72277.55444891177;74704.62647686727;75109;75520.86718699113;76677;6786.60874520732;7625.428092892944;7914.24814642608;8046.0747014091785;8062.911506946487;10196.541155647865;10767.569297228023;12003.219790713576;12403.93798031004;12528.248305101723;9863.605455781688;10225.744356390853;10327.845082323622;10619.840702389316;10762.300450470695;10619.840702389316;10797.838032429363;10859.402732849769;11112.277701949348;11335;10942.124630029737;11297.661070167907;11314.547418103184;11378;11419.771604568028;7348.619634967761;7880.369570434237;8051.677932882853;8114.756557303291;8304.418690691593;8245.175482451707;10332.070113689804;10698.358098513803;11073.280236784583;11183.539621283304 05:02:48 INFO - PROCESS | 1669 | |3;mootools.html;1359.2388262572958;1497.8577035181654;1683.6548507555945;1722.8793984421095;1872.6067525819576;7983.203779149689;8271.172882711726;8477.576121193939;9069.637214511427;9081.682141125062;8482.745517634465;8774.605142768574;8815.250703690193;8894.91105088949;8915.910840891589;2314.2710046335433;2332.265336419031;2334.159702507096;2343.261872510154;2344.9296521104393;2309.7921187093243;2330.254318618043;2364.314348838843;2367.7868991790733;2373.4185124644487;2339.251439539348;2346.756219203825;2350.2949115265437;2357.669926210332;2358.1982126077132;2312.791848733605;2322.094383190549;2326.8254880883915;2345.8475199112004;2347.4835536182013;2282.0643536150264;2329.662198981155;2335.719713634358;2337.5324935012895;2346.425125844162;8885.42244754089;8965;8968.058353872815;8974.955125224398;9018.278537716968;5188.584913206934;5215.956808638288;5306.681599104066;5369.704666243355;5376.247325374412;3390.4575267957007;3537.964620353815;3598.082488965323;3715.20123173519;3720.497732806091;1051.0382999555397;1080.6920027792041;1087.152021423291;1088.4448931045144;1101.2291396022736;11740.412979350998;11837.585684501;11882.881171188264;11894.405279736065;11896.22674526153;4482.484514280834;4703.623710103184;4771.880702982384;4829.140780799417;4836.516348365206;10023.849642255373;10088.949555252177;10105.343152695052;10123.240756943258;10139.695809125738;4078.3678529827926;4309.676774241944;4542.613877820356;5099.0056938897305;5322.733863306819;3529.7529172957647;4257.212415703105;4719.59883409914;4841.273808928689;4854.126257273725;3885.805709714503;3919.8824035278985;3997.5608780838875;4054.432379466877;4061.837526498966;1268.3658170914543;1273.7834568123442;1275.279467101085;1291.599604122725;1295.956754812385;750.6659536506203;874.7681864305964;884.730157302029;893.3166127912152;896.5562046786752;5718.1136923776885;5737.713114344266;5862.020738846438;5941.762329506858;5960.493358064613;368.5522090659861;380.3477036881761;382.7110531548663;390.6874500399726;391.48910671573867;3162.7469802415753;3183.936321273608;3277.4919887417486;3287.375398674244;3305.0910999474854;10648.893511064945;10881.12950963921;10909;11011.889881101246;11073.394357818072;10979.670609881716;10981.341119532854;10986.230963832455;10989.835152472719;11051.115910727123;8053;8102.554359510284;8241.83516329682;8368.832623347498;8381.664733410595;5289.576833853205;5815.883682326245;5846.6784326862435;5855.677937713466;5889.293284805851;8390.496570205807;9223.76940576464;9975.002499750257;9979.101880830469;10060.346077504939;9491.38306010107;10092.848607271044;10331.793364132525;10386;10422.853486116674;6960.860782784417;7162.713491460491;7268.127824661075;7502.137254215835;7574.3183113520045;8270.627821747916;8502.78743031429;8508.61711222984;8527.18991695788;8546.871796922927;6225.439710426083;7420.220876807857;7549.471536992358;7586.203448637813;7687.154413014676;6534.771283005072;7383.483156179016;7426.777196684108;7445.181030086795;7599.620018998918;6931.549449285982;6947.40947019509;7033.156021277479;7036.718531258693;7037.648117594203;4940.777665005012;5003.174476211384;5047.87380315495;5048.72232027242;5054.69671819692;4870.707757534559;4936.111499930047;5050.3434553507805;5148.794048238028;5151.098557752303 05:02:48 INFO - PROCESS | 1669 | |4;prototype.html;295.8535524915167;305.4151300260002;311.0543946402934;315.80420139513507;317.6489978573676;5064.468230835764;5342.252084708144;5539.27989361383;5616.522595579376;5672.68800215988;4796.5537575836315;4882.389701287339;5327.893442131155;5553.527950124234;5685.061964775818;3419.6580341965855;3580.6598373154507;4270.188664153801;4270.444842170514;4284.271673815459;3920.8823735287842;4170.958290417103;4294.720843145201;4306.461692288464;4310.4396428464415;3647.94528082079;4049.969476332987;4173.686973476986;4198.391233271173;4250;3856.67218286446;4201.43280657112;4201.487464512788;4218.514870789868;4247.5540068293;3385.8814941477176;3648.1426864686778;3665.6334366563397;3698.68561172298;3712.9071773205615;5271.710055946922;5407.215953686733;5452.345718513764;5461.42655021225;5498.80754173606;4037.1320166164414;4311.50417701962;4426.55734426558;4604.2633178691585;4863.683860549088;4952.975235123838;5159.896802063975;5880.7353669085;5893.705314734246;5960.254968128984;3812.2756676231706;4021.2761702893467;4032.3145065338963;4054.716169868081;4062.8577999770164;5796.652200867919;5969.880602387928;6168.75324987;6169.321374648785;6183;3273.639899611041;3982.1637456134536;4084;4089.5297040840087;4100.712950093465;3723.7525428981053;4166.187593419315;4171.33258678616;4181.452862440856;4192.350185721218;4262.339337402707;4278.44380230568;4470.150671372494;4817.8313759018965;4853.296272040534;5002.849914502571;5381.327334083239;5405.70268635221;5860.505571079433;5947.851303717442;1221.3709939381226;1286.3825363825417;1308.9280089595163;1417.4046900301898;1424.7577911755027;739.9123288765506;743.4498471131324;823.4524041512447;857.439460182462;877.8902637170354;4075.898102547401;4186.116247688552;4498.143678764009;4501.887452813706;4622.86131416058;4396.868093957187;4687.999680204672;4838.491958344393;4997.525235102659;5039.244113383022;4111.8560850370095;4287.97856010718;4491.158624963749;4585.266357382803;4611.8847028823875;2094.5706130243266;2479.5866356176807;2512.7361627029263;2578.414275220763;2649.5893136563864;4425.760786979651;4715.834945776881;4741.478437371887;5114.462981386975;5177.844664660066;4289.656827453796;4665.020345727436;4684.5549672781035;4845.563899249085;5017.197248440232;4808.61531077513;4828.589569886599;5110.054639891632;5153.948460515422;5160.058766503057;4020.7133717210213;4235.851745188904;4297.205017071852;4686.531346865286;4821.9035619286715;4521.299198624219;4776.9729508155915;4898.583620392164;4942.110420124268;4999.375078115236;4409.625181859577;4578.816847326203;4583.770811459347;5090.803661139703;5136.845894623168;3494.371013217645;3515.1739341255297;3557.4485954677066;3567.304375646724;3587.7393947561673;4301.483821941262;4677.292788132375;4868.829590964299;5026.723530205873;5049.520295571915;4503.504614492469;4513.4273464126445;4804.453639571033;4899.951000490092;4925.285833554186;4770.522947705202;4774.641901857444;4810.927836082532;4844.806207751651;4858.271259311132;4180.703981765662;4229.492460904711;4299.290617048277;4550.34020067095;4724.48030716614;3316.17238295503;4116.835326586903;4170.687198460067;4668.743219122845;4746.33551302811;3831.429114063221;3970.543387510473;4134.472752336187;4672.813087476463;4738.455077665974 05:02:48 INFO - PROCESS | 1669 | |5;yui.html;702.6205848841626;724.3589423360327;759.3469616130124;771.23647588887;812.560458367438;14807.925960370196;15937.123458209793;15966.920165399186;15991.84008159918;16005.75991360129;14309.356078976445;14313.71372572548;14424.711505769877;14458.927705361484;14473.927630361859;696.6690821859602;850.6554845287668;865.5888452984844;876.5529579914257;879.3361012435602;709.2871663977695;853.138330286409;867.9566021698921;879.8504254276788;895.2256298301954;909.6770646420524;924.2790623313826;924.3852837862813;925.4863550729342;930.2000279759402;889.617464490269;914.4330515080626;918.1002617434951;921.1295325917011;921.9907800921972;637.8724255148989;651.4202359899693;673.1148539670318;688.7141836138047;691.0670594697169;14606.561803145923;14766.409343626243;14790.186539740418;14812;14855.851441485554;14299.285035748171;14579.489717859824;14634.341454634567;14649.780253296209;14863.70272594542;11940.164188506807;12017;12036.398180091048;12239.388030598524;12424.440900159514;3837.5586807517216;3869.496965394509;3891.8054097295303;4020.3768415895584;4025.5370632377358;118747.40626296813;120447.3977630115;120776.48740932516;121605;122384;11153.944230278797;11256.606018789304;11322.830157547643;11375.31748095117;11589.478473468545;23080.422989425064;23116.19093331725;23123.884380578325;23182.840857957035;23206.76793232046;7779.338756205672;7969.72105976288;8001.479903806234;8007.79883017552;8054.556999364974;4449.354843547732;4482.170798402306;4496.797644106023;4500.414946057057;4639.188142075123;3533.9293214135573;3575.481555174485;3580.946285805715;3595.7123430125525;3651.744377893522;647.1780838335288;651.3681728723132;653.4576301669603;654.5254690349459;664.3622122762106;589.9793507227226;629.9748010079637;656.4813797100333;661.4014317043084;676.681959479044;9154.450732956044;9302.720918372459;9348;9365.812683746288;9466;571.7455732199214;588.2352941176454;589.2130224040228;632.3929028133044;667.6494840208832;4370.38814565961;4489.708168969007;4610.596879979678;4614.792334344896;4687.67186296956;120053.19920201204;125513;152309;158450;179235;82792.58603706943;107132;107936.46031769791;114620;136049;70681.46592670346;72555;73321.26678733145;104281.47859260807;106752.46623766831;7189.964050179716;9711.36876103051;9744.512774361396;9890.950545247515;10053.547590358305;48461.75769121132;48759.02481950412;53931.191032135335;54214;55094.44905550893;54156;97114.5144274274;98312;99953.00046999437;102168.48915755373;11611.013063889664;11615.883841161818;11620.302781833117;11646.24299420535;11886.049116070866;12325.75348493043;12444.329697282845;12533.310667912987;12548.18436801605;12838.614841554769;11854.52581896749;11909.106816988933;12018.519259229883;12384.81422778677;12395.380230988594;11875.584354547549;12010.039196864054;12057.51769929193;12100.939495302468;12439.378031098227;11412.429378530875;11858.466369013242;11939.582114625946;11950.646181922652;12080.275183489019;9909.355891867006;10009.649662262078;10011.599536018477;10045.799084018132;10113.494325283853;8727.78180545466;9709.854352184864;10228.28402011852;10228.977102289711;10324.845127322946 05:02:48 INFO - PROCESS | 1669 | __end_tp_report 05:02:48 INFO - PROCESS | 1669 | __start_cc_report 05:02:48 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,362 05:02:48 INFO - PROCESS | 1669 | __end_cc_report 05:02:48 INFO - PROCESS | 1669 | __startTimestamp1475496168128__endTimestamp 05:02:48 INFO - PROCESS | 1669 | 05:02:48 INFO - PROCESS | 1669 | ------- Summary: start ------- 05:02:48 INFO - PROCESS | 1669 | Number of tests: 6 05:02:48 INFO - PROCESS | 1669 | 05:02:48 INFO - PROCESS | 1669 | [#0] dojo.html Cycles:165 Average:5738.77 Median:5977.46 stddev:660.63 (11.1%) stddev-sans-first:625.55 05:02:48 INFO - PROCESS | 1669 | Values: 2955.9 3424.7 3434.9 3435.6 3474.8 5698.7 5856.5 5877.2 5880.2 5916.5 5641.2 5665.9 5724.3 5756.6 5816.8 5206.1 5250.2 5250.2 5358.5 5372.4 4903.1 5366.8 5395.1 5409.9 5487.6 5197.3 5233.2 5336.5 5358.9 5369.5 5274.2 5286.1 5293.8 5295.5 5341.9 4294.8 4870.0 5235.8 5250.1 5272.5 4623.1 5703.3 5727.5 5751.8 5790.4 6153.8 6209.2 6227.7 6236.5 6241.7 6176.3 6228.2 6235.8 6239.5 6258.9 5868.4 5899.4 5904.0 5921.6 5960.3 6211.5 6230.3 6295.4 6315.8 6367.3 6174.8 6238.9 6245.2 6247.3 6262.8 6076.1 6259.9 6263.7 6277.0 6310.2 6109.6 6195.2 6251.5 6274.0 6288.4 6245.8 6277.2 6285.4 6300.9 6318.6 4994.4 4999.3 5002.6 5009.7 5011.8 4408.4 4429.7 4433.7 4445.8 4501.3 5067.8 5698.0 5980.6 5994.2 6035.5 6155.8 6224.1 6227.2 6229.8 6240.6 5870.6 5924.3 5924.7 5995.7 6008.1 5429.7 5485.7 5504.8 5541.7 5560.7 6334.6 6385.7 6389.5 6408.4 6455.1 5003.7 5049.6 5124.9 6196.4 6231.2 5814.6 6065.2 6302.1 6310.4 6435.2 6181.0 6215.4 6229.8 6243.1 6272.9 6138.8 6278.4 6285.2 6294.2 6303.3 5407.9 5977.5 6104.0 6114.8 6139.7 5108.0 5556.4 5769.4 6131.3 6166.3 5648.9 5924.0 6039.6 6045.9 6095.4 6128.2 6139.6 6198.2 6214.9 6217.7 5277.7 5804.3 6071.3 6076.1 6105.4 05:02:48 INFO - PROCESS | 1669 | 05:02:48 INFO - PROCESS | 1669 | [#1] ext.html Cycles:180 Average:74540.47 Median:40298.80 stddev:168098.16 (417.1%) stddev-sans-first:168477.80 05:02:48 INFO - PROCESS | 1669 | Values: 499.4 506.1 515.8 529.3 555.0 36994.6 37851.1 40415.0 40432.0 40520.4 41953.4 42415.4 42482.4 42502.0 42630.0 9520.8 9723.6 9739.0 9765.0 9785.2 9592.8 9685.0 9686.5 9688.8 9700.9 9679.3 9709.2 9711.0 9733.0 9735.5 9657.7 9693.3 9699.8 9717.9 9748.4 9592.7 9685.1 9700.8 9703.3 9718.9 30083.8 36224.7 37990.6 39540.4 40468.0 2818.4 2951.4 3089.4 3484.3 3683.2 30823.7 31145.7 31519.5 32056.0 32374.7 3111.5 3266.4 3275.0 3324.0 3385.7 516183.0 516633.0 518692.0 524013.0 524306.0 63982.7 64014.7 64314.7 64381.7 64507.0 54888.2 65498.0 66034.7 66039.3 66341.0 15929.8 16517.4 16671.3 16713.8 16790.6 12804.2 12925.5 12940.8 12945.5 12948.7 17338.9 17448.5 18520.6 18580.2 19344.9 7595.3 7649.8 7725.3 7794.4 7799.0 698.6 954.9 997.8 1017.4 1029.2 29565.7 30007.0 30060.7 30769.8 31117.2 873.2 1017.7 1078.6 1096.3 1126.9 15242.4 15617.1 15633.7 15713.6 15884.6 730571.0 737860.7 739908.0 741805.0 742115.0 57900.7 58330.7 58384.7 58485.0 58687.7 1317.3 1324.7 1361.5 1364.4 1369.7 28398.4 29312.0 29451.6 29852.0 29966.7 50410.7 50653.2 50707.7 50827.2 50857.7 599008.0 602388.0 603993.0 604540.0 605502.0 26634.6 26745.3 26773.7 27173.0 27293.2 45261.5 46071.0 46472.4 46941.0 47646.0 38303.8 38569.4 38861.6 39846.0 40182.6 37267.3 38297.8 39028.4 39371.6 40704.8 32510.5 33025.7 35919.8 38521.2 38849.4 31888.7 33218.0 33702.7 34010.0 34104.3 20623.7 25477.7 25660.9 25703.5 25861.9 05:02:48 INFO - PROCESS | 1669 | 05:02:48 INFO - PROCESS | 1669 | [#2] jquery.html Cycles:180 Average:33239.52 Median:2276.06 stddev:93939.05 (4127.3%) stddev-sans-first:94170.14 05:02:48 INFO - PROCESS | 1669 | Values: 365.6 367.8 375.8 379.8 398.0 17341.9 18827.8 20184.6 20448.0 21038.3 15985.5 16726.0 17020.5 19703.8 20101.0 5897.5 5932.2 6201.5 6206.4 6209.8 5975.5 6119.9 6127.4 6159.8 6165.0 4805.3 6034.7 6060.8 6101.3 6123.4 6110.0 6169.0 6171.3 6171.4 6183.5 5143.1 5416.6 5474.8 5512.9 6005.3 12648.6 12775.2 12800.5 13007.9 13008.3 2108.3 2216.7 2255.7 2296.4 2381.5 9702.4 10248.6 13063.9 14809.9 15479.5 1799.2 1893.9 1901.7 1921.6 1922.3 107755.5 109007.9 121480.0 121719.0 124365.0 11501.3 11510.2 11553.7 11687.4 11787.9 14088.6 14091.6 14096.6 14114.3 14154.1 8276.8 8426.9 8531.9 8547.9 8635.7 7015.7 8465.0 8613.4 8695.3 8701.3 4448.2 4489.4 4537.7 4618.9 4626.4 1933.8 1961.9 1965.5 1999.4 2015.1 841.8 865.3 865.7 879.9 946.5 20037.8 20544.0 21045.0 21050.0 21221.2 880.3 953.7 954.6 966.4 1004.6 10668.0 10872.7 11036.9 11076.1 11099.4 482018.0 559675.0 559715.0 591017.0 605285.0 121641.0 122378.0 124322.0 125772.0 125828.0 829.3 958.8 962.4 985.5 988.6 17962.7 17995.5 18079.9 18085.4 18138.6 43908.8 54211.7 54453.2 54618.2 55338.0 72277.6 74704.6 75109.0 75520.9 76677.0 6786.6 7625.4 7914.2 8046.1 8062.9 10196.5 10767.6 12003.2 12403.9 12528.2 9863.6 10225.7 10327.8 10619.8 10762.3 10619.8 10797.8 10859.4 11112.3 11335.0 10942.1 11297.7 11314.5 11378.0 11419.8 7348.6 7880.4 8051.7 8114.8 8304.4 8245.2 10332.1 10698.4 11073.3 11183.5 05:02:48 INFO - PROCESS | 1669 | 05:02:48 INFO - PROCESS | 1669 | [#3] mootools.html Cycles:180 Average:5663.97 Median:4623.12 stddev:3275.41 (70.8%) stddev-sans-first:3268.63 05:02:48 INFO - PROCESS | 1669 | Values: 1359.2 1497.9 1683.7 1722.9 1872.6 7983.2 8271.2 8477.6 9069.6 9081.7 8482.7 8774.6 8815.3 8894.9 8915.9 2314.3 2332.3 2334.2 2343.3 2344.9 2309.8 2330.3 2364.3 2367.8 2373.4 2339.3 2346.8 2350.3 2357.7 2358.2 2312.8 2322.1 2326.8 2345.8 2347.5 2282.1 2329.7 2335.7 2337.5 2346.4 8885.4 8965.0 8968.1 8975.0 9018.3 5188.6 5216.0 5306.7 5369.7 5376.2 3390.5 3538.0 3598.1 3715.2 3720.5 1051.0 1080.7 1087.2 1088.4 1101.2 11740.4 11837.6 11882.9 11894.4 11896.2 4482.5 4703.6 4771.9 4829.1 4836.5 10023.8 10088.9 10105.3 10123.2 10139.7 4078.4 4309.7 4542.6 5099.0 5322.7 3529.8 4257.2 4719.6 4841.3 4854.1 3885.8 3919.9 3997.6 4054.4 4061.8 1268.4 1273.8 1275.3 1291.6 1296.0 750.7 874.8 884.7 893.3 896.6 5718.1 5737.7 5862.0 5941.8 5960.5 368.6 380.3 382.7 390.7 391.5 3162.7 3183.9 3277.5 3287.4 3305.1 10648.9 10881.1 10909.0 11011.9 11073.4 10979.7 10981.3 10986.2 10989.8 11051.1 8053.0 8102.6 8241.8 8368.8 8381.7 5289.6 5815.9 5846.7 5855.7 5889.3 8390.5 9223.8 9975.0 9979.1 10060.3 9491.4 10092.8 10331.8 10386.0 10422.9 6960.9 7162.7 7268.1 7502.1 7574.3 8270.6 8502.8 8508.6 8527.2 8546.9 6225.4 7420.2 7549.5 7586.2 7687.2 6534.8 7383.5 7426.8 7445.2 7599.6 6931.5 6947.4 7033.2 7036.7 7037.6 4940.8 5003.2 5047.9 5048.7 5054.7 4870.7 4936.1 5050.3 5148.8 5151.1 05:02:48 INFO - PROCESS | 1669 | 05:02:48 INFO - PROCESS | 1669 | [#4] prototype.html Cycles:180 Average:4222.78 Median:4508.47 stddev:1249.67 (27.7%) stddev-sans-first:1217.92 05:02:48 INFO - PROCESS | 1669 | Values: 295.9 305.4 311.1 315.8 317.6 5064.5 5342.3 5539.3 5616.5 5672.7 4796.6 4882.4 5327.9 5553.5 5685.1 3419.7 3580.7 4270.2 4270.4 4284.3 3920.9 4171.0 4294.7 4306.5 4310.4 3647.9 4050.0 4173.7 4198.4 4250.0 3856.7 4201.4 4201.5 4218.5 4247.6 3385.9 3648.1 3665.6 3698.7 3712.9 5271.7 5407.2 5452.3 5461.4 5498.8 4037.1 4311.5 4426.6 4604.3 4863.7 4953.0 5159.9 5880.7 5893.7 5960.3 3812.3 4021.3 4032.3 4054.7 4062.9 5796.7 5969.9 6168.8 6169.3 6183.0 3273.6 3982.2 4084.0 4089.5 4100.7 3723.8 4166.2 4171.3 4181.5 4192.4 4262.3 4278.4 4470.2 4817.8 4853.3 5002.8 5381.3 5405.7 5860.5 5947.9 1221.4 1286.4 1308.9 1417.4 1424.8 739.9 743.4 823.5 857.4 877.9 4075.9 4186.1 4498.1 4501.9 4622.9 4396.9 4688.0 4838.5 4997.5 5039.2 4111.9 4288.0 4491.2 4585.3 4611.9 2094.6 2479.6 2512.7 2578.4 2649.6 4425.8 4715.8 4741.5 5114.5 5177.8 4289.7 4665.0 4684.6 4845.6 5017.2 4808.6 4828.6 5110.1 5153.9 5160.1 4020.7 4235.9 4297.2 4686.5 4821.9 4521.3 4777.0 4898.6 4942.1 4999.4 4409.6 4578.8 4583.8 5090.8 5136.8 3494.4 3515.2 3557.4 3567.3 3587.7 4301.5 4677.3 4868.8 5026.7 5049.5 4503.5 4513.4 4804.5 4900.0 4925.3 4770.5 4774.6 4810.9 4844.8 4858.3 4180.7 4229.5 4299.3 4550.3 4724.5 3316.2 4116.8 4170.7 4668.7 4746.3 3831.4 3970.5 4134.5 4672.8 4738.5 05:02:48 INFO - PROCESS | 1669 | 05:02:48 INFO - PROCESS | 1669 | [#5] yui.html Cycles:180 Average:23384.73 Median:3853.53 stddev:37773.82 (980.2%) stddev-sans-first:37841.40 05:02:48 INFO - PROCESS | 1669 | Values: 702.6 724.4 759.3 771.2 812.6 14807.9 15937.1 15966.9 15991.8 16005.8 14309.4 14313.7 14424.7 14458.9 14473.9 696.7 850.7 865.6 876.6 879.3 709.3 853.1 868.0 879.9 895.2 909.7 924.3 924.4 925.5 930.2 889.6 914.4 918.1 921.1 922.0 637.9 651.4 673.1 688.7 691.1 14606.6 14766.4 14790.2 14812.0 14855.9 14299.3 14579.5 14634.3 14649.8 14863.7 11940.2 12017.0 12036.4 12239.4 12424.4 3837.6 3869.5 3891.8 4020.4 4025.5 118747.4 120447.4 120776.5 121605.0 122384.0 11153.9 11256.6 11322.8 11375.3 11589.5 23080.4 23116.2 23123.9 23182.8 23206.8 7779.3 7969.7 8001.5 8007.8 8054.6 4449.4 4482.2 4496.8 4500.4 4639.2 3533.9 3575.5 3580.9 3595.7 3651.7 647.2 651.4 653.5 654.5 664.4 590.0 630.0 656.5 661.4 676.7 9154.5 9302.7 9348.0 9365.8 9466.0 571.7 588.2 589.2 632.4 667.6 4370.4 4489.7 4610.6 4614.8 4687.7 120053.2 125513.0 152309.0 158450.0 179235.0 82792.6 107132.0 107936.5 114620.0 136049.0 70681.5 72555.0 73321.3 104281.5 106752.5 7190.0 9711.4 9744.5 9891.0 10053.5 48461.8 48759.0 53931.2 54214.0 55094.4 54156.0 97114.5 98312.0 99953.0 102168.5 11611.0 11615.9 11620.3 11646.2 11886.0 12325.8 12444.3 12533.3 12548.2 12838.6 11854.5 11909.1 12018.5 12384.8 12395.4 11875.6 12010.0 12057.5 12100.9 12439.4 11412.4 11858.5 11939.6 11950.6 12080.3 9909.4 10009.6 10011.6 10045.8 10113.5 8727.8 9709.9 10228.3 10229.0 10324.8 05:02:48 INFO - PROCESS | 1669 | -------- Summary: end -------- 05:02:48 INFO - PROCESS | 1669 | 05:02:48 INFO - TEST-INFO | 1669: exit 0 05:02:48 INFO - TEST-OK | dromaeo_css | took 1116260ms 05:02:48 INFO - TEST-START | kraken 05:02:48 INFO - operating with platform_type : mac_ 05:02:48 INFO - Initialising browser for kraken test... 05:02:48 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmpbZt2Sz/profile http://localhost:49274/getInfo.html) 05:02:51 INFO - PROCESS | 1679 | 2016-10-03 05:02:51.627 plugin-container[1681:11465] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e27, name = 'com.apple.tsm.portname' 05:02:51 INFO - PROCESS | 1679 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:02:51 INFO - PROCESS | 1679 | 2016-10-03 05:02:51.627 plugin-container[1681:11465] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9103, name = 'com.apple.CFPasteboardClient' 05:02:51 INFO - PROCESS | 1679 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:02:51 INFO - PROCESS | 1679 | 2016-10-03 05:02:51.627 plugin-container[1681:11465] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:02:51 INFO - PROCESS | 1679 | __metrics Screen width/height:1600/1200 05:02:51 INFO - PROCESS | 1679 | colorDepth:24 05:02:51 INFO - PROCESS | 1679 | Browser inner width/height: 1024/689 05:02:51 INFO - PROCESS | 1679 | __metrics 05:02:51 ERROR - PROCESS | 1679 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3079: TypeError: this.tabs is undefined 05:02:52 INFO - TEST-INFO | 1679: exit 0 05:02:52 INFO - Browser initialized. 05:02:52 INFO - Running cycle 1/1 for kraken test... 05:02:52 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmpbZt2Sz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:02:55 INFO - PROCESS | 1682 | 2016-10-03 05:02:55.063 plugin-container[1683:11578] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.tsm.portname' 05:02:55 INFO - PROCESS | 1682 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:02:55 INFO - PROCESS | 1682 | 2016-10-03 05:02:55.064 plugin-container[1683:11578] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9103, name = 'com.apple.CFPasteboardClient' 05:02:55 INFO - PROCESS | 1682 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:02:55 INFO - PROCESS | 1682 | 2016-10-03 05:02:55.064 plugin-container[1683:11578] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:03:04 INFO - PROCESS | 1682 | RSS: Main: 240226304 05:03:04 INFO - PROCESS | 1682 | 05:03:07 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection) 05:03:08 INFO - PROCESS | 1682 | RSS: Main: 254070784 05:03:08 INFO - PROCESS | 1682 | 05:03:11 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-dft) 05:03:12 INFO - PROCESS | 1682 | RSS: Main: 262660096 05:03:12 INFO - PROCESS | 1682 | 05:03:15 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-fft) 05:03:16 INFO - PROCESS | 1682 | RSS: Main: 262832128 05:03:16 INFO - PROCESS | 1682 | 05:03:18 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator) 05:03:18 INFO - PROCESS | 1682 | RSS: Main: 264937472 05:03:18 INFO - PROCESS | 1682 | 05:03:20 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:03:21 INFO - PROCESS | 1682 | RSS: Main: 265203712 05:03:21 INFO - PROCESS | 1682 | 05:03:24 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom) 05:03:24 INFO - PROCESS | 1682 | RSS: Main: 266137600 05:03:24 INFO - PROCESS | 1682 | 05:03:27 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate) 05:03:28 INFO - PROCESS | 1682 | RSS: Main: 266248192 05:03:28 INFO - PROCESS | 1682 | 05:03:30 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial) 05:03:31 INFO - PROCESS | 1682 | RSS: Main: 266571776 05:03:31 INFO - PROCESS | 1682 | 05:03:33 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:03:33 INFO - PROCESS | 1682 | RSS: Main: 266268672 05:03:33 INFO - PROCESS | 1682 | 05:03:35 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:03:36 INFO - PROCESS | 1682 | RSS: Main: 266309632 05:03:36 INFO - PROCESS | 1682 | 05:03:38 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:03:39 INFO - PROCESS | 1682 | RSS: Main: 266362880 05:03:39 INFO - PROCESS | 1682 | 05:03:42 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:03:43 INFO - PROCESS | 1682 | RSS: Main: 266620928 05:03:43 INFO - PROCESS | 1682 | 05:03:46 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:03:47 INFO - PROCESS | 1682 | RSS: Main: 264429568 05:03:47 INFO - PROCESS | 1682 | 05:03:49 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49274/tests/kraken/driver.html?testName=ai-astar) 05:03:49 INFO - PROCESS | 1682 | RSS: Main: 265949184 05:03:49 INFO - PROCESS | 1682 | 05:03:49 INFO - PROCESS | 1682 | __start_tp_report 05:03:49 INFO - PROCESS | 1682 | _x_x_mozilla_page_load 05:03:49 INFO - PROCESS | 1682 | _x_x_mozilla_page_load_details 05:03:49 INFO - PROCESS | 1682 | |i|pagename|runs| 05:03:49 INFO - PROCESS | 1682 | |0;ai-astar;104;119;165;166;117;119;114;114;119;117 05:03:49 INFO - PROCESS | 1682 | |1;audio-beat-detection;158;131;116;152;119;118;118;139;144;117 05:03:49 INFO - PROCESS | 1682 | |2;audio-dft;232;306;221;201;196;199;207;201;196;200 05:03:49 INFO - PROCESS | 1682 | |3;audio-fft;80;60;60;85;59;60;62;86;109;76 05:03:49 INFO - PROCESS | 1682 | |4;audio-oscillator;87;71;88;73;69;72;89;70;73;84 05:03:49 INFO - PROCESS | 1682 | |5;imaging-gaussian-blur;89;87;89;87;89;85;90;86;92;87 05:03:49 INFO - PROCESS | 1682 | |6;imaging-darkroom;95;96;95;95;95;95;96;97;96;95 05:03:49 INFO - PROCESS | 1682 | |7;imaging-desaturate;90;94;109;91;95;90;91;95;91;91 05:03:49 INFO - PROCESS | 1682 | |8;json-parse-financial;57;55;55;57;59;58;57;57;57;61 05:03:49 INFO - PROCESS | 1682 | |9;json-stringify-tinderbox;69;75;70;109;93;106;105;109;70;74 05:03:49 INFO - PROCESS | 1682 | |10;stanford-crypto-aes;90;89;82;92;82;100;115;79;102;80 05:03:49 INFO - PROCESS | 1682 | |11;stanford-crypto-ccm;150;159;162;162;149;150;180;162;188;154 05:03:49 INFO - PROCESS | 1682 | |12;stanford-crypto-pbkdf2;179;198;184;223;241;199;237;178;242;240 05:03:49 INFO - PROCESS | 1682 | |13;stanford-crypto-sha256-iterative;71;71;72;84;89;90;70;67;91;89 05:03:49 INFO - PROCESS | 1682 | __end_tp_report 05:03:49 INFO - PROCESS | 1682 | __start_cc_report 05:03:49 INFO - PROCESS | 1682 | _x_x_mozilla_cycle_collect,954 05:03:49 INFO - PROCESS | 1682 | __end_cc_report 05:03:49 INFO - PROCESS | 1682 | __startTimestamp1475496229384__endTimestamp 05:03:49 INFO - PROCESS | 1682 | 05:03:49 INFO - PROCESS | 1682 | ------- Summary: start ------- 05:03:49 INFO - PROCESS | 1682 | Number of tests: 14 05:03:49 INFO - PROCESS | 1682 | 05:03:49 INFO - PROCESS | 1682 | [#0] ai-astar Cycles:10 Average:125.40 Median:119.00 stddev:21.60 (18.1%) stddev-sans-first:21.48 05:03:49 INFO - PROCESS | 1682 | Values: 104.0 119.0 165.0 166.0 117.0 119.0 114.0 114.0 119.0 117.0 05:03:49 INFO - PROCESS | 1682 | 05:03:49 INFO - PROCESS | 1682 | [#1] audio-beat-detection Cycles:10 Average:131.20 Median:135.00 stddev:16.01 (11.9%) stddev-sans-first:13.73 05:03:49 INFO - PROCESS | 1682 | Values: 158.0 131.0 116.0 152.0 119.0 118.0 118.0 139.0 144.0 117.0 05:03:49 INFO - PROCESS | 1682 | 05:03:49 INFO - PROCESS | 1682 | [#2] audio-dft Cycles:10 Average:215.90 Median:204.00 stddev:33.76 (16.5%) stddev-sans-first:35.30 05:03:49 INFO - PROCESS | 1682 | Values: 232.0 306.0 221.0 201.0 196.0 199.0 207.0 201.0 196.0 200.0 05:03:49 INFO - PROCESS | 1682 | 05:03:49 INFO - PROCESS | 1682 | [#3] audio-fft Cycles:10 Average:73.70 Median:69.00 stddev:16.62 (24.1%) stddev-sans-first:17.47 05:03:49 INFO - PROCESS | 1682 | Values: 80.0 60.0 60.0 85.0 59.0 60.0 62.0 86.0 109.0 76.0 05:03:49 INFO - PROCESS | 1682 | 05:03:49 INFO - PROCESS | 1682 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:78.50 stddev:8.28 (10.5%) stddev-sans-first:8.05 05:03:49 INFO - PROCESS | 1682 | Values: 87.0 71.0 88.0 73.0 69.0 72.0 89.0 70.0 73.0 84.0 05:03:49 INFO - PROCESS | 1682 | 05:03:49 INFO - PROCESS | 1682 | [#5] imaging-gaussian-blur Cycles:10 Average:88.10 Median:89.00 stddev:2.08 (2.3%) stddev-sans-first:2.18 05:03:49 INFO - PROCESS | 1682 | Values: 89.0 87.0 89.0 87.0 89.0 85.0 90.0 86.0 92.0 87.0 05:03:49 INFO - PROCESS | 1682 | 05:03:49 INFO - PROCESS | 1682 | [#6] imaging-darkroom Cycles:10 Average:95.50 Median:95.50 stddev:0.71 (0.7%) stddev-sans-first:0.73 05:03:49 INFO - PROCESS | 1682 | Values: 95.0 96.0 95.0 95.0 95.0 95.0 96.0 97.0 96.0 95.0 05:03:49 INFO - PROCESS | 1682 | 05:03:49 INFO - PROCESS | 1682 | [#7] imaging-desaturate Cycles:10 Average:93.70 Median:91.00 stddev:5.72 (6.3%) stddev-sans-first:5.90 05:03:49 INFO - PROCESS | 1682 | Values: 90.0 94.0 109.0 91.0 95.0 90.0 91.0 95.0 91.0 91.0 05:03:49 INFO - PROCESS | 1682 | 05:03:49 INFO - PROCESS | 1682 | [#8] json-parse-financial Cycles:10 Average:57.30 Median:57.00 stddev:1.77 (3.1%) stddev-sans-first:1.87 05:03:49 INFO - PROCESS | 1682 | Values: 57.0 55.0 55.0 57.0 59.0 58.0 57.0 57.0 57.0 61.0 05:03:49 INFO - PROCESS | 1682 | 05:03:49 INFO - PROCESS | 1682 | [#9] json-stringify-tinderbox Cycles:10 Average:88.00 Median:70.00 stddev:17.93 (25.6%) stddev-sans-first:17.65 05:03:49 INFO - PROCESS | 1682 | Values: 69.0 75.0 70.0 109.0 93.0 106.0 105.0 109.0 70.0 74.0 05:03:49 INFO - PROCESS | 1682 | 05:03:49 INFO - PROCESS | 1682 | [#10] stanford-crypto-aes Cycles:10 Average:91.10 Median:82.00 stddev:11.60 (14.1%) stddev-sans-first:12.30 05:03:49 INFO - PROCESS | 1682 | Values: 90.0 89.0 82.0 92.0 82.0 100.0 115.0 79.0 102.0 80.0 05:03:49 INFO - PROCESS | 1682 | 05:03:49 INFO - PROCESS | 1682 | [#11] stanford-crypto-ccm Cycles:10 Average:161.60 Median:162.00 stddev:13.03 (8.0%) stddev-sans-first:13.13 05:03:49 INFO - PROCESS | 1682 | Values: 150.0 159.0 162.0 162.0 149.0 150.0 180.0 162.0 188.0 154.0 05:03:49 INFO - PROCESS | 1682 | 05:03:49 INFO - PROCESS | 1682 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:212.10 Median:230.00 stddev:27.21 (11.8%) stddev-sans-first:26.09 05:03:49 INFO - PROCESS | 1682 | Values: 179.0 198.0 184.0 223.0 241.0 199.0 237.0 178.0 242.0 240.0 05:03:49 INFO - PROCESS | 1682 | 05:03:49 INFO - PROCESS | 1682 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.40 Median:86.50 stddev:9.95 (11.5%) stddev-sans-first:10.07 05:03:49 INFO - PROCESS | 1682 | Values: 71.0 71.0 72.0 84.0 89.0 90.0 70.0 67.0 91.0 89.0 05:03:49 INFO - PROCESS | 1682 | -------- Summary: end -------- 05:03:49 INFO - PROCESS | 1682 | 05:03:50 INFO - TEST-INFO | 1682: exit 0 05:03:50 INFO - TEST-OK | kraken | took 61597ms 05:03:50 INFO - SUITE-END | took 1177s 05:03:50 INFO - Completed test suite (00:19:37) 05:03:50 INFO - javascript benchmark 05:03:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7177.295894280221, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2955.9408811823764, 3424.657534246576, 3434.9141271468225, 3435.604905435874, 3474.7567670263084, 5698.658080515172, 5856.47291743743, 5877.206577112089, 5880.1767752514625, 5916.467517923386, 5641.18202860585, 5665.8583535411635, 5724.284464441945, 5756.568257380693, 5816.796412125576, 5206.114960456732, 5250.212468129773, 5250.212468129783, 5358.490943360374, 5372.355317361922, 4903.065565853319, 5366.785328586851, 5395.136778115502, 5409.945060713162, 5487.588430867681, 5197.272381866542, 5233.188855727369, 5336.51971322579, 5358.91961620576, 5369.463053694639, 5274.208868669692, 5286.127788914824, 5293.7617807198385, 5295.4969277918535, 5341.946580534183, 4294.785260736982, 4869.952960113593, 5235.816746413857, 5250.133727934886, 5272.525472707474, 4623.144718227105, 5703.31560212773, 5727.455891690324, 5751.798687045975, 5790.449907258803, 6153.815385538441, 6209.192804935375, 6227.657478838662, 6236.469900058501, 6241.719122639447, 6176.258848938156, 6228.190335256388, 6235.844103897393, 6239.532035097386, 6258.906116408258, 5868.4424979626865, 5899.439553242392, 5904.0, 5921.585489015813, 5960.254968128984, 6211.534134939807, 6230.283517395557, 6295.433410993032, 6315.810525684236, 6367.331430199855, 6174.753009879554, 6238.937610623836, 6245.18812554365, 6247.250329960434, 6262.780802671885, 6076.088586712029, 6259.906101408483, 6263.71813268404, 6276.968615156895, 6310.1796766420075, 6109.586862300737, 6195.2255968004, 6251.468625166821, 6274.0, 6288.402601752826, 6245.812625621239, 6277.24673039229, 6285.402886725754, 6300.873982520416, 6318.589291696025, 4994.3507344045765, 4999.325091112726, 5002.624803139779, 5009.674371165863, 5011.84964451067, 4408.360787685768, 4429.712068715523, 4433.733975961452, 4445.844395446144, 4501.3022981437925, 5067.771950262173, 5697.971510142423, 5980.581359304894, 5994.190784244071, 6035.547333949971, 6155.815325540241, 6224.128621992924, 6227.221597300338, 6229.844253893689, 6240.563160578716, 5870.559708021916, 5924.259467566554, 5924.7333869975955, 5995.730192141364, 6008.128821320884, 5429.67421954684, 5485.670859748428, 5504.7522861470525, 5541.6674999499355, 5560.749766260528, 6334.5565810393755, 6385.680715964183, 6389.456896163878, 6408.423241908251, 6455.064015717789, 5003.699778013331, 5049.57078648319, 5124.948750512447, 6196.3803619639475, 6231.189945307081, 5814.592978491465, 6065.181200537871, 6302.149209856794, 6310.400511951358, 6435.22777266731, 6181.041938499539, 6215.409536094064, 6229.813105606839, 6243.063540468785, 6272.937270627235, 6138.754449822136, 6278.434940855345, 6285.1515045470105, 6294.213223347082, 6303.338149494237, 5407.864803379947, 5977.462028417463, 6104.004449388181, 6114.844865410725, 6139.662318572521, 5108.029474399852, 5556.416576259435, 5769.365369809401, 6131.325554188947, 6166.321704612579, 5648.858778530405, 5923.992921203319, 6039.63762174271, 6045.909311360421, 6095.420935011167, 6128.228138398502, 6139.584134579464, 6198.2252218472695, 6214.875702485834, 6217.720202590985, 5277.656952298088, 5804.2744656917885, 6071.332153463204, 6076.1493390924425, 6105.419985101408], "value": 5697.758431023294, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.4019138755977, 506.05115408608856, 515.778215367392, 529.3171808367206, 554.9639273447226, 36994.63005369945, 37851.05372365692, 40415.0, 40431.99999999996, 40520.392194117114, 41953.37069943953, 42415.3637695434, 42482.36276455856, 42502.0, 42630.0, 9520.76198095048, 9723.611055557769, 9738.951305243463, 9764.951175244114, 9785.217182625373, 9592.760180995496, 9684.951575242114, 9686.46724430156, 9688.75778105546, 9700.854487182698, 9679.27405444591, 9709.174720148796, 9710.980347063563, 9733.0, 9735.464549449778, 9657.71026869195, 9693.321467497279, 9699.757506062335, 9717.90282097177, 9748.415095094282, 9592.66425675098, 9685.079917407906, 9700.75748106296, 9703.272254580934, 9718.854217186748, 30083.849580752176, 36224.73213437517, 37990.620093798985, 39540.406893896616, 40467.98830029243, 2818.408134291801, 2951.409718056376, 3089.4130115278026, 3484.337975784593, 3683.189698266377, 30823.69176308253, 31145.688543114506, 31519.527207091913, 32056.0, 32374.6762532374, 3111.533270009494, 3266.4283750343593, 3275.033865009828, 3323.9501407478906, 3385.6783605557434, 516183.0, 516633.0, 518692.0, 524013.0, 524306.0, 63982.68008659927, 64014.679926601, 64314.67842660757, 64381.71236575242, 64507.0, 54888.176677349875, 65498.0, 66034.66982665056, 66039.33960660428, 66341.0, 15929.761053584205, 16517.421890234025, 16671.333146674, 16713.832861671228, 16790.58023549421, 12804.231746095078, 12925.482980680856, 12940.805887911689, 12945.482180712666, 12948.741025179444, 17338.91330543339, 17448.47654570365, 18520.629587408177, 18580.163892624863, 19344.936028518565, 7595.3164215220895, 7649.808754781064, 7725.343345815556, 7794.3764498839955, 7799.025121859768, 698.5958223969756, 954.8615450759601, 997.8104160209537, 1017.3539802225492, 1029.1920842138884, 29565.704342956724, 30007.0, 30060.69939300579, 30769.846150769103, 31117.222069448446, 873.2402809555728, 1017.6896046705825, 1078.5847448732138, 1096.276457538021, 1126.9380184089953, 15242.390304387922, 15617.141057241734, 15633.687326253412, 15713.607159821097, 15884.60288492797, 730571.0, 737860.6604504683, 739908.0, 741805.0, 742115.0, 57900.710496447246, 58330.708346458, 58384.70807645935, 58485.0, 58687.70656146692, 1317.2754984758344, 1324.735052989406, 1361.46902707942, 1364.379207460603, 1369.7465968795805, 28398.43203135967, 29312.0, 29451.558226626188, 29852.0, 29966.70033299639, 50410.74794626003, 50653.227137050024, 50707.74646126894, 50827.23759143542, 50857.74571127121, 599008.0, 602388.0, 603993.0, 604540.0, 605502.0, 26634.600480993187, 26745.33136671599, 26773.732262677902, 27173.0, 27293.181204563894, 45261.54738452574, 46071.0, 46472.444015579465, 46941.0, 47646.0, 38303.808480957414, 38569.42145867758, 38861.6113838858, 39846.0, 40182.59817401788, 37267.25465490621, 38297.808510957264, 39028.41457378085, 39371.606283936795, 40704.796476018615, 32510.51234231441, 33025.66974330226, 35919.82040089783, 38521.22957540889, 38849.41725874058, 31888.681113189497, 33218.003459896245, 33702.66297336995, 34010.0, 34104.31791364209, 20623.690644640043, 25477.745222548278, 25660.871695641403, 25703.485930281662, 25861.870690646425], "value": 20076.98890705624, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.5649776765647, 367.82712125301106, 375.7707798243071, 379.79870668545647, 398.01689826129467, 17341.91329043356, 18827.811145141397, 20184.587078904482, 20448.0, 21038.263660771896, 15985.520434386957, 16726.0, 17020.48938531846, 19703.817770933754, 20101.0, 5897.4692277695, 5932.169496270515, 6201.503879689614, 6206.441420272175, 6209.751609935597, 5975.52195824333, 6119.908201376983, 6127.35662755411, 6159.7536098556, 6164.969175154118, 4805.303231031498, 6034.69826508675, 6060.818175454743, 6101.298350689661, 6123.357047509992, 6109.969450152743, 6168.969155154218, 6171.259448866164, 6171.444569988723, 6183.505319574423, 5143.12566863634, 5416.566674666017, 5474.7810087596445, 5512.889742205173, 6005.309389420229, 12648.55730049453, 12775.23348599087, 12800.48798048077, 13007.934960325138, 13008.349582520836, 2108.325335892515, 2216.710971184737, 2255.6912826207554, 2296.437372843647, 2381.5117900830287, 9702.417854928726, 10248.64129755455, 13063.86936130636, 14809.92595037018, 15479.5356139316, 1799.199356286453, 1893.8958357290344, 1901.6767149584605, 1921.625283069802, 1922.3406212551436, 107755.45301384291, 109007.90992090057, 121480.0, 121719.0, 124365.0, 11501.309921404743, 11510.194286399872, 11553.653390398302, 11687.415629218674, 11787.941060294643, 14088.647783805282, 14091.647708807363, 14096.647583810487, 14114.294285285694, 14154.079984800956, 8276.751697449086, 8426.873596896052, 8531.872021919677, 8547.888774459405, 8635.6977505787, 7015.719371225094, 8465.0, 8613.353998450142, 8695.304375649934, 8701.347398945105, 4448.221561226773, 4489.416375871116, 4537.659675524349, 4618.930716039262, 4626.398568186181, 1933.825955663969, 1961.8626696131134, 1965.4571161638046, 1999.4201681512197, 2015.0932080563805, 841.7895526118471, 865.2861389353808, 865.6710450029075, 879.8680197970356, 946.5456580841235, 20037.799622003593, 20544.0, 21045.0, 21050.0, 21221.15115395398, 880.2693764175718, 953.7377221264207, 954.5513608603945, 966.4104896013426, 1004.5881188712593, 10668.0, 10872.734846906826, 11036.944815275872, 11076.11391088711, 11099.44502774858, 482018.0, 559675.0, 559715.0, 591017.0, 605285.0, 121641.0, 122378.0, 124322.0, 125772.0, 125828.0, 829.344817594106, 958.8226178157063, 962.3744566031995, 985.5269470653983, 988.6243227573476, 17962.730559041887, 17995.460136195936, 18079.909600451912, 18085.377620245254, 18138.637227255644, 43908.78045609879, 54211.72894135504, 54453.1832022512, 54618.18072728992, 55338.0, 72277.55444891177, 74704.62647686727, 75109.0, 75520.86718699113, 76677.0, 6786.60874520732, 7625.428092892944, 7914.24814642608, 8046.0747014091785, 8062.911506946487, 10196.541155647865, 10767.569297228023, 12003.219790713576, 12403.93798031004, 12528.248305101723, 9863.605455781688, 10225.744356390853, 10327.845082323622, 10619.840702389316, 10762.300450470695, 10619.840702389316, 10797.838032429363, 10859.402732849769, 11112.277701949348, 11335.0, 10942.124630029737, 11297.661070167907, 11314.547418103184, 11378.0, 11419.771604568028, 7348.619634967761, 7880.369570434237, 8051.677932882853, 8114.756557303291, 8304.418690691593, 8245.175482451707, 10332.070113689804, 10698.358098513803, 11073.280236784583, 11183.539621283304], "value": 9278.792848406889, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1359.2388262572958, 1497.8577035181654, 1683.6548507555945, 1722.8793984421095, 1872.6067525819576, 7983.203779149689, 8271.172882711726, 8477.576121193939, 9069.637214511427, 9081.682141125062, 8482.745517634465, 8774.605142768574, 8815.250703690193, 8894.91105088949, 8915.910840891589, 2314.2710046335433, 2332.265336419031, 2334.159702507096, 2343.261872510154, 2344.9296521104393, 2309.7921187093243, 2330.254318618043, 2364.314348838843, 2367.7868991790733, 2373.4185124644487, 2339.251439539348, 2346.756219203825, 2350.2949115265437, 2357.669926210332, 2358.1982126077132, 2312.791848733605, 2322.094383190549, 2326.8254880883915, 2345.8475199112004, 2347.4835536182013, 2282.0643536150264, 2329.662198981155, 2335.719713634358, 2337.5324935012895, 2346.425125844162, 8885.42244754089, 8965.0, 8968.058353872815, 8974.955125224398, 9018.278537716968, 5188.584913206934, 5215.956808638288, 5306.681599104066, 5369.704666243355, 5376.247325374412, 3390.4575267957007, 3537.964620353815, 3598.082488965323, 3715.20123173519, 3720.497732806091, 1051.0382999555397, 1080.6920027792041, 1087.152021423291, 1088.4448931045144, 1101.2291396022736, 11740.412979350998, 11837.585684501, 11882.881171188264, 11894.405279736065, 11896.22674526153, 4482.484514280834, 4703.623710103184, 4771.880702982384, 4829.140780799417, 4836.516348365206, 10023.849642255373, 10088.949555252177, 10105.343152695052, 10123.240756943258, 10139.695809125738, 4078.3678529827926, 4309.676774241944, 4542.613877820356, 5099.0056938897305, 5322.733863306819, 3529.7529172957647, 4257.212415703105, 4719.59883409914, 4841.273808928689, 4854.126257273725, 3885.805709714503, 3919.8824035278985, 3997.5608780838875, 4054.432379466877, 4061.837526498966, 1268.3658170914543, 1273.7834568123442, 1275.279467101085, 1291.599604122725, 1295.956754812385, 750.6659536506203, 874.7681864305964, 884.730157302029, 893.3166127912152, 896.5562046786752, 5718.1136923776885, 5737.713114344266, 5862.020738846438, 5941.762329506858, 5960.493358064613, 368.5522090659861, 380.3477036881761, 382.7110531548663, 390.6874500399726, 391.48910671573867, 3162.7469802415753, 3183.936321273608, 3277.4919887417486, 3287.375398674244, 3305.0910999474854, 10648.893511064945, 10881.12950963921, 10909.0, 11011.889881101246, 11073.394357818072, 10979.670609881716, 10981.341119532854, 10986.230963832455, 10989.835152472719, 11051.115910727123, 8053.0, 8102.554359510284, 8241.83516329682, 8368.832623347498, 8381.664733410595, 5289.576833853205, 5815.883682326245, 5846.6784326862435, 5855.677937713466, 5889.293284805851, 8390.496570205807, 9223.76940576464, 9975.002499750257, 9979.101880830469, 10060.346077504939, 9491.38306010107, 10092.848607271044, 10331.793364132525, 10386.0, 10422.853486116674, 6960.860782784417, 7162.713491460491, 7268.127824661075, 7502.137254215835, 7574.3183113520045, 8270.627821747916, 8502.78743031429, 8508.61711222984, 8527.18991695788, 8546.871796922927, 6225.439710426083, 7420.220876807857, 7549.471536992358, 7586.203448637813, 7687.154413014676, 6534.771283005072, 7383.483156179016, 7426.777196684108, 7445.181030086795, 7599.620018998918, 6931.549449285982, 6947.40947019509, 7033.156021277479, 7036.718531258693, 7037.648117594203, 4940.777665005012, 5003.174476211384, 5047.87380315495, 5048.72232027242, 5054.69671819692, 4870.707757534559, 4936.111499930047, 5050.3434553507805, 5148.794048238028, 5151.098557752303], "value": 4430.752692427537, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.8535524915167, 305.4151300260002, 311.0543946402934, 315.80420139513507, 317.6489978573676, 5064.468230835764, 5342.252084708144, 5539.27989361383, 5616.522595579376, 5672.68800215988, 4796.5537575836315, 4882.389701287339, 5327.893442131155, 5553.527950124234, 5685.061964775818, 3419.6580341965855, 3580.6598373154507, 4270.188664153801, 4270.444842170514, 4284.271673815459, 3920.8823735287842, 4170.958290417103, 4294.720843145201, 4306.461692288464, 4310.4396428464415, 3647.94528082079, 4049.969476332987, 4173.686973476986, 4198.391233271173, 4250.0, 3856.67218286446, 4201.43280657112, 4201.487464512788, 4218.514870789868, 4247.5540068293, 3385.8814941477176, 3648.1426864686778, 3665.6334366563397, 3698.68561172298, 3712.9071773205615, 5271.710055946922, 5407.215953686733, 5452.345718513764, 5461.42655021225, 5498.80754173606, 4037.1320166164414, 4311.50417701962, 4426.55734426558, 4604.2633178691585, 4863.683860549088, 4952.975235123838, 5159.896802063975, 5880.7353669085, 5893.705314734246, 5960.254968128984, 3812.2756676231706, 4021.2761702893467, 4032.3145065338963, 4054.716169868081, 4062.8577999770164, 5796.652200867919, 5969.880602387928, 6168.75324987, 6169.321374648785, 6183.0, 3273.639899611041, 3982.1637456134536, 4084.0, 4089.5297040840087, 4100.712950093465, 3723.7525428981053, 4166.187593419315, 4171.33258678616, 4181.452862440856, 4192.350185721218, 4262.339337402707, 4278.44380230568, 4470.150671372494, 4817.8313759018965, 4853.296272040534, 5002.849914502571, 5381.327334083239, 5405.70268635221, 5860.505571079433, 5947.851303717442, 1221.3709939381226, 1286.3825363825417, 1308.9280089595163, 1417.4046900301898, 1424.7577911755027, 739.9123288765506, 743.4498471131324, 823.4524041512447, 857.439460182462, 877.8902637170354, 4075.898102547401, 4186.116247688552, 4498.143678764009, 4501.887452813706, 4622.86131416058, 4396.868093957187, 4687.999680204672, 4838.491958344393, 4997.525235102659, 5039.244113383022, 4111.8560850370095, 4287.97856010718, 4491.158624963749, 4585.266357382803, 4611.8847028823875, 2094.5706130243266, 2479.5866356176807, 2512.7361627029263, 2578.414275220763, 2649.5893136563864, 4425.760786979651, 4715.834945776881, 4741.478437371887, 5114.462981386975, 5177.844664660066, 4289.656827453796, 4665.020345727436, 4684.5549672781035, 4845.563899249085, 5017.197248440232, 4808.61531077513, 4828.589569886599, 5110.054639891632, 5153.948460515422, 5160.058766503057, 4020.7133717210213, 4235.851745188904, 4297.205017071852, 4686.531346865286, 4821.9035619286715, 4521.299198624219, 4776.9729508155915, 4898.583620392164, 4942.110420124268, 4999.375078115236, 4409.625181859577, 4578.816847326203, 4583.770811459347, 5090.803661139703, 5136.845894623168, 3494.371013217645, 3515.1739341255297, 3557.4485954677066, 3567.304375646724, 3587.7393947561673, 4301.483821941262, 4677.292788132375, 4868.829590964299, 5026.723530205873, 5049.520295571915, 4503.504614492469, 4513.4273464126445, 4804.453639571033, 4899.951000490092, 4925.285833554186, 4770.522947705202, 4774.641901857444, 4810.927836082532, 4844.806207751651, 4858.271259311132, 4180.703981765662, 4229.492460904711, 4299.290617048277, 4550.34020067095, 4724.48030716614, 3316.17238295503, 4116.835326586903, 4170.687198460067, 4668.743219122845, 4746.33551302811, 3831.429114063221, 3970.543387510473, 4134.472752336187, 4672.813087476463, 4738.455077665974], "value": 3832.4713013453725, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [702.6205848841626, 724.3589423360327, 759.3469616130124, 771.23647588887, 812.560458367438, 14807.925960370196, 15937.123458209793, 15966.920165399186, 15991.84008159918, 16005.75991360129, 14309.356078976445, 14313.71372572548, 14424.711505769877, 14458.927705361484, 14473.927630361859, 696.6690821859602, 850.6554845287668, 865.5888452984844, 876.5529579914257, 879.3361012435602, 709.2871663977695, 853.138330286409, 867.9566021698921, 879.8504254276788, 895.2256298301954, 909.6770646420524, 924.2790623313826, 924.3852837862813, 925.4863550729342, 930.2000279759402, 889.617464490269, 914.4330515080626, 918.1002617434951, 921.1295325917011, 921.9907800921972, 637.8724255148989, 651.4202359899693, 673.1148539670318, 688.7141836138047, 691.0670594697169, 14606.561803145923, 14766.409343626243, 14790.186539740418, 14812.0, 14855.851441485554, 14299.285035748171, 14579.489717859824, 14634.341454634567, 14649.780253296209, 14863.70272594542, 11940.164188506807, 12017.0, 12036.398180091048, 12239.388030598524, 12424.440900159514, 3837.5586807517216, 3869.496965394509, 3891.8054097295303, 4020.3768415895584, 4025.5370632377358, 118747.40626296813, 120447.3977630115, 120776.48740932516, 121605.0, 122384.0, 11153.944230278797, 11256.606018789304, 11322.830157547643, 11375.31748095117, 11589.478473468545, 23080.422989425064, 23116.19093331725, 23123.884380578325, 23182.840857957035, 23206.76793232046, 7779.338756205672, 7969.72105976288, 8001.479903806234, 8007.79883017552, 8054.556999364974, 4449.354843547732, 4482.170798402306, 4496.797644106023, 4500.414946057057, 4639.188142075123, 3533.9293214135573, 3575.481555174485, 3580.946285805715, 3595.7123430125525, 3651.744377893522, 647.1780838335288, 651.3681728723132, 653.4576301669603, 654.5254690349459, 664.3622122762106, 589.9793507227226, 629.9748010079637, 656.4813797100333, 661.4014317043084, 676.681959479044, 9154.450732956044, 9302.720918372459, 9348.0, 9365.812683746288, 9466.0, 571.7455732199214, 588.2352941176454, 589.2130224040228, 632.3929028133044, 667.6494840208832, 4370.38814565961, 4489.708168969007, 4610.596879979678, 4614.792334344896, 4687.67186296956, 120053.19920201204, 125513.0, 152309.0, 158450.0, 179235.0, 82792.58603706943, 107132.0, 107936.46031769791, 114620.0, 136049.0, 70681.46592670346, 72555.0, 73321.26678733145, 104281.47859260807, 106752.46623766831, 7189.964050179716, 9711.36876103051, 9744.512774361396, 9890.950545247515, 10053.547590358305, 48461.75769121132, 48759.02481950412, 53931.191032135335, 54214.0, 55094.44905550893, 54156.0, 97114.5144274274, 98312.0, 99953.00046999437, 102168.48915755373, 11611.013063889664, 11615.883841161818, 11620.302781833117, 11646.24299420535, 11886.049116070866, 12325.75348493043, 12444.329697282845, 12533.310667912987, 12548.18436801605, 12838.614841554769, 11854.52581896749, 11909.106816988933, 12018.519259229883, 12384.81422778677, 12395.380230988594, 11875.584354547549, 12010.039196864054, 12057.51769929193, 12100.939495302468, 12439.378031098227, 11412.429378530875, 11858.466369013242, 11939.582114625946, 11950.646181922652, 12080.275183489019, 9909.355891867006, 10009.649662262078, 10011.599536018477, 10045.799084018132, 10113.494325283853, 8727.78180545466, 9709.854352184864, 10228.28402011852, 10228.977102289711, 10324.845127322946], "value": 7583.407601728125, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1590.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 119.0, 165.0, 166.0, 117.0, 119.0, 114.0, 114.0, 119.0, 117.0], "value": 125.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 131.0, 116.0, 152.0, 119.0, 118.0, 118.0, 139.0, 144.0, 117.0], "value": 131.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [232.0, 306.0, 221.0, 201.0, 196.0, 199.0, 207.0, 201.0, 196.0, 200.0], "value": 215.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 60.0, 60.0, 85.0, 59.0, 60.0, 62.0, 86.0, 109.0, 76.0], "value": 73.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 88.0, 73.0, 69.0, 72.0, 89.0, 70.0, 73.0, 84.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 89.0, 87.0, 89.0, 85.0, 90.0, 86.0, 92.0, 87.0], "value": 88.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 97.0, 96.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 94.0, 109.0, 91.0, 95.0, 90.0, 91.0, 95.0, 91.0, 91.0], "value": 93.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 55.0, 57.0, 59.0, 58.0, 57.0, 57.0, 57.0, 61.0], "value": 57.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 75.0, 70.0, 109.0, 93.0, 106.0, 105.0, 109.0, 70.0, 74.0], "value": 88.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 82.0, 92.0, 82.0, 100.0, 115.0, 79.0, 102.0, 80.0], "value": 91.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 159.0, 162.0, 162.0, 149.0, 150.0, 180.0, 162.0, 188.0, 154.0], "value": 161.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 198.0, 184.0, 223.0, 241.0, 199.0, 237.0, 178.0, 242.0, 240.0], "value": 212.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 71.0, 72.0, 84.0, 89.0, 90.0, 70.0, 67.0, 91.0, 89.0], "value": 79.4, "unit": "score"}]}]} 05:03:50 INFO - Return code: 0 05:03:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:03:50 ERROR - # TBPL SUCCESS # 05:03:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:03:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:03:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:03:50 INFO - 2016-10-03 05:03:50.685 screenresolution[1687:11961] starting screenresolution argv=screenresolution get 05:03:50 INFO - 2016-10-03 05:03:50.688 screenresolution[1687:11961] Display 0: 1600x1200x32@60 05:03:50 INFO - 2016-10-03 05:03:50.694 screenresolution[1688:11964] starting screenresolution argv=screenresolution list 05:03:50 INFO - Available Modes on Display 0 05:03:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:03:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:03:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:03:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:03:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:03:50 INFO - Intel Iris: 05:03:50 INFO - Chipset Model: Intel Iris 05:03:50 INFO - Type: GPU 05:03:50 INFO - Bus: Built-In 05:03:50 INFO - VRAM (Dynamic, Max): 1536 MB 05:03:50 INFO - Vendor: Intel (0x8086) 05:03:50 INFO - Device ID: 0x0a2e 05:03:50 INFO - Revision ID: 0x0009 05:03:50 INFO - Displays: 05:03:50 INFO - Display: 05:03:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:03:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:03:50 INFO - Display Serial Number: 1600x1200 60 05:03:50 INFO - Main Display: Yes 05:03:50 INFO - Mirror: Off 05:03:50 INFO - Online: Yes 05:03:50 INFO - Rotation: Supported 05:03:50 INFO - Return code: 0 05:03:50 INFO - Running post-action listener: _resource_record_post_action 05:03:50 INFO - [mozharness: 2016-10-03 12:03:50.950519Z] Finished run-tests step (success) 05:03:50 INFO - Running post-run listener: _resource_record_post_run 05:03:51 INFO - Total resource usage - Wall time: 1213s; CPU: 55.0%; Read bytes: 34525184; Write bytes: 713227776; Read time: 326; Write time: 2026 05:03:51 INFO - TinderboxPrint: CPU usage
54.9% 05:03:51 INFO - TinderboxPrint: I/O read bytes / time
34,525,184 / 326 05:03:51 INFO - TinderboxPrint: I/O write bytes / time
713,227,776 / 2,026 05:03:51 INFO - TinderboxPrint: CPU idle
2,188.1 (45.1%) 05:03:51 INFO - TinderboxPrint: CPU system
987.0 (20.3%) 05:03:51 INFO - TinderboxPrint: CPU user
1,678.6 (34.6%) 05:03:51 INFO - TinderboxPrint: Swap in / out
438,603,776 / 0 05:03:51 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 389652992; Write bytes: 383211520; Read time: 29363; Write time: 694 05:03:51 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 33595392; Write bytes: 303613440; Read time: 312; Write time: 1277 05:03:51 INFO - Running post-run listener: _upload_blobber_files 05:03:51 INFO - Blob upload gear active. 05:03:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:03:51 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 05:03:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:03:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:03:51 INFO - (blobuploader) - INFO - Open directory for files ... 05:03:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:03:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:03:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:03:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:03:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:03:52 INFO - (blobuploader) - INFO - Done attempting. 05:03:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:03:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:03:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:03:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:03:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:03:53 INFO - (blobuploader) - INFO - Done attempting. 05:03:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:03:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:03:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:03:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:03:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:03:56 INFO - (blobuploader) - INFO - Done attempting. 05:03:56 INFO - (blobuploader) - INFO - Iteration through files over. 05:03:56 INFO - Return code: 0 05:03:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:03:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:03:56 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f24013c271c2f836b1276e6d23b0ead040ac0e885e902a0434bb9f6dfc70a96c16948fe4f7d7c57d96e65539463765294a529eafb9a49ebbf072cab07c58d8ea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c7ff59bef85ffeab34734e6f2fa4fc74e00945b56f941b8e702b094a332de67b4a5d2f2af67e245d7dd30150cacf986dd30652e8004cbb4a5f53708420ed8af", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e5e2e257bfe8bbe2eb32077216aea0aab458b1f528f7ce8b316eaa0802b708b3a3e0a5bce68cd6a368680d376340c370fcf0a9e1ddb0ff1d90bf8e37ee893d0e"} 05:03:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:03:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:03:56 INFO - Contents: 05:03:56 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f24013c271c2f836b1276e6d23b0ead040ac0e885e902a0434bb9f6dfc70a96c16948fe4f7d7c57d96e65539463765294a529eafb9a49ebbf072cab07c58d8ea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c7ff59bef85ffeab34734e6f2fa4fc74e00945b56f941b8e702b094a332de67b4a5d2f2af67e245d7dd30150cacf986dd30652e8004cbb4a5f53708420ed8af", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e5e2e257bfe8bbe2eb32077216aea0aab458b1f528f7ce8b316eaa0802b708b3a3e0a5bce68cd6a368680d376340c370fcf0a9e1ddb0ff1d90bf8e37ee893d0e"} 05:03:56 INFO - Running post-run listener: copy_logs_to_upload_dir 05:03:56 INFO - Copying logs to upload dir... 05:03:56 INFO - mkdir: /builds/slave/test/build/upload/logs 05:03:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.622985 ========= master_lag: 2.73 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-10-03 05:03:58.923416) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-03 05:03:58.927344) ========= 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.vzifQsTBou/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.diw2i6RTuY/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f24013c271c2f836b1276e6d23b0ead040ac0e885e902a0434bb9f6dfc70a96c16948fe4f7d7c57d96e65539463765294a529eafb9a49ebbf072cab07c58d8ea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c7ff59bef85ffeab34734e6f2fa4fc74e00945b56f941b8e702b094a332de67b4a5d2f2af67e245d7dd30150cacf986dd30652e8004cbb4a5f53708420ed8af", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e5e2e257bfe8bbe2eb32077216aea0aab458b1f528f7ce8b316eaa0802b708b3a3e0a5bce68cd6a368680d376340c370fcf0a9e1ddb0ff1d90bf8e37ee893d0e"} build_url:https://queue.taskcluster.net/v1/task/HWkL42RrSsidisVAntOtNg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011115 build_url: 'https://queue.taskcluster.net/v1/task/HWkL42RrSsidisVAntOtNg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f24013c271c2f836b1276e6d23b0ead040ac0e885e902a0434bb9f6dfc70a96c16948fe4f7d7c57d96e65539463765294a529eafb9a49ebbf072cab07c58d8ea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c7ff59bef85ffeab34734e6f2fa4fc74e00945b56f941b8e702b094a332de67b4a5d2f2af67e245d7dd30150cacf986dd30652e8004cbb4a5f53708420ed8af", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e5e2e257bfe8bbe2eb32077216aea0aab458b1f528f7ce8b316eaa0802b708b3a3e0a5bce68cd6a368680d376340c370fcf0a9e1ddb0ff1d90bf8e37ee893d0e"}' ========= master_lag: 0.19 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-03 05:03:59.124520) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 05:03:59.124851) ========= 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.vzifQsTBou/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.diw2i6RTuY/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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.005116 ========= master_lag: 0.40 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 05:03:59.532520) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-03 05:03:59.532794) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-03 05:03:59.623877) ========= ========= Total master_lag: 3.39 =========