builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0119 starttime: 1477399499.61 results: success (0) buildid: 20161025024804 builduid: 1cae3571d48b404bbf371ce3bc3f4d92 revision: 523d47a7f8f38c271a2d5cf4fb9cbdbad1036e3b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-25 05:44:59.611281) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-25 05:44:59.611855) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-25 05:44:59.612302) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-25 05:44:59.634941) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 05:44:59.635432) ========= 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.kovZ4aggJx/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.ON90of9EUb/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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-25 05:44:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.14M=0.001s 2016-10-25 05:44:59 (8.14 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.083573 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 05:44:59.741948) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 05:44:59.742408) ========= 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.kovZ4aggJx/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.ON90of9EUb/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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.020449 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 05:44:59.790747) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-25 05:44:59.791096) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 523d47a7f8f38c271a2d5cf4fb9cbdbad1036e3b --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 523d47a7f8f38c271a2d5cf4fb9cbdbad1036e3b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kovZ4aggJx/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.ON90of9EUb/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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-25 05:44:59,862 truncating revision to first 12 chars 2016-10-25 05:44:59,862 Setting DEBUG logging. 2016-10-25 05:44:59,862 attempt 1/10 2016-10-25 05:44:59,862 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/523d47a7f8f3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-25 05:45:01,291 unpacking tar archive at: mozilla-central-523d47a7f8f3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.686825 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-25 05:45:01.500686) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-25 05:45:01.501023) ========= script_repo_revision: 523d47a7f8f38c271a2d5cf4fb9cbdbad1036e3b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-25 05:45:01.501437) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-25 05:45:01.501795) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-25 05:45:01.520000) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-10-25 05:45:01.520416) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kovZ4aggJx/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.ON90of9EUb/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 05:45:01 INFO - MultiFileLogger online at 20161025 05:45:01 in /builds/slave/test 05:45:01 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 05:45:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:45:01 INFO - {'append_to_log': False, 05:45:01 INFO - 'base_work_dir': '/builds/slave/test', 05:45:01 INFO - 'blob_upload_branch': 'Firefox', 05:45:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:45:01 INFO - 'branch': 'Firefox', 05:45:01 INFO - 'buildbot_json_path': 'buildprops.json', 05:45:01 INFO - 'config_files': ('talos/mac_config.py',), 05:45:01 INFO - 'default_actions': ('clobber', 05:45:01 INFO - 'read-buildbot-config', 05:45:01 INFO - 'download-and-extract', 05:45:01 INFO - 'populate-webroot', 05:45:01 INFO - 'create-virtualenv', 05:45:01 INFO - 'install', 05:45:01 INFO - 'run-tests'), 05:45:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:45:01 INFO - 'download_minidump_stackwalk': True, 05:45:01 INFO - 'download_symbols': 'ondemand', 05:45:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:45:01 INFO - 'tooltool.py': '/tools/tooltool.py', 05:45:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:45:01 INFO - '/tools/misc-python/virtualenv.py')}, 05:45:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:45:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:45:01 INFO - 'installer_path': 'installer.exe', 05:45:01 INFO - 'log_level': 'info', 05:45:01 INFO - 'log_name': 'talos', 05:45:01 INFO - 'log_to_console': True, 05:45:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:45:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:45:01 INFO - 'opt_config_files': (), 05:45:01 INFO - 'pip_index': False, 05:45:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:45:01 INFO - 'cmd': ('bash', 05:45:01 INFO - '-c', 05:45:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:45:01 INFO - 'enabled': True, 05:45:01 INFO - 'halt_on_failure': False, 05:45:01 INFO - 'name': 'check_screen_resolution'},), 05:45:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:45:01 INFO - 'cmd': ('bash', 05:45:01 INFO - '-c', 05:45:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:45:01 INFO - 'enabled': True, 05:45:01 INFO - 'halt_on_failure': False, 05:45:01 INFO - 'name': 'check_screen_resolution'},), 05:45:01 INFO - 'run_cmd_checks_enabled': True, 05:45:01 INFO - 'sps_profile': False, 05:45:01 INFO - 'sps_profile_interval': 0, 05:45:01 INFO - 'suite': 'dromaeojs-e10s', 05:45:01 INFO - 'system_bits': '32', 05:45:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:45:01 INFO - 'title': 't-yosemite-r7-0119', 05:45:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:45:01 INFO - 'use_talos_json': True, 05:45:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:45:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:45:01 INFO - 'work_dir': 'build'} 05:45:01 INFO - [mozharness: 2016-10-25 12:45:01.717592Z] Running clobber step. 05:45:01 INFO - Running pre-action listener: _resource_record_pre_action 05:45:01 INFO - Running main action method: clobber 05:45:01 INFO - rmtree: /builds/slave/test/build 05:45:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:45:03 INFO - Running post-action listener: _resource_record_post_action 05:45:03 INFO - [mozharness: 2016-10-25 12:45:03.262399Z] Finished clobber step (success) 05:45:03 INFO - [mozharness: 2016-10-25 12:45:03.262614Z] Running read-buildbot-config step. 05:45:03 INFO - Running pre-action listener: _resource_record_pre_action 05:45:03 INFO - Running main action method: read_buildbot_config 05:45:03 INFO - Using buildbot properties: 05:45:03 INFO - { 05:45:03 INFO - "project": "", 05:45:03 INFO - "product": "firefox", 05:45:03 INFO - "script_repo_revision": "production", 05:45:03 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:45:03 INFO - "repository": "", 05:45:03 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:45:03 INFO - "buildid": "20161025024804", 05:45:03 INFO - "pgo_build": "False", 05:45:03 INFO - "basedir": "/builds/slave/test", 05:45:03 INFO - "buildnumber": 13, 05:45:03 INFO - "slavename": "t-yosemite-r7-0119", 05:45:03 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 05:45:03 INFO - "platform": "yosemite_r7", 05:45:03 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:45:03 INFO - "branch": "mozilla-central", 05:45:03 INFO - "repo_path": "mozilla-central", 05:45:03 INFO - "revision": "523d47a7f8f38c271a2d5cf4fb9cbdbad1036e3b", 05:45:03 INFO - "stage_platform": "macosx64", 05:45:03 INFO - "builduid": "1cae3571d48b404bbf371ce3bc3f4d92", 05:45:03 INFO - "slavebuilddir": "test" 05:45:03 INFO - } 05:45:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/ALkgmStASCaRckuCrXipNA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 05:45:03 INFO - Running post-action listener: _resource_record_post_action 05:45:03 INFO - [mozharness: 2016-10-25 12:45:03.268349Z] Finished read-buildbot-config step (success) 05:45:03 INFO - [mozharness: 2016-10-25 12:45:03.268572Z] Running download-and-extract step. 05:45:03 INFO - Running pre-action listener: _resource_record_pre_action 05:45:03 INFO - Running main action method: download_and_extract 05:45:03 INFO - mkdir: /builds/slave/test/build/tests 05:45:03 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')]} 05:45:03 INFO - trying https://queue.taskcluster.net/v1/task/ALkgmStASCaRckuCrXipNA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 05:45:03 INFO - Downloading https://queue.taskcluster.net/v1/task/ALkgmStASCaRckuCrXipNA/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 05:45:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ALkgmStASCaRckuCrXipNA/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 05:45:04 INFO - Downloaded 1199 bytes. 05:45:04 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:45:04 INFO - Using the following test package requirements: 05:45:04 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:45:04 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:45:04 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 05:45:04 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:45:04 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 05:45:04 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:45:04 INFO - u'jsshell-mac.zip'], 05:45:04 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:45:04 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 05:45:04 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:45:04 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:45:04 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 05:45:04 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:45:04 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 05:45:04 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:45:04 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 05:45:04 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:45:04 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 05:45:04 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:45:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ALkgmStASCaRckuCrXipNA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:45:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ALkgmStASCaRckuCrXipNA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:45:04 INFO - Fetch https://queue.taskcluster.net/v1/task/ALkgmStASCaRckuCrXipNA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:45:04 INFO - Http code: 200 05:45:04 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 05:45:04 INFO - x-amz-cf-id: HChn5ieNYR8Q-H2vKqV4bQUOB6CPuI7iVJw_n7OSOh1pN8YFW3cgLA== 05:45:04 INFO - x-amz-version-id: tRdLeoUaZ3apl0xDjZ2Vub4gN5M0mF0p 05:45:05 INFO - Expected file size: 16423377 05:45:05 INFO - Obtained file size: 16423377 05:45:11 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 05:45:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ALkgmStASCaRckuCrXipNA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:45:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ALkgmStASCaRckuCrXipNA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:45:11 INFO - Fetch https://queue.taskcluster.net/v1/task/ALkgmStASCaRckuCrXipNA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:45:12 INFO - Http code: 200 05:45:12 INFO - via: 1.1 ccaa055c9a26ce13da709353563e04a5.cloudfront.net (CloudFront) 05:45:12 INFO - x-amz-cf-id: RLeBzRLLV07HSROGNA3I9S5aSzN33y-n7tlDm3V8g35Yn7huEvNf2Q== 05:45:12 INFO - x-amz-version-id: tRdLeoUaZ3apl0xDjZ2Vub4gN5M0mF0p 05:45:12 INFO - Expected file size: 16423377 05:45:12 INFO - Obtained file size: 16423377 05:45:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ALkgmStASCaRckuCrXipNA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 05:45:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ALkgmStASCaRckuCrXipNA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:45:19 INFO - Fetch https://queue.taskcluster.net/v1/task/ALkgmStASCaRckuCrXipNA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 05:45:20 INFO - Http code: 200 05:45:20 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront) 05:45:20 INFO - x-amz-cf-id: fqv4fuZeg1Gs7gwJXx5R7HhTyO1qrBBGeP3N564dGBbwqeN8I5tlTg== 05:45:20 INFO - x-amz-version-id: 7uZVdpUMBcnh63Gula8FQjwg.eZDomdd 05:45:20 INFO - Expected file size: 14409028 05:45:20 INFO - Obtained file size: 14409028 05:45:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:20 INFO - trying https://queue.taskcluster.net/v1/task/ALkgmStASCaRckuCrXipNA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:45:20 INFO - Downloading https://queue.taskcluster.net/v1/task/ALkgmStASCaRckuCrXipNA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 05:45:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ALkgmStASCaRckuCrXipNA/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 05:45:23 INFO - Downloaded 111298684 bytes. 05:45:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ALkgmStASCaRckuCrXipNA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:45:23 INFO - mkdir: /builds/slave/test/properties 05:45:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:45:23 INFO - Writing to file /builds/slave/test/properties/build_url 05:45:23 INFO - Contents: 05:45:23 INFO - build_url:https://queue.taskcluster.net/v1/task/ALkgmStASCaRckuCrXipNA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:45:24 INFO - Running post-action listener: _resource_record_post_action 05:45:24 INFO - Running post-action listener: set_extra_try_arguments 05:45:24 INFO - [mozharness: 2016-10-25 12:45:24.238509Z] Finished download-and-extract step (success) 05:45:24 INFO - [mozharness: 2016-10-25 12:45:24.238656Z] Running populate-webroot step. 05:45:24 INFO - Running pre-action listener: _resource_record_pre_action 05:45:24 INFO - Running main action method: populate_webroot 05:45:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:45:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:45:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:45:24 INFO - '--tppagecycles', 05:45:24 INFO - '7'], 05:45:24 INFO - 'tests': ['tsvgm']}}, 05:45:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:45:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:45:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:45:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:45:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:45:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:45:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:24 INFO - 'talos_options': ['--disable-e10s'], 05:45:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:45:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:45:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:24 INFO - 'talos_options': ['--disable-e10s'], 05:45:24 INFO - 'tests': ['damp', 'tps']}, 05:45:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:24 INFO - 'tests': ['damp', 'tps']}, 05:45:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:45:24 INFO - 'tests': ['dromaeo_dom']}, 05:45:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:45:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:45:24 INFO - 'tests': ['basic_compositor_video']}, 05:45:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:45:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:45:24 INFO - 'tests': ['a11yr', 05:45:24 INFO - 'ts_paint', 05:45:24 INFO - 'tpaint', 05:45:24 INFO - 'sessionrestore', 05:45:24 INFO - 'sessionrestore_no_auto_restore', 05:45:24 INFO - 'tabpaint']}, 05:45:24 INFO - 'other-e10s': {'tests': ['a11yr', 05:45:24 INFO - 'ts_paint', 05:45:24 INFO - 'tpaint', 05:45:24 INFO - 'sessionrestore', 05:45:24 INFO - 'sessionrestore_no_auto_restore', 05:45:24 INFO - 'tabpaint']}, 05:45:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:45:24 INFO - 'ts_paint', 05:45:24 INFO - 'tpaint', 05:45:24 INFO - 'sessionrestore', 05:45:24 INFO - 'sessionrestore_no_auto_restore', 05:45:24 INFO - 'tabpaint']}, 05:45:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:45:24 INFO - 'ts_paint', 05:45:24 INFO - 'tpaint', 05:45:24 INFO - 'sessionrestore', 05:45:24 INFO - 'sessionrestore_no_auto_restore', 05:45:24 INFO - 'tabpaint']}, 05:45:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:45:24 INFO - 'tests': ['a11yr', 05:45:24 INFO - 'ts_paint', 05:45:24 INFO - 'tpaint', 05:45:24 INFO - 'sessionrestore', 05:45:24 INFO - 'sessionrestore_no_auto_restore', 05:45:24 INFO - 'tabpaint']}, 05:45:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:45:24 INFO - 'tests': ['a11yr', 05:45:24 INFO - 'ts_paint', 05:45:24 INFO - 'tpaint', 05:45:24 INFO - 'sessionrestore', 05:45:24 INFO - 'sessionrestore_no_auto_restore', 05:45:24 INFO - 'tabpaint']}, 05:45:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:45:24 INFO - 'tests': ['tsvgx', 05:45:24 INFO - 'tsvgr_opacity', 05:45:24 INFO - 'tart', 05:45:24 INFO - 'tscrollx', 05:45:24 INFO - 'cart']}, 05:45:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:45:24 INFO - 'tsvgr_opacity', 05:45:24 INFO - 'tart', 05:45:24 INFO - 'tscrollx', 05:45:24 INFO - 'cart']}, 05:45:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:24 INFO - 'talos_options': ['--disable-e10s'], 05:45:24 INFO - 'tests': ['tp5o']}, 05:45:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:24 INFO - 'tests': ['tp5o']}, 05:45:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:24 INFO - 'talos_options': ['--disable-e10s', 05:45:24 INFO - '--xperf_path', 05:45:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:45:24 INFO - 'tests': ['tp5n']}, 05:45:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:24 INFO - 'talos_options': ['--xperf_path', 05:45:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:45:24 INFO - 'tests': ['tp5n']}}, 05:45:24 INFO - 'talos.zip': {'path': '', 05:45:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:45:24 INFO - Running post-action listener: _resource_record_post_action 05:45:24 INFO - [mozharness: 2016-10-25 12:45:24.257561Z] Finished populate-webroot step (success) 05:45:24 INFO - [mozharness: 2016-10-25 12:45:24.257687Z] Running create-virtualenv step. 05:45:24 INFO - Running pre-action listener: _resource_record_pre_action 05:45:24 INFO - Running main action method: create_virtualenv 05:45:24 INFO - Creating virtualenv /builds/slave/test/build/venv 05:45:24 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 05:45:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:45:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:45:24 INFO - Using real prefix '/tools/python27' 05:45:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:45:25 INFO - Installing distribute.............................................................................................................................................................................................done. 05:45:27 INFO - Installing pip.................done. 05:45:27 INFO - Return code: 0 05:45:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:45:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:45:28 INFO - Reading from file tmpfile_stdout 05:45:28 INFO - Output received: 05:45:28 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) 05:45:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:45:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:28 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 0x10965b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095d1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f78d92720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10946fd30>, '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 0x109295db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109467b20>, '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.kovZ4aggJx/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.ON90of9EUb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 05:45:28 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 05:45:28 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 05:45:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kovZ4aggJx/Render', 05:45:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:28 INFO - 'HOME': '/Users/cltbld', 05:45:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:28 INFO - 'LOGNAME': 'cltbld', 05:45:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:28 INFO - 'MOZ_NO_REMOTE': '1', 05:45:28 INFO - 'NO_EM_RESTART': '1', 05:45:28 INFO - 'PAGER': '/bin/cat', 05:45:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:28 INFO - 'PWD': '/builds/slave/test', 05:45:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:28 INFO - 'SHELL': '/bin/bash', 05:45:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ON90of9EUb/Listeners', 05:45:28 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 05:45:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:28 INFO - 'USER': 'cltbld', 05:45:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:28 INFO - 'XPC_FLAGS': '0x0', 05:45:28 INFO - 'XPC_SERVICE_NAME': '0', 05:45:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:28 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 05:45:28 INFO - Cleaning up... 05:45:28 INFO - Return code: 0 05:45:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:45:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:28 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 0x10965b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095d1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f78d92720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10946fd30>, '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 0x109295db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109467b20>, '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.kovZ4aggJx/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.ON90of9EUb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 05:45:28 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 05:45:28 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 05:45:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kovZ4aggJx/Render', 05:45:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:28 INFO - 'HOME': '/Users/cltbld', 05:45:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:28 INFO - 'LOGNAME': 'cltbld', 05:45:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:28 INFO - 'MOZ_NO_REMOTE': '1', 05:45:28 INFO - 'NO_EM_RESTART': '1', 05:45:28 INFO - 'PAGER': '/bin/cat', 05:45:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:28 INFO - 'PWD': '/builds/slave/test', 05:45:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:28 INFO - 'SHELL': '/bin/bash', 05:45:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ON90of9EUb/Listeners', 05:45:28 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 05:45:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:28 INFO - 'USER': 'cltbld', 05:45:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:28 INFO - 'XPC_FLAGS': '0x0', 05:45:28 INFO - 'XPC_SERVICE_NAME': '0', 05:45:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:28 INFO - Downloading/unpacking psutil>=3.1.1 05:45:28 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 05:45:28 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 05:45:28 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 05:45:28 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 05:45:28 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 05:45:28 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 05:45:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:45:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:45:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:45:32 INFO - Installing collected packages: psutil 05:45:32 INFO - Running setup.py install for psutil 05:45:32 INFO - building 'psutil._psutil_osx' extension 05:45:32 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 05:45:32 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 05:45:32 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 05:45:32 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 05:45:32 INFO - building 'psutil._psutil_posix' extension 05:45:32 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 05:45:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:45:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:45:32 INFO - ^ 05:45:32 INFO - 1 warning generated. 05:45:32 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 05:45:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:45:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:45:33 INFO - Successfully installed psutil 05:45:33 INFO - Cleaning up... 05:45:33 INFO - Return code: 0 05:45:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:45:33 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')]} 05:45:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:33 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')]} 05:45:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:33 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 0x10965b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095d1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f78d92720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10946fd30>, '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 0x109295db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109467b20>, '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.kovZ4aggJx/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.ON90of9EUb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 05:45:33 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 05:45:33 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 05:45:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kovZ4aggJx/Render', 05:45:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:33 INFO - 'HOME': '/Users/cltbld', 05:45:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:33 INFO - 'LOGNAME': 'cltbld', 05:45:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:33 INFO - 'MOZ_NO_REMOTE': '1', 05:45:33 INFO - 'NO_EM_RESTART': '1', 05:45:33 INFO - 'PAGER': '/bin/cat', 05:45:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:33 INFO - 'PWD': '/builds/slave/test', 05:45:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:33 INFO - 'SHELL': '/bin/bash', 05:45:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ON90of9EUb/Listeners', 05:45:33 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 05:45:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:33 INFO - 'USER': 'cltbld', 05:45:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:33 INFO - 'XPC_FLAGS': '0x0', 05:45:33 INFO - 'XPC_SERVICE_NAME': '0', 05:45:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:45: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 05:45: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 05:45: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 05:45: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 05:45: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 05:45: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 05:45:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:45:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:45:35 INFO - Installing collected packages: mozsystemmonitor 05:45:35 INFO - Running setup.py install for mozsystemmonitor 05:45:36 INFO - Successfully installed mozsystemmonitor 05:45:36 INFO - Cleaning up... 05:45:36 INFO - Return code: 0 05:45:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:45: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')]} 05:45:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45: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')]} 05:45:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10965b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095d1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f78d92720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10946fd30>, '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 0x109295db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109467b20>, '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.kovZ4aggJx/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.ON90of9EUb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 05:45: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==2.5.1'] in /builds/slave/test/build 05:45: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==2.5.1 05:45:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kovZ4aggJx/Render', 05:45:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:36 INFO - 'HOME': '/Users/cltbld', 05:45:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:36 INFO - 'LOGNAME': 'cltbld', 05:45:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:36 INFO - 'MOZ_NO_REMOTE': '1', 05:45:36 INFO - 'NO_EM_RESTART': '1', 05:45:36 INFO - 'PAGER': '/bin/cat', 05:45:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:36 INFO - 'PWD': '/builds/slave/test', 05:45:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:36 INFO - 'SHELL': '/bin/bash', 05:45:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ON90of9EUb/Listeners', 05:45:36 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 05:45:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:36 INFO - 'USER': 'cltbld', 05:45:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:36 INFO - 'XPC_FLAGS': '0x0', 05:45:36 INFO - 'XPC_SERVICE_NAME': '0', 05:45:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:36 INFO - Downloading/unpacking jsonschema==2.5.1 05:45:36 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 05:45:36 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 05:45:36 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 05:45:36 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 05:45:36 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 05:45:36 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 05:45:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:45:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:45:38 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 05:45:38 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 05:45:38 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 05:45:38 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 05:45:38 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 05:45:38 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 05:45:39 INFO - Downloading functools32-3.2.3-2.tar.gz 05:45:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:45:39 INFO - warning: no files found matching '*.txt' 05:45:39 INFO - no previously-included directories found matching 'build' 05:45:39 INFO - no previously-included directories found matching 'dist' 05:45:39 INFO - no previously-included directories found matching '.git*' 05:45:39 INFO - Installing collected packages: jsonschema, functools32 05:45:39 INFO - Running setup.py install for functools32 05:45:39 INFO - warning: no files found matching '*.txt' 05:45:39 INFO - no previously-included directories found matching 'build' 05:45:39 INFO - no previously-included directories found matching 'dist' 05:45:39 INFO - no previously-included directories found matching '.git*' 05:45:39 INFO - Successfully installed jsonschema functools32 05:45:39 INFO - Cleaning up... 05:45:39 INFO - Return code: 0 05:45:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:45:39 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')]} 05:45:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:39 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')]} 05:45:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:39 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 0x10965b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095d1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f78d92720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10946fd30>, '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 0x109295db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109467b20>, '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.kovZ4aggJx/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.ON90of9EUb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 05:45:39 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 05:45:39 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 05:45:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kovZ4aggJx/Render', 05:45:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:39 INFO - 'HOME': '/Users/cltbld', 05:45:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:39 INFO - 'LOGNAME': 'cltbld', 05:45:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:39 INFO - 'MOZ_NO_REMOTE': '1', 05:45:39 INFO - 'NO_EM_RESTART': '1', 05:45:39 INFO - 'PAGER': '/bin/cat', 05:45:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:39 INFO - 'PWD': '/builds/slave/test', 05:45:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:39 INFO - 'SHELL': '/bin/bash', 05:45:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ON90of9EUb/Listeners', 05:45:39 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 05:45:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:39 INFO - 'USER': 'cltbld', 05:45:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:39 INFO - 'XPC_FLAGS': '0x0', 05:45:39 INFO - 'XPC_SERVICE_NAME': '0', 05:45:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:45:39 INFO - Cleaning up... 05:45:39 INFO - Return code: 0 05:45:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:45:39 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')]} 05:45:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:39 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')]} 05:45:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:39 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 0x10965b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095d1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f78d92720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10946fd30>, '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 0x109295db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109467b20>, '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.kovZ4aggJx/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.ON90of9EUb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 05:45:39 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 05:45:39 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 05:45:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kovZ4aggJx/Render', 05:45:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:39 INFO - 'HOME': '/Users/cltbld', 05:45:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:39 INFO - 'LOGNAME': 'cltbld', 05:45:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:39 INFO - 'MOZ_NO_REMOTE': '1', 05:45:39 INFO - 'NO_EM_RESTART': '1', 05:45:39 INFO - 'PAGER': '/bin/cat', 05:45:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:39 INFO - 'PWD': '/builds/slave/test', 05:45:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:39 INFO - 'SHELL': '/bin/bash', 05:45:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ON90of9EUb/Listeners', 05:45:39 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 05:45:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:39 INFO - 'USER': 'cltbld', 05:45:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:39 INFO - 'XPC_FLAGS': '0x0', 05:45:39 INFO - 'XPC_SERVICE_NAME': '0', 05:45:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:40 INFO - Downloading/unpacking blobuploader==1.2.4 05:45:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:42 INFO - Downloading blobuploader-1.2.4.tar.gz 05:45:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:45:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:45:42 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 05:45:42 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 05:45:42 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 05:45:42 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 05:45:42 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 05:45:42 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 05:45:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:45:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:45:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:43 INFO - Downloading docopt-0.6.1.tar.gz 05:45:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:45:44 INFO - Installing collected packages: blobuploader, requests, docopt 05:45:44 INFO - Running setup.py install for blobuploader 05:45:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:45:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:45:44 INFO - Running setup.py install for requests 05:45:44 INFO - Running setup.py install for docopt 05:45:44 INFO - Successfully installed blobuploader requests docopt 05:45:44 INFO - Cleaning up... 05:45:44 INFO - Return code: 0 05:45:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:45:44 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')]} 05:45:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:44 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')]} 05:45:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:44 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 0x10965b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095d1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f78d92720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10946fd30>, '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 0x109295db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109467b20>, '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.kovZ4aggJx/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.ON90of9EUb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 05:45:44 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 05:45:44 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 05:45:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kovZ4aggJx/Render', 05:45:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:44 INFO - 'HOME': '/Users/cltbld', 05:45:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:44 INFO - 'LOGNAME': 'cltbld', 05:45:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:44 INFO - 'MOZ_NO_REMOTE': '1', 05:45:44 INFO - 'NO_EM_RESTART': '1', 05:45:44 INFO - 'PAGER': '/bin/cat', 05:45:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:44 INFO - 'PWD': '/builds/slave/test', 05:45:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:44 INFO - 'SHELL': '/bin/bash', 05:45:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ON90of9EUb/Listeners', 05:45:44 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 05:45:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:44 INFO - 'USER': 'cltbld', 05:45:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:44 INFO - 'XPC_FLAGS': '0x0', 05:45:44 INFO - 'XPC_SERVICE_NAME': '0', 05:45:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:45:45 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-royGLH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:45:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:45:45 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-SXiqZo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:45:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:45:45 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-EIoNfo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:45:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:45:45 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-OiwJyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:45:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:45:45 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-MLi5gC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:45:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:45:45 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-GokWPU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:45:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:45:45 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-H0AjYl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:45:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:45:45 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-mxpxUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:45:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:45:45 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-Wpm4j2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:45:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:45:46 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-D94NNc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:45:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:45:46 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-4reMj0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:45:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:45:46 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-OxVTky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:45:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:45:46 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-0Fv7ff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:45:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:45:46 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-OJVDK_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:45:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:45:46 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-yS5lJe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:45:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:45:46 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-ONBtZK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:45:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:45:46 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-mwHQbf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:45:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:45:46 INFO - Running setup.py install for manifestparser 05:45:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:45:47 INFO - Running setup.py install for mozcrash 05:45:47 INFO - Running setup.py install for mozdebug 05:45:47 INFO - Running setup.py install for mozdevice 05:45:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:45:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:45:47 INFO - Running setup.py install for mozfile 05:45:47 INFO - Running setup.py install for mozhttpd 05:45:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:45:47 INFO - Running setup.py install for mozinfo 05:45:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:45:47 INFO - Running setup.py install for mozInstall 05:45:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:45:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:45:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:45:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:45:48 INFO - Running setup.py install for mozleak 05:45:48 INFO - Running setup.py install for mozlog 05:45:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:45:48 INFO - Running setup.py install for moznetwork 05:45:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:45:48 INFO - Running setup.py install for mozprocess 05:45:48 INFO - Running setup.py install for mozprofile 05:45:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:45:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:45:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:45:48 INFO - Running setup.py install for mozrunner 05:45:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:45:49 INFO - Running setup.py install for mozscreenshot 05:45:49 INFO - Running setup.py install for moztest 05:45:49 INFO - Running setup.py install for mozversion 05:45:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:45:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:45:49 INFO - Cleaning up... 05:45:49 INFO - Return code: 0 05:45:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:45:49 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')]} 05:45:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:49 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')]} 05:45:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:49 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 0x10965b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095d1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f78d92720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10946fd30>, '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 0x109295db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109467b20>, '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.kovZ4aggJx/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.ON90of9EUb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 05:45:49 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 05:45:49 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 05:45:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kovZ4aggJx/Render', 05:45:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:49 INFO - 'HOME': '/Users/cltbld', 05:45:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:49 INFO - 'LOGNAME': 'cltbld', 05:45:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:49 INFO - 'MOZ_NO_REMOTE': '1', 05:45:49 INFO - 'NO_EM_RESTART': '1', 05:45:49 INFO - 'PAGER': '/bin/cat', 05:45:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:49 INFO - 'PWD': '/builds/slave/test', 05:45:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:49 INFO - 'SHELL': '/bin/bash', 05:45:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ON90of9EUb/Listeners', 05:45:49 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 05:45:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:49 INFO - 'USER': 'cltbld', 05:45:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:49 INFO - 'XPC_FLAGS': '0x0', 05:45:49 INFO - 'XPC_SERVICE_NAME': '0', 05:45:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:45:49 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-Y_d9V9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:45:49 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)) 05:45:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:45:49 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-H7wSCM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:45:49 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)) 05:45:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:45:49 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-qKxh6f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:45:50 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)) 05:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:45:50 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-gD9PjO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:45:50 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)) 05:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:45:50 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-Fwt4G7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:45:50 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)) 05:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:45:50 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-D7w1j7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:45:50 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)) 05:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:45:50 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-y0wOu_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:45:50 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)) 05:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:45:50 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-eY0w4g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:45:50 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)) 05:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:45:50 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-NOl0eP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:45:50 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)) 05:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:45:50 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-w4vJon-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:45:50 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)) 05:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:45:50 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-xGM0ze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:45:50 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)) 05:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:45:50 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-4FvIQ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:45:51 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)) 05:45:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:45:51 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-m7N81w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:45:51 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)) 05:45:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:45:51 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-lPx4ni-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:45:51 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)) 05:45:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:45:51 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-u8JkrN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:45:51 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)) 05:45:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:45:51 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-oZDc6x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:45:51 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)) 05:45:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:45:51 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-8Ga4Sv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:45:51 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)) 05:45:51 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)) 05:45:51 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)) 05:45:51 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)) 05:45:51 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)) 05:45:51 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)) 05:45:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:45:51 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 05:45:51 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 05:45:51 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 05:45:51 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 05:45:51 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 05:45:51 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 05:45:54 INFO - Downloading blessings-1.6.tar.gz 05:45:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:45:54 INFO - Installing collected packages: blessings 05:45:54 INFO - Running setup.py install for blessings 05:45:54 INFO - Successfully installed blessings 05:45:54 INFO - Cleaning up... 05:45:54 INFO - Return code: 0 05:45:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:45:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:45:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:45:54 INFO - Reading from file tmpfile_stdout 05:45:54 INFO - Current package versions: 05:45:54 INFO - blessings == 1.6 05:45:54 INFO - blobuploader == 1.2.4 05:45:54 INFO - docopt == 0.6.1 05:45:54 INFO - functools32 == 3.2.3-2 05:45:54 INFO - jsonschema == 2.5.1 05:45:54 INFO - manifestparser == 1.1 05:45:54 INFO - mozInstall == 1.12 05:45:54 INFO - mozcrash == 0.17 05:45:54 INFO - mozdebug == 0.1 05:45:54 INFO - mozdevice == 0.48 05:45:54 INFO - mozfile == 1.2 05:45:54 INFO - mozhttpd == 0.7 05:45:54 INFO - mozinfo == 0.9 05:45:54 INFO - mozleak == 0.1 05:45:54 INFO - mozlog == 3.4 05:45:54 INFO - moznetwork == 0.27 05:45:54 INFO - mozprocess == 0.23 05:45:54 INFO - mozprofile == 0.28 05:45:54 INFO - mozrunner == 6.12 05:45:54 INFO - mozscreenshot == 0.1 05:45:54 INFO - mozsystemmonitor == 0.3 05:45:54 INFO - moztest == 0.7 05:45:54 INFO - mozversion == 1.4 05:45:54 INFO - psutil == 3.1.1 05:45:54 INFO - requests == 1.2.3 05:45:54 INFO - wsgiref == 0.1.2 05:45:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:45:54 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')]} 05:45:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:54 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')]} 05:45:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:54 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 0x10965b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095d1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f78d92720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10946fd30>, '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 0x109295db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109467b20>, '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.kovZ4aggJx/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.ON90of9EUb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 05:45:54 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 05:45:54 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 05:45:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kovZ4aggJx/Render', 05:45:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:54 INFO - 'HOME': '/Users/cltbld', 05:45:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:54 INFO - 'LOGNAME': 'cltbld', 05:45:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:54 INFO - 'MOZ_NO_REMOTE': '1', 05:45:54 INFO - 'NO_EM_RESTART': '1', 05:45:54 INFO - 'PAGER': '/bin/cat', 05:45:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:54 INFO - 'PWD': '/builds/slave/test', 05:45:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:54 INFO - 'SHELL': '/bin/bash', 05:45:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ON90of9EUb/Listeners', 05:45:54 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 05:45:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:54 INFO - 'USER': 'cltbld', 05:45:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:54 INFO - 'XPC_FLAGS': '0x0', 05:45:54 INFO - 'XPC_SERVICE_NAME': '0', 05:45:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:54 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)) 05:45:54 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)) 05:45:54 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)) 05:45:54 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)) 05:45:54 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)) 05:45:54 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)) 05:45:54 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)) 05:45:54 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)) 05:45:54 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)) 05:45:54 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)) 05:45:54 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)) 05:45:54 INFO - Cleaning up... 05:45:54 INFO - Return code: 0 05:45:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:45:54 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')]} 05:45:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:54 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')]} 05:45:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:54 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 0x10965b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095d1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f78d92720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10946fd30>, '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 0x109295db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109467b20>, '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.kovZ4aggJx/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.ON90of9EUb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 05:45:54 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 05:45:54 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 05:45:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kovZ4aggJx/Render', 05:45:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:54 INFO - 'HOME': '/Users/cltbld', 05:45:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:54 INFO - 'LOGNAME': 'cltbld', 05:45:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:55 INFO - 'MOZ_NO_REMOTE': '1', 05:45:55 INFO - 'NO_EM_RESTART': '1', 05:45:55 INFO - 'PAGER': '/bin/cat', 05:45:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:55 INFO - 'PWD': '/builds/slave/test', 05:45:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:55 INFO - 'SHELL': '/bin/bash', 05:45:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ON90of9EUb/Listeners', 05:45:55 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 05:45:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:55 INFO - 'USER': 'cltbld', 05:45:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:55 INFO - 'XPC_FLAGS': '0x0', 05:45:55 INFO - 'XPC_SERVICE_NAME': '0', 05:45:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:45:55 INFO - Cleaning up... 05:45:55 INFO - Return code: 0 05:45:55 INFO - Running post-action listener: _resource_record_post_action 05:45:55 INFO - Running post-action listener: _start_resource_monitoring 05:45:55 INFO - Starting resource monitoring. 05:45:55 INFO - [mozharness: 2016-10-25 12:45:55.283991Z] Finished create-virtualenv step (success) 05:45:55 INFO - [mozharness: 2016-10-25 12:45:55.285000Z] Running install step. 05:45:55 INFO - Running pre-action listener: _resource_record_pre_action 05:45:55 INFO - Running main action method: install 05:45:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:45:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:45:55 INFO - Reading from file tmpfile_stdout 05:45:55 INFO - Detecting whether we're running mozinstall >=1.0... 05:45:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:45:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:45:55 INFO - Reading from file tmpfile_stdout 05:45:55 INFO - Output received: 05:45:55 INFO - Usage: mozinstall [options] installer 05:45:55 INFO - Options: 05:45:55 INFO - -h, --help show this help message and exit 05:45:55 INFO - -d DEST, --destination=DEST 05:45:55 INFO - Directory to install application into. [default: 05:45:55 INFO - "/builds/slave/test"] 05:45:55 INFO - --app=APP Application being installed. [default: firefox] 05:45:55 INFO - mkdir: /builds/slave/test/build/application 05:45:55 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'] 05:45:55 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 05:46:29 INFO - Reading from file tmpfile_stdout 05:46:29 INFO - Output received: 05:46:29 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:46:29 INFO - Running post-action listener: _resource_record_post_action 05:46:29 INFO - [mozharness: 2016-10-25 12:46:29.531839Z] Finished install step (success) 05:46:29 INFO - [mozharness: 2016-10-25 12:46:29.531991Z] Running run-tests step. 05:46:29 INFO - Running pre-action listener: _resource_record_pre_action 05:46:29 INFO - Running main action method: run_tests 05:46:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:46:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:46:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:46:29 INFO - 2016-10-25 05:46:29.546 screenresolution[1924:12828] starting screenresolution argv=screenresolution get 05:46:29 INFO - 2016-10-25 05:46:29.549 screenresolution[1924:12828] Display 0: 1600x1200x32@60 05:46:29 INFO - 2016-10-25 05:46:29.555 screenresolution[1925:12830] starting screenresolution argv=screenresolution list 05:46:29 INFO - Available Modes on Display 0 05:46:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:46:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:46:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:46:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:46:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:46:29 INFO - Intel Iris: 05:46:29 INFO - Chipset Model: Intel Iris 05:46:29 INFO - Type: GPU 05:46:29 INFO - Bus: Built-In 05:46:29 INFO - VRAM (Dynamic, Max): 1536 MB 05:46:29 INFO - Vendor: Intel (0x8086) 05:46:29 INFO - Device ID: 0x0a2e 05:46:29 INFO - Revision ID: 0x0009 05:46:29 INFO - Displays: 05:46:29 INFO - Display: 05:46:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:46:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:46:29 INFO - Display Serial Number: 1600x1200 60 05:46:29 INFO - Main Display: Yes 05:46:29 INFO - Mirror: Off 05:46:29 INFO - Online: Yes 05:46:29 INFO - Rotation: Supported 05:46:29 INFO - Return code: 0 05:46:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:46:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:46:29 INFO - Python 2.7.3 05:46:29 INFO - Return code: 0 05:46:29 INFO - grabbing minidump binary from tooltool 05:46:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:46:29 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 0x10946fd30>, '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 0x109295db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109467b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:46:29 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 05:46:29 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 05:46:29 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 05:46:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:46:29 INFO - Return code: 0 05:46:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:46:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:46:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:46:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:46:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:46:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ALkgmStASCaRckuCrXipNA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0119', '--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 05:46:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ALkgmStASCaRckuCrXipNA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0119 --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 05:46:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kovZ4aggJx/Render', 05:46:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:46:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:46:29 INFO - 'HOME': '/Users/cltbld', 05:46:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:46:29 INFO - 'LOGNAME': 'cltbld', 05:46:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:46:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:46:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:29 INFO - 'MOZ_NO_REMOTE': '1', 05:46:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:46:29 INFO - 'NO_EM_RESTART': '1', 05:46:29 INFO - 'PAGER': '/bin/cat', 05:46:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:46:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:29 INFO - 'PWD': '/builds/slave/test', 05:46:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:46:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:46:29 INFO - 'SHELL': '/bin/bash', 05:46:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ON90of9EUb/Listeners', 05:46:29 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 05:46:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:46:29 INFO - 'USER': 'cltbld', 05:46:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:46:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:46:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:29 INFO - 'XPC_FLAGS': '0x0', 05:46:29 INFO - 'XPC_SERVICE_NAME': '0', 05:46:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:46:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ALkgmStASCaRckuCrXipNA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0119', '--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 05:46:30 INFO - mozversion application_buildid: 20161025024804 05:46:30 INFO - mozversion application_changeset: 523d47a7f8f38c271a2d5cf4fb9cbdbad1036e3b 05:46:30 INFO - mozversion application_display_name: Nightly 05:46:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:46:30 INFO - mozversion application_name: Firefox 05:46:30 INFO - mozversion application_remotingname: firefox 05:46:30 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:46:30 INFO - mozversion application_vendor: Mozilla 05:46:30 INFO - mozversion application_version: 52.0a1 05:46:30 INFO - mozversion platform_buildid: 20161025024804 05:46:30 INFO - mozversion platform_changeset: 523d47a7f8f38c271a2d5cf4fb9cbdbad1036e3b 05:46:30 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:46:30 INFO - mozversion platform_version: 52.0a1 05:46:30 INFO - using testdate: 1477399590 05:46:30 INFO - actual date: 1477399590 05:46:30 INFO - starting webserver on 'localhost:50906' 05:46:30 INFO - SUITE-START | Running 2 tests 05:46:30 INFO - TEST-START | dromaeo_css 05:46:30 INFO - operating with platform_type : mac_ 05:46:30 INFO - Initialising browser for dromaeo_css test... 05:46:30 INFO - TEST-INFO | started process 1935 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/tmpokG8_A/profile http://localhost:50906/getInfo.html) 05:46:32 INFO - PROCESS | 1935 | 2016-10-25 05:46:32.667 plugin-container[1937:12989] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4843, name = 'com.apple.tsm.portname' 05:46:32 INFO - PROCESS | 1935 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:46:32 INFO - PROCESS | 1935 | 2016-10-25 05:46:32.669 plugin-container[1937:12989] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0f, name = 'com.apple.CFPasteboardClient' 05:46:32 INFO - PROCESS | 1935 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:46:32 INFO - PROCESS | 1935 | 2016-10-25 05:46:32.669 plugin-container[1937:12989] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:46:32 INFO - PROCESS | 1935 | __metrics Screen width/height:1600/1200 05:46:32 INFO - PROCESS | 1935 | colorDepth:24 05:46:32 INFO - PROCESS | 1935 | Browser inner width/height: 1024/689 05:46:32 INFO - PROCESS | 1935 | __metrics 05:46:32 ERROR - PROCESS | 1935 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3097: TypeError: this.tabs is undefined 05:46:33 INFO - TEST-INFO | 1935: exit 0 05:46:33 INFO - Browser initialized. 05:46:33 INFO - Running cycle 1/1 for dromaeo_css test... 05:46:33 INFO - TEST-INFO | started process 1938 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/tmpokG8_A/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:46:35 INFO - PROCESS | 1938 | 2016-10-25 05:46:35.850 plugin-container[1939:13114] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 05:46:35 INFO - PROCESS | 1938 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:46:35 INFO - PROCESS | 1938 | 2016-10-25 05:46:35.851 plugin-container[1939:13114] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 05:46:35 INFO - PROCESS | 1938 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:46:35 INFO - PROCESS | 1938 | 2016-10-25 05:46:35.851 plugin-container[1939:13114] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:46:45 INFO - PROCESS | 1938 | RSS: Main: 235982848 05:46:45 INFO - PROCESS | 1938 | 05:47:35 INFO - PROCESS | 1938 | 1477399655173 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] 05:49:47 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:50906/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50906/tests/dromaeo/cssquery-ext.html) 05:49:49 INFO - PROCESS | 1938 | RSS: Main: 252076032 05:49:49 INFO - PROCESS | 1938 | 05:52:51 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:50906/tests/dromaeo/cssquery-ext.html (next: http://localhost:50906/tests/dromaeo/cssquery-jquery.html) 05:52:52 INFO - PROCESS | 1938 | RSS: Main: 262762496 05:52:52 INFO - PROCESS | 1938 | 05:55:54 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:50906/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50906/tests/dromaeo/cssquery-mootools.html) 05:55:54 INFO - PROCESS | 1938 | RSS: Main: 267513856 05:55:54 INFO - PROCESS | 1938 | 05:58:56 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:50906/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50906/tests/dromaeo/cssquery-prototype.html) 05:58:57 INFO - PROCESS | 1938 | RSS: Main: 267096064 05:58:57 INFO - PROCESS | 1938 | 06:02:00 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:50906/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50906/tests/dromaeo/cssquery-yui.html) 06:02:00 INFO - PROCESS | 1938 | RSS: Main: 272539648 06:02:00 INFO - PROCESS | 1938 | 06:05:03 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:50906/tests/dromaeo/cssquery-yui.html (next: http://localhost:50906/tests/dromaeo/cssquery-dojo.html) 06:05:03 INFO - PROCESS | 1938 | RSS: Main: 275394560 06:05:03 INFO - PROCESS | 1938 | 06:05:03 INFO - PROCESS | 1938 | __start_tp_report 06:05:03 INFO - PROCESS | 1938 | _x_x_mozilla_page_load 06:05:03 INFO - PROCESS | 1938 | _x_x_mozilla_page_load_details 06:05:03 INFO - PROCESS | 1938 | |i|pagename|runs| 06:05:03 INFO - PROCESS | 1938 | |0;dojo.html;3294.505824126381;3314.486254630533;3315.2043509557684;3352.6479719629447;3353.178471274538;4344.500382456018;5510.669359838413;5549.139883318087;5594.104943209088;5639.984802735506;5455.5908306877045;5540.279763630722;5614.9157762633595;5615.101583746601;5630.493255606994;5092.465291144432;5098.464661210584;5135.743212839343;5139.897202055957;5142.511461411179;4484.902106544924;5161.122609156434;5200.453952334989;5258.237555554442;5326.92009619856;5057.064443078042;5199.324087868553;5201.193814958649;5229.267902493654;5243.977424402243;5069.645124841262;5138.126518491866;5167.431582525956;5172.32759741235;5224.582033437316;5060.291559181717;5089.643724939256;5133.281340612317;5135.666181698178;5194.480551944813;5352.545033672142;5500.724963751836;5517.393086760453;5637.3517045539875;5663.9716801415725;6029.306629737549;6051.909221361683;6078.939210607881;6127.504844864458;6157.384261573851;6156.415140561639;6174.783882564134;6185.195924529828;6195.101710251988;6201.9689901550655;5528.115501519737;5662.886742265132;5672.234248376499;5712.257406537124;5720.685362305072;6145.139680444741;6165.075238714228;6199.721012554446;6226.40849119333;6275.466585340298;6013.865077897371;6015.2781666200335;6059.394060593905;6077.9392206078255;6123.173371594778;6026.939730602638;6150.354212807591;6156.415140561639;6167.259928808572;6191.907121393183;5663.9716801415725;5944.7324870380935;5954.166416701665;5963.4334738199805;6089;6010;6036.034234522455;6056.121862330026;6065.636061836304;6078.483328917003;4145.461090058273;4781.091592597395;4786.401699787527;4800.183968725325;4811.518848115161;3413.9829300853335;3441.5525981622227;4198.685098617616;4235.53409124996;4350.695451318377;5503.284573005483;5666.46168613981;5677.744501497443;5715.914261286084;5746.597738158289;6043.153958445821;6147.293061297919;6186.072089186658;6189.783357582463;6203.689815509207;5574.777008919597;5619.690916999522;5636.887262254814;5649.4633009864;5667.489925906688;4299.763513006752;4617.683960071364;5338.813141540028;5349.117395629678;5402.91895621566;6219.160413344141;6224.719887604978;6231.22109736283;6246.6876656167915;6247.437730604086;6051.273847138372;6066.817995460143;6106.126990750407;6218.06728990655;6251.218597675291;6147.754089836357;6193.504519638508;6246;6267.373262673878;6268.216472940882;6098.725557349841;6119.204503414528;6138.324784273816;6152.723127459186;6157.507399408126;5986.491148252448;6110.114033465212;6118.162361256354;6118.785842495491;6136.926037943253;5915.822525324247;5947.553933454921;6006.909896351645;6043.942310095627;6075.27096748393;5972.492338151305;5986.88026239464;6006.249218847644;6024.427679370453;6042.912275790224;3786.053486628343;5362.463753624763;5813.244278243801;5831.825045248649;5855.795047173328;5970.462658360769;6019.638821670714;6020.638761674313;6042.244719410074;6071.848203794941;5813.3895940925595;5822.679752613647;5874.118882167709;5893.381194974466;5952.910706339322 06:05:03 INFO - PROCESS | 1938 | |1;ext.html;481.3694060780378;517.4876871896822;530.1755769777997;532.29205157141;532.5393534592579;30071.548926766118;39378;39973.800130999305;40831.97920052;42742;42462.57537424633;42956.570434295645;42988;43249.78375108128;43310.7834460828;9504.619815207401;9686;9698.951505242463;9720.854187187162;9744.366616169926;9616.663416780415;9642.517874106301;9686.176674982635;9692;9703.902960970407;8505.277051450634;9564.80870382592;9722.076402741764;9743.853842192373;9747.610095596168;9617.42295462271;9639;9692.030796920288;9698.030196980317;9725.027497250288;9513.762155946157;9576.138147566753;9587.42475451468;9621.663241786573;9651.758706032335;40723.59276407257;41040.58959410398;41144;41205.79397103025;41249.96875078117;3334.733221342287;3399.9490007649906;3558.822058897045;3924.725269231155;3961.564227934925;28554.286142846388;31001.914932977463;31275.687243127504;31346.843265783755;32301.51547726786;3221.5811944447305;3252.560904277916;3265.281638039651;3315.8507867146036;3362.899113026613;251263;360239;360761;361009;362804.9999999974;60269.69865150734;60415.69792151011;60686;60735.78528429495;60798.39201607927;63368;63708.36291637024;63776.68111659505;64068.679656601416;64161;15792;15892.841071589137;16020.359185632444;16022.919885400499;16083.437079702155;12310.199837010565;12376.257424554555;12402.75194496105;12416.875831241752;12452.688682783002;16690.83309166917;16704.582385440463;16709.74935375946;16753.832461675473;16842.24209910557;2760.1857452051695;6992.650367481708;7133.89299160513;7216.567005979553;7292.96353518229;881.4843316659826;923.9861402078974;945.5461378538203;1007.6271779441654;1052.2003277509148;29410;30057.549136762966;30378.392432151235;30473;30541.85236284948;995.9502024898726;1031.515187861704;1047.2145890582065;1062.2458054781036;1114.2478826791883;14306.356213970397;15722.528324150293;15796.526104216891;15878.047317161007;16056.75914861278;656962;657411;659793;664122;665614.343856565;56284.43715562874;56528.43471565314;56717.14924276139;56909.14636280459;57151;1354.6003928840819;1376.7728324826292;1380.827396575428;1397.699494608664;1399.979000314996;23785;28326.00858969926;29875;29875.55186672158;30546.236344090685;50425.73935651491;50743.74628126836;50915.999999998516;50954;51310.177940213645;592051;594669.026654864;596395;596742.2606191407;599900;26423.339416513973;26489.470210596064;26634.600480993187;26769;26775.732242677324;44227.557724423634;46899;47960.5203947956;48223.75888120537;48603;38673.61326386813;38907.610923890395;39710.205795884496;40413.79793101016;40785.77642670725;33268.833655831564;33993.98018059462;34633.65366346405;34727.13182170466;40610.39084413677;32237;38172.23655526929;38760;39059.60940390673;39277.803610982905;31317.217069573442;31727;33933;34689.479657805656;34743.65256347404;22916;24209.757902421454;24468.51062978766;25480.617790733526;25735.613965790904 06:05:03 INFO - PROCESS | 1938 | |2;jquery.html;370.4942753141962;374.9662530372266;381.66222892739927;381.875890335641;401.9296623090958;18685.760826658527;20390;21039.47401314968;21214.469638259052;21275.787242127575;19908.800911990875;20231.79768202321;20599.691004634944;20718.342532597395;21008.57982840346;4772.6181905447565;5690.516306113986;5912.201852749867;6004.85907677542;6007.369226231248;4131.421600975851;4365.105153443552;5890.5582081343855;5891.351951285355;5923.555733319997;6232.844178895519;6540.607563546178;6589.472842172615;6595.109660195884;6652.70062847173;6349.650769207692;6579.9671001645165;6611.570247933917;6624.403803657646;6643.269240383554;5174.809793747421;5393.946060539422;5658.547316214735;5696.515796157331;5706.828795136111;25555.87222063878;25677.61483577729;25769.840357183974;26015.869920650464;26042.34894127643;2222.0667319725753;2268.206127855254;2350.471143992605;2923.751481124086;3078.7690923180853;15127.470538531099;15250.694986100216;15270.618234544114;15483.458078967182;16027.599310017225;1789.8389144976884;1810.3482746211353;1865.4590168851016;1888.4617883903022;1912.502749285182;81121.78317325245;89635.75910602222;118968.40515797453;123317;124983.3750831249;8381.371397145238;9284.582193801296;9947.303688741864;10610.840837387444;10741.19441041925;13072.215667059816;13489;13502.392392342368;13506.392212350467;13561.525346612822;7420.443466740016;7583.544987300669;8389.748307550783;8427.789305267417;8504.44721093127;6919.861602767916;8461.61922713467;8510.234078932926;8598.527081010605;8603.827923441497;5307.310049693592;6323.620582765049;6523.608583484911;6525.249596296392;6528.640924749089;1443.5452832357773;1549.5971047527498;1775.4318618042362;1808.7790741249867;1824.6350729854082;890.5547226386807;922.3589605224305;944.2729098594182;946.1295608040482;951.4766878216954;20971.161153553992;21749.67375489369;22348.553028939332;22355.44111397228;22562.774372256066;860.475110182788;943.4433684126399;983.7393090830935;1026.630413051301;1053.5311786255043;8228.670853165926;10629.84055239172;11106.278091924;11119.943605357477;11173;506792;510087;512689;618771;628705;138884.91672624918;146977.26511367375;149027;150817.24591376973;152038.23980880025;803.935685145187;951.3103000324847;989.0603926270072;991.311038828022;993.4089216915924;18314.725279120557;18741.437756867315;18772.6245475087;19028.809711902704;19227.230910763414;48847;49865.75335616639;55130.17304740352;56764;57755.71122144531;81058;81374.77937831056;81618.18381816105;82008;83221;7619.809504762426;7620.085589729268;7665.923340766521;7777.611119443892;8038.115807261313;10547.419891906018;12338.198017128858;12856.678583035125;13102.672433189247;13433.462661493822;9908.504574771088;10997.835032474359;11178.658560972735;11191.776164476827;11912.463939122934;11018.669439916815;11394.658160255205;11508.194426390408;11773.587924422603;11780.52877884875;11015.373543111751;11359.318440893572;11368.715782105513;11599.246049006788;11625.360605166798;7949.085855126735;7960.960195198987;8189;8291.875621865556;8366.790830229293;10294.227932904912;10465.424401657981;10619.840702389316;11454.370009649549;11704.94147529257 06:05:03 INFO - PROCESS | 1938 | |3;mootools.html;1497.3636204613038;1540.4454396417289;1767.3372485318005;1889.2348598817484;1921.3179321340924;6600.537962342638;8023.31801796848;8065.032196136472;8168.652172391556;8318.126596707349;7412.444066694992;8350.540720260384;8630.48217106974;8685;8805.691800786974;2264.1509433962224;2282.5206706591553;2289.301762962296;2292.9656055159185;2296.357020034393;2017.7276067730816;2122.4587730128796;2293.312006398074;2301.7122859642545;2305.13557415969;2265.954680906381;2266.3880752196897;2277.3737222263844;2280.981331521599;2292.9312120636323;2251.4596496840804;2258.0967612954787;2270.829687773415;2272.1365880965127;2279.817614590837;2207.514346843692;2208.0505382685437;2208.1277895231456;2216.1578600132007;2223.032980653421;8642.913570864273;8778.780530486789;8801.779955501099;8812.735617931408;8824.470531768115;5154.4587818278915;5316.149416093445;5318.494742999409;5319.627626066177;5328.547073498718;2849.5155823510054;3253.8210398428077;3565.4295312750096;3571.624979377154;3580.122869896892;1002.8495725641139;1044.2533588484193;1050.0287234308234;1052.2897044495012;1061.5117046158775;11282.830757538644;11295.887041129567;11314;11419.657410277705;11441.027512661434;4436.824241575985;4565.931511027337;4638.489766125724;4677.041206552649;4679.625629949596;9798.31411801167;9842.852357214648;9875.753106172404;9891.109800118023;9903.158231550255;5262;5263.552598029134;5269.841904742864;5275.20871869215;5276.419593844674;4089.3661482470266;4787.18617834962;4812.205986012339;4823;4823.517648235148;3295.8846440374473;3740.102375429877;3790.753601015925;3851.730378873452;3896.123372241223;1261.3062815451465;1263.1915574032625;1287.0861688201294;1288.4717265921115;1296.125115547002;896.6503063805121;904.904984976568;919.5034681272039;937.1893312284825;942.9721603452133;5695.430456954271;5733.455321744427;5766.163906233573;5835.679037652885;5873.500752436091;776.3362325211975;912.3157631776168;964.6864768950119;995.4077323992213;1006.2050979725934;3629.0564453242346;3733.794641294755;3821.7706937583835;3826.7895265760094;3841.5774264830848;10014.198864090855;10227.7443063924;10444.321119127233;10505.212109091848;10551.788964220672;10458.424786636655;10477.42374169428;10484.161267098614;10519.211059170593;10553.84169237462;7845.764627061198;8129.918700813035;8212;8242.917570824215;8255.545944972964;4607.838725644587;5137.794488220578;5683.3748287687495;5742.942570574408;5856.736446859987;7861.842763144819;9573.473458959825;9624.133827955238;9744.707658770249;9775.755606109904;9285.396449230779;9594.520273986413;9603.423794572349;9610.807783844144;9711;6284.811455656338;6795.830104247433;7001.579905205704;7262.237465066092;7511.474196806171;8119.79700507492;8337.666493340199;8337.95831020841;8339.874901876598;8356.456830306011;7387.113546374255;7517.849643007;7528.887066694114;7670.348020418327;7674.6162691864065;6608.273089960005;7170.641467926687;7268.9273107270365;7388.6675099621725;7416.555006699616;6927.411170050602;7032.964835175791;7042.190148133031;7068.646567671493;7070.540414873017;4803.471618122074;4826.107170173529;4905.288733133747;5037.345145131109;5045.192769156917;4952.504749525018;4962.5533701967;5037.622178336566;5073.59411247092;5143.5113664201845 06:05:03 INFO - PROCESS | 1938 | |4;prototype.html;304.70595874980637;306.5738623313594;314.3445915266669;320.4600248581141;321.69439032918706;4672.836450724225;5370.133503877212;5421.349438067427;5443.60481388189;5501.202325662776;5117.769700363483;5302.946970530294;5334.146536554152;5368.436314187013;5385.623006389555;3744.868929587465;4159.292920203573;4164.625183733463;4172.311568591179;4182.393552934823;3849.1331704123477;4067.877963661095;4186.183694179636;4201.0802492267885;4209.200251952135;3399.388110140174;3832.1569254764045;3995.1011022519847;4004.419359192915;4041.939370909439;4027.1744292419985;4039.3537034074407;4040.2323558523785;4080.3471444569022;4082.4692789937417;3035.6357237131688;3190.330030693557;3350.3466823969334;3487.232808782064;3507.631698671654;4597.60920321773;4981.452040275567;5180.2026905471;5244.580433565306;5319.840404787863;4689.921318096857;4704.45898721648;4720.268358404453;4721.716696998157;4722.433308003062;4987.576056035241;5012.699238045729;5607.915881261743;5685.488306052475;5714.257161559185;3257.527658489506;3863.03424143964;3863.8840834775;3865.627702165747;3876.1588245583403;5958.880822383571;5969.731362088673;5976.432238937337;6002.399760024006;6076.817695469143;3618.113562177231;3653.3606618842127;3898.181381909774;3930.4890364252465;3934.5475270344277;3310.4206763816237;3348.4977253411694;3381.053305074534;3441.9330007697695;3804.410316400907;3505.4741788731894;3657.65252301031;4062.898427539276;4214.473190851144;4619.168549661026;5015.313744397865;5104.744762761847;5686.5499297678825;5815.4879731270175;5815.865906148345;1225.7681030564227;1236.8330275412884;1244.2037096258402;1253.0289026004737;1296.2241728831373;691.5021184746975;725.4704066031721;734.4234775701049;802.2378740196837;831.5592735850009;3955.841766329372;4148.730332528376;4156.896077598084;4230.011894409623;4301.806418711166;3982.7212095153063;4292.642967388104;4533.818647254073;4553.886152846206;4633.62930965522;4069.3895915612893;4410.360497727812;4432.138501829239;4444.644428445717;4501.447000784724;2121.3105740634046;2299.7700229977204;2575.6265341525373;2577.510273048115;2607.687077550668;4328.978355108267;4960.883801144786;4989.101961646866;5051.974740126276;5054.0686401886815;4379.824807007684;4646.930296045562;5024.371953505812;5026.220935754964;5029.17018691919;3832.827522761538;4127.174565086994;4314.482262128565;4772.260299653559;5077.669951453144;4022.445746950766;4140.896477587964;4312.266914624459;4787.54518320759;4858.90282194347;3817.8854634361014;4000.8199631017255;4149.398337241144;4671.252599584051;4883.212334854484;4389.209942210305;4696.7886445110735;4757.690750101232;4977.751112444291;5145.562627176732;3197.4084794313126;3326.9168270793425;3627.927441451209;3745.5692595350793;3833.5208098987628;5742.397048309867;5744.195812586157;5769.1634712967225;5773.278340207474;5798;4709.576138147583;4918.852434426973;5389;5538.47384498472;5576.637518561281;4969.254611808257;5488.368837583729;5565.693886836263;5567.7750894803075;5585.497305242385;4752.857414277577;5442.755076021668;5446.747248132872;5494.478024587658;5520.696361700145;5158.710322419395;5232.6249569027;5267.446918073547;5272.340957380327;5561.63849349791;5049.697018178921;5344.812931547533;5446.945530544803;5539.335279766454;5615.8876822464135 06:05:03 INFO - PROCESS | 1938 | |5;yui.html;722.9047992291679;727.6107282603807;784.4861615641756;812.6221307092209;838.387976776953;13407.597772066842;15271.312790924407;15663.843361566382;15679.216039198038;15723.449679261228;13072.346382680875;13250.807427331538;14210.218437985906;14330.713385732306;14403.13581185127;883.0595415882082;894.9731508054737;905.1310741687987;905.1672461335587;907.9954600226989;897.7172190759923;912.3932584831113;914.076782449724;918.0956757593765;918.4030380252823;944.3295260365114;960.7886265021684;962.6534447598859;962.7641227899175;965.0735294117655;951.8619800128977;954.9618015279381;956.4261443134134;957.6504575829807;960.346964064436;683.641088428579;701.9578825270501;708.6810935079184;718.1561665043553;720.272524750001;12536.623901282976;12877.67805804853;12889.355532223351;12961.092723509359;13708.3831227595;11961.581344652895;12513.499460021589;12520.373981300898;12577.937110314482;12838.508542212247;12226.510939562495;12325.69185770354;12484.563040293546;12857;12950.546730864375;3896.636177337937;4450.955490445119;4537.591616754508;4612.700174488681;4700.31845382421;66110;97221.54167687561;98620.50689746578;99709.0029099707;101005;10414.218933580012;10459.477026148663;10500.632477863239;10512.57949682004;10908.945455272831;19724.211031558865;19965.301214457424;19981.500462488264;19983.300584479763;20027.799722002885;6401.64790936503;6791.04925310447;7647.694092236359;7654.617269136521;7728.806779830436;3834.175652234783;4131.607497287753;4195.202911446876;4238.936415953764;4356.76037817923;3422.760406771502;3551.662592053751;3564.7326450516316;3664.17556049892;3840.2895464339185;702.817267510451;702.8418605813753;704.7286794584122;708.9787306380816;720.8702433561905;553.7563472072355;574.7758374234052;641.3426238105978;649.1658219188321;663.6781161136845;8481.745547633582;8977.102289771101;9047.457152570867;9112.81774364509;9305.581248843819;554.5896036932721;615.6213928433981;620.0667994667996;640.9935900641027;653.2860686994452;3688.0964163780045;4402.361657559636;4405.471343438808;4416.823327066882;4428.490723566831;82548.58725706453;83850.58074709709;106385.46807265915;113000;116079;94339.58490622646;101167.98832011585;101657.98342016633;103239;104807.4759626197;88144;88564;89190.10809891818;89196;89561;7274.6726397313305;8603.913960860562;8647.0920553341;8674.132586741276;8690.130986901258;54051.72974135104;55601.721991389786;55797.721011394686;57238.7138064307;59465.10802338055;62697;75349.4790312583;75576;75931.62034189794;76700.61649691715;11177.329360238411;11440.141989351;11594.420278986187;11615.186936914673;11772.352520611448;12192;12199.268043917393;12208;12932.288724120264;13091.756283153085;11107.944460277648;12145.757084858431;12310.692232694255;12432.818882206177;13586.79619805684;12785;13387.330633468482;13414.664633384244;13778.931105344409;13869.930650347087;12830.743385132431;12862.614121576367;13025.609231723063;13107.708569120836;13370.465181393025;8982.820343593223;10574.179121574003;10653;11065.944670276596;11201.831972520582;8931.687390941286;9859.605615775288;11375.033122184706;11399.658010259705;11403 06:05:03 INFO - PROCESS | 1938 | __end_tp_report 06:05:03 INFO - PROCESS | 1938 | __start_cc_report 06:05:03 INFO - PROCESS | 1938 | _x_x_mozilla_cycle_collect,368 06:05:03 INFO - PROCESS | 1938 | __end_cc_report 06:05:03 INFO - PROCESS | 1938 | __startTimestamp1477400703922__endTimestamp 06:05:03 INFO - PROCESS | 1938 | 06:05:03 INFO - PROCESS | 1938 | ------- Summary: start ------- 06:05:03 INFO - PROCESS | 1938 | Number of tests: 6 06:05:03 INFO - PROCESS | 1938 | 06:05:03 INFO - PROCESS | 1938 | [#0] dojo.html Cycles:165 Average:5600.86 Median:5855.80 stddev:702.69 (12.0%) stddev-sans-first:681.15 06:05:03 INFO - PROCESS | 1938 | Values: 3294.5 3314.5 3315.2 3352.6 3353.2 4344.5 5510.7 5549.1 5594.1 5640.0 5455.6 5540.3 5614.9 5615.1 5630.5 5092.5 5098.5 5135.7 5139.9 5142.5 4484.9 5161.1 5200.5 5258.2 5326.9 5057.1 5199.3 5201.2 5229.3 5244.0 5069.6 5138.1 5167.4 5172.3 5224.6 5060.3 5089.6 5133.3 5135.7 5194.5 5352.5 5500.7 5517.4 5637.4 5664.0 6029.3 6051.9 6078.9 6127.5 6157.4 6156.4 6174.8 6185.2 6195.1 6202.0 5528.1 5662.9 5672.2 5712.3 5720.7 6145.1 6165.1 6199.7 6226.4 6275.5 6013.9 6015.3 6059.4 6077.9 6123.2 6026.9 6150.4 6156.4 6167.3 6191.9 5664.0 5944.7 5954.2 5963.4 6089.0 6010.0 6036.0 6056.1 6065.6 6078.5 4145.5 4781.1 4786.4 4800.2 4811.5 3414.0 3441.6 4198.7 4235.5 4350.7 5503.3 5666.5 5677.7 5715.9 5746.6 6043.2 6147.3 6186.1 6189.8 6203.7 5574.8 5619.7 5636.9 5649.5 5667.5 4299.8 4617.7 5338.8 5349.1 5402.9 6219.2 6224.7 6231.2 6246.7 6247.4 6051.3 6066.8 6106.1 6218.1 6251.2 6147.8 6193.5 6246.0 6267.4 6268.2 6098.7 6119.2 6138.3 6152.7 6157.5 5986.5 6110.1 6118.2 6118.8 6136.9 5915.8 5947.6 6006.9 6043.9 6075.3 5972.5 5986.9 6006.2 6024.4 6042.9 3786.1 5362.5 5813.2 5831.8 5855.8 5970.5 6019.6 6020.6 6042.2 6071.8 5813.4 5822.7 5874.1 5893.4 5952.9 06:05:03 INFO - PROCESS | 1938 | 06:05:03 INFO - PROCESS | 1938 | [#1] ext.html Cycles:180 Average:66951.64 Median:39842.00 stddev:147519.92 (370.3%) stddev-sans-first:147849.33 06:05:03 INFO - PROCESS | 1938 | Values: 481.4 517.5 530.2 532.3 532.5 30071.5 39378.0 39973.8 40832.0 42742.0 42462.6 42956.6 42988.0 43249.8 43310.8 9504.6 9686.0 9699.0 9720.9 9744.4 9616.7 9642.5 9686.2 9692.0 9703.9 8505.3 9564.8 9722.1 9743.9 9747.6 9617.4 9639.0 9692.0 9698.0 9725.0 9513.8 9576.1 9587.4 9621.7 9651.8 40723.6 41040.6 41144.0 41205.8 41250.0 3334.7 3399.9 3558.8 3924.7 3961.6 28554.3 31001.9 31275.7 31346.8 32301.5 3221.6 3252.6 3265.3 3315.9 3362.9 251263.0 360239.0 360761.0 361009.0 362805.0 60269.7 60415.7 60686.0 60735.8 60798.4 63368.0 63708.4 63776.7 64068.7 64161.0 15792.0 15892.8 16020.4 16022.9 16083.4 12310.2 12376.3 12402.8 12416.9 12452.7 16690.8 16704.6 16709.7 16753.8 16842.2 2760.2 6992.7 7133.9 7216.6 7293.0 881.5 924.0 945.5 1007.6 1052.2 29410.0 30057.5 30378.4 30473.0 30541.9 996.0 1031.5 1047.2 1062.2 1114.2 14306.4 15722.5 15796.5 15878.0 16056.8 656962.0 657411.0 659793.0 664122.0 665614.3 56284.4 56528.4 56717.1 56909.1 57151.0 1354.6 1376.8 1380.8 1397.7 1400.0 23785.0 28326.0 29875.0 29875.6 30546.2 50425.7 50743.7 50916.0 50954.0 51310.2 592051.0 594669.0 596395.0 596742.3 599900.0 26423.3 26489.5 26634.6 26769.0 26775.7 44227.6 46899.0 47960.5 48223.8 48603.0 38673.6 38907.6 39710.2 40413.8 40785.8 33268.8 33994.0 34633.7 34727.1 40610.4 32237.0 38172.2 38760.0 39059.6 39277.8 31317.2 31727.0 33933.0 34689.5 34743.7 22916.0 24209.8 24468.5 25480.6 25735.6 06:05:03 INFO - PROCESS | 1938 | 06:05:03 INFO - PROCESS | 1938 | [#2] jquery.html Cycles:180 Average:34372.50 Median:372.73 stddev:94025.83 (25226.2%) stddev-sans-first:94254.93 06:05:03 INFO - PROCESS | 1938 | Values: 370.5 375.0 381.7 381.9 401.9 18685.8 20390.0 21039.5 21214.5 21275.8 19908.8 20231.8 20599.7 20718.3 21008.6 4772.6 5690.5 5912.2 6004.9 6007.4 4131.4 4365.1 5890.6 5891.4 5923.6 6232.8 6540.6 6589.5 6595.1 6652.7 6349.7 6580.0 6611.6 6624.4 6643.3 5174.8 5393.9 5658.5 5696.5 5706.8 25555.9 25677.6 25769.8 26015.9 26042.3 2222.1 2268.2 2350.5 2923.8 3078.8 15127.5 15250.7 15270.6 15483.5 16027.6 1789.8 1810.3 1865.5 1888.5 1912.5 81121.8 89635.8 118968.4 123317.0 124983.4 8381.4 9284.6 9947.3 10610.8 10741.2 13072.2 13489.0 13502.4 13506.4 13561.5 7420.4 7583.5 8389.7 8427.8 8504.4 6919.9 8461.6 8510.2 8598.5 8603.8 5307.3 6323.6 6523.6 6525.2 6528.6 1443.5 1549.6 1775.4 1808.8 1824.6 890.6 922.4 944.3 946.1 951.5 20971.2 21749.7 22348.6 22355.4 22562.8 860.5 943.4 983.7 1026.6 1053.5 8228.7 10629.8 11106.3 11119.9 11173.0 506792.0 510087.0 512689.0 618771.0 628705.0 138884.9 146977.3 149027.0 150817.2 152038.2 803.9 951.3 989.1 991.3 993.4 18314.7 18741.4 18772.6 19028.8 19227.2 48847.0 49865.8 55130.2 56764.0 57755.7 81058.0 81374.8 81618.2 82008.0 83221.0 7619.8 7620.1 7665.9 7777.6 8038.1 10547.4 12338.2 12856.7 13102.7 13433.5 9908.5 10997.8 11178.7 11191.8 11912.5 11018.7 11394.7 11508.2 11773.6 11780.5 11015.4 11359.3 11368.7 11599.2 11625.4 7949.1 7961.0 8189.0 8291.9 8366.8 10294.2 10465.4 10619.8 11454.4 11704.9 06:05:03 INFO - PROCESS | 1938 | 06:05:03 INFO - PROCESS | 1938 | [#3] mootools.html Cycles:180 Average:5556.80 Median:5105.69 stddev:3104.88 (60.8%) stddev-sans-first:3098.61 06:05:03 INFO - PROCESS | 1938 | Values: 1497.4 1540.4 1767.3 1889.2 1921.3 6600.5 8023.3 8065.0 8168.7 8318.1 7412.4 8350.5 8630.5 8685.0 8805.7 2264.2 2282.5 2289.3 2293.0 2296.4 2017.7 2122.5 2293.3 2301.7 2305.1 2266.0 2266.4 2277.4 2281.0 2292.9 2251.5 2258.1 2270.8 2272.1 2279.8 2207.5 2208.1 2208.1 2216.2 2223.0 8642.9 8778.8 8801.8 8812.7 8824.5 5154.5 5316.1 5318.5 5319.6 5328.5 2849.5 3253.8 3565.4 3571.6 3580.1 1002.8 1044.3 1050.0 1052.3 1061.5 11282.8 11295.9 11314.0 11419.7 11441.0 4436.8 4565.9 4638.5 4677.0 4679.6 9798.3 9842.9 9875.8 9891.1 9903.2 5262.0 5263.6 5269.8 5275.2 5276.4 4089.4 4787.2 4812.2 4823.0 4823.5 3295.9 3740.1 3790.8 3851.7 3896.1 1261.3 1263.2 1287.1 1288.5 1296.1 896.7 904.9 919.5 937.2 943.0 5695.4 5733.5 5766.2 5835.7 5873.5 776.3 912.3 964.7 995.4 1006.2 3629.1 3733.8 3821.8 3826.8 3841.6 10014.2 10227.7 10444.3 10505.2 10551.8 10458.4 10477.4 10484.2 10519.2 10553.8 7845.8 8129.9 8212.0 8242.9 8255.5 4607.8 5137.8 5683.4 5742.9 5856.7 7861.8 9573.5 9624.1 9744.7 9775.8 9285.4 9594.5 9603.4 9610.8 9711.0 6284.8 6795.8 7001.6 7262.2 7511.5 8119.8 8337.7 8338.0 8339.9 8356.5 7387.1 7517.8 7528.9 7670.3 7674.6 6608.3 7170.6 7268.9 7388.7 7416.6 6927.4 7033.0 7042.2 7068.6 7070.5 4803.5 4826.1 4905.3 5037.3 5045.2 4952.5 4962.6 5037.6 5073.6 5143.5 06:05:03 INFO - PROCESS | 1938 | 06:05:03 INFO - PROCESS | 1938 | [#4] prototype.html Cycles:180 Average:4250.38 Median:4608.39 stddev:1327.36 (28.8%) stddev-sans-first:1297.63 06:05:03 INFO - PROCESS | 1938 | Values: 304.7 306.6 314.3 320.5 321.7 4672.8 5370.1 5421.3 5443.6 5501.2 5117.8 5302.9 5334.1 5368.4 5385.6 3744.9 4159.3 4164.6 4172.3 4182.4 3849.1 4067.9 4186.2 4201.1 4209.2 3399.4 3832.2 3995.1 4004.4 4041.9 4027.2 4039.4 4040.2 4080.3 4082.5 3035.6 3190.3 3350.3 3487.2 3507.6 4597.6 4981.5 5180.2 5244.6 5319.8 4689.9 4704.5 4720.3 4721.7 4722.4 4987.6 5012.7 5607.9 5685.5 5714.3 3257.5 3863.0 3863.9 3865.6 3876.2 5958.9 5969.7 5976.4 6002.4 6076.8 3618.1 3653.4 3898.2 3930.5 3934.5 3310.4 3348.5 3381.1 3441.9 3804.4 3505.5 3657.7 4062.9 4214.5 4619.2 5015.3 5104.7 5686.5 5815.5 5815.9 1225.8 1236.8 1244.2 1253.0 1296.2 691.5 725.5 734.4 802.2 831.6 3955.8 4148.7 4156.9 4230.0 4301.8 3982.7 4292.6 4533.8 4553.9 4633.6 4069.4 4410.4 4432.1 4444.6 4501.4 2121.3 2299.8 2575.6 2577.5 2607.7 4329.0 4960.9 4989.1 5052.0 5054.1 4379.8 4646.9 5024.4 5026.2 5029.2 3832.8 4127.2 4314.5 4772.3 5077.7 4022.4 4140.9 4312.3 4787.5 4858.9 3817.9 4000.8 4149.4 4671.3 4883.2 4389.2 4696.8 4757.7 4977.8 5145.6 3197.4 3326.9 3627.9 3745.6 3833.5 5742.4 5744.2 5769.2 5773.3 5798.0 4709.6 4918.9 5389.0 5538.5 5576.6 4969.3 5488.4 5565.7 5567.8 5585.5 4752.9 5442.8 5446.7 5494.5 5520.7 5158.7 5232.6 5267.4 5272.3 5561.6 5049.7 5344.8 5446.9 5539.3 5615.9 06:05:03 INFO - PROCESS | 1938 | 06:05:03 INFO - PROCESS | 1938 | [#5] yui.html Cycles:180 Average:20618.62 Median:4439.72 stddev:30424.44 (685.3%) stddev-sans-first:30473.11 06:05:03 INFO - PROCESS | 1938 | Values: 722.9 727.6 784.5 812.6 838.4 13407.6 15271.3 15663.8 15679.2 15723.4 13072.3 13250.8 14210.2 14330.7 14403.1 883.1 895.0 905.1 905.2 908.0 897.7 912.4 914.1 918.1 918.4 944.3 960.8 962.7 962.8 965.1 951.9 955.0 956.4 957.7 960.3 683.6 702.0 708.7 718.2 720.3 12536.6 12877.7 12889.4 12961.1 13708.4 11961.6 12513.5 12520.4 12577.9 12838.5 12226.5 12325.7 12484.6 12857.0 12950.5 3896.6 4451.0 4537.6 4612.7 4700.3 66110.0 97221.5 98620.5 99709.0 101005.0 10414.2 10459.5 10500.6 10512.6 10908.9 19724.2 19965.3 19981.5 19983.3 20027.8 6401.6 6791.0 7647.7 7654.6 7728.8 3834.2 4131.6 4195.2 4238.9 4356.8 3422.8 3551.7 3564.7 3664.2 3840.3 702.8 702.8 704.7 709.0 720.9 553.8 574.8 641.3 649.2 663.7 8481.7 8977.1 9047.5 9112.8 9305.6 554.6 615.6 620.1 641.0 653.3 3688.1 4402.4 4405.5 4416.8 4428.5 82548.6 83850.6 106385.5 113000.0 116079.0 94339.6 101168.0 101658.0 103239.0 104807.5 88144.0 88564.0 89190.1 89196.0 89561.0 7274.7 8603.9 8647.1 8674.1 8690.1 54051.7 55601.7 55797.7 57238.7 59465.1 62697.0 75349.5 75576.0 75931.6 76700.6 11177.3 11440.1 11594.4 11615.2 11772.4 12192.0 12199.3 12208.0 12932.3 13091.8 11107.9 12145.8 12310.7 12432.8 13586.8 12785.0 13387.3 13414.7 13778.9 13869.9 12830.7 12862.6 13025.6 13107.7 13370.5 8982.8 10574.2 10653.0 11065.9 11201.8 8931.7 9859.6 11375.0 11399.7 11403.0 06:05:03 INFO - PROCESS | 1938 | -------- Summary: end -------- 06:05:03 INFO - PROCESS | 1938 | 06:05:04 INFO - TEST-INFO | 1938: exit 0 06:05:04 INFO - TEST-OK | dromaeo_css | took 1114453ms 06:05:04 INFO - TEST-START | kraken 06:05:04 INFO - operating with platform_type : mac_ 06:05:04 INFO - Initialising browser for kraken test... 06:05:04 INFO - TEST-INFO | started process 1943 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/tmpnfUfId/profile http://localhost:50906/getInfo.html) 06:05:07 INFO - PROCESS | 1943 | 2016-10-25 06:05:07.261 plugin-container[1944:14922] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a43, name = 'com.apple.tsm.portname' 06:05:07 INFO - PROCESS | 1943 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:05:07 INFO - PROCESS | 1943 | 2016-10-25 06:05:07.264 plugin-container[1944:14922] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0f, name = 'com.apple.CFPasteboardClient' 06:05:07 INFO - PROCESS | 1943 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:05:07 INFO - PROCESS | 1943 | 2016-10-25 06:05:07.264 plugin-container[1944:14922] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:05:07 INFO - PROCESS | 1943 | __metrics Screen width/height:1600/1200 06:05:07 INFO - PROCESS | 1943 | colorDepth:24 06:05:07 INFO - PROCESS | 1943 | Browser inner width/height: 1024/689 06:05:07 INFO - PROCESS | 1943 | __metrics 06:05:07 ERROR - PROCESS | 1943 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3097: TypeError: this.tabs is undefined 06:05:08 INFO - TEST-INFO | 1943: exit 0 06:05:08 INFO - Browser initialized. 06:05:08 INFO - Running cycle 1/1 for kraken test... 06:05:08 INFO - TEST-INFO | started process 1945 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/tmpnfUfId/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:05:10 INFO - PROCESS | 1945 | 2016-10-25 06:05:10.714 plugin-container[1946:15040] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 06:05:10 INFO - PROCESS | 1945 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:05:10 INFO - PROCESS | 1945 | 2016-10-25 06:05:10.715 plugin-container[1946:15040] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b17, name = 'com.apple.CFPasteboardClient' 06:05:10 INFO - PROCESS | 1945 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:05:10 INFO - PROCESS | 1945 | 2016-10-25 06:05:10.715 plugin-container[1946:15040] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:05:20 INFO - PROCESS | 1945 | RSS: Main: 235905024 06:05:20 INFO - PROCESS | 1945 | 06:05:23 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50906/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50906/tests/kraken/driver.html?testName=audio-beat-detection) 06:05:24 INFO - PROCESS | 1945 | RSS: Main: 248651776 06:05:24 INFO - PROCESS | 1945 | 06:05:27 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50906/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50906/tests/kraken/driver.html?testName=audio-dft) 06:05:27 INFO - PROCESS | 1945 | RSS: Main: 257323008 06:05:27 INFO - PROCESS | 1945 | 06:05:31 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50906/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50906/tests/kraken/driver.html?testName=audio-fft) 06:05:31 INFO - PROCESS | 1945 | RSS: Main: 259403776 06:05:31 INFO - PROCESS | 1945 | 06:05:33 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50906/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50906/tests/kraken/driver.html?testName=audio-oscillator) 06:05:34 INFO - PROCESS | 1945 | RSS: Main: 260317184 06:05:34 INFO - PROCESS | 1945 | 06:05:36 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50906/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50906/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:05:36 INFO - PROCESS | 1945 | RSS: Main: 260599808 06:05:36 INFO - PROCESS | 1945 | 06:05:39 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50906/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50906/tests/kraken/driver.html?testName=imaging-darkroom) 06:05:40 INFO - PROCESS | 1945 | RSS: Main: 260694016 06:05:40 INFO - PROCESS | 1945 | 06:05:43 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50906/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50906/tests/kraken/driver.html?testName=imaging-desaturate) 06:05:44 INFO - PROCESS | 1945 | RSS: Main: 262201344 06:05:44 INFO - PROCESS | 1945 | 06:05:46 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50906/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50906/tests/kraken/driver.html?testName=json-parse-financial) 06:05:47 INFO - PROCESS | 1945 | RSS: Main: 262643712 06:05:47 INFO - PROCESS | 1945 | 06:05:49 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50906/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50906/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:05:49 INFO - PROCESS | 1945 | RSS: Main: 262320128 06:05:49 INFO - PROCESS | 1945 | 06:05:51 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50906/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50906/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:05:52 INFO - PROCESS | 1945 | RSS: Main: 262701056 06:05:52 INFO - PROCESS | 1945 | 06:05:54 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50906/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50906/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:05:55 INFO - PROCESS | 1945 | RSS: Main: 262459392 06:05:55 INFO - PROCESS | 1945 | 06:05:58 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50906/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50906/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:05:58 INFO - PROCESS | 1945 | RSS: Main: 262643712 06:05:58 INFO - PROCESS | 1945 | 06:06:01 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50906/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50906/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:06:02 INFO - PROCESS | 1945 | RSS: Main: 262635520 06:06:02 INFO - PROCESS | 1945 | 06:06:04 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50906/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50906/tests/kraken/driver.html?testName=ai-astar) 06:06:04 INFO - PROCESS | 1945 | RSS: Main: 263995392 06:06:04 INFO - PROCESS | 1945 | 06:06:04 INFO - PROCESS | 1945 | __start_tp_report 06:06:04 INFO - PROCESS | 1945 | _x_x_mozilla_page_load 06:06:04 INFO - PROCESS | 1945 | _x_x_mozilla_page_load_details 06:06:04 INFO - PROCESS | 1945 | |i|pagename|runs| 06:06:04 INFO - PROCESS | 1945 | |0;ai-astar;105;116;118;132;171;100;98;173;172;158 06:06:04 INFO - PROCESS | 1945 | |1;audio-beat-detection;146;115;113;151;114;114;115;115;147;114 06:06:04 INFO - PROCESS | 1945 | |2;audio-dft;210;203;233;203;196;199;200;202;196;201 06:06:04 INFO - PROCESS | 1945 | |3;audio-fft;83;66;83;109;88;62;59;59;79;58 06:06:04 INFO - PROCESS | 1945 | |4;audio-oscillator;87;57;87;84;98;101;111;70;69;88 06:06:04 INFO - PROCESS | 1945 | |5;imaging-gaussian-blur;93;120;88;83;89;86;89;86;88;86 06:06:04 INFO - PROCESS | 1945 | |6;imaging-darkroom;95;94;95;94;120;131;126;154;149;93 06:06:04 INFO - PROCESS | 1945 | |7;imaging-desaturate;93;95;88;85;91;88;104;134;89;88 06:06:04 INFO - PROCESS | 1945 | |8;json-parse-financial;55;56;55;57;56;56;56;57;58;56 06:06:04 INFO - PROCESS | 1945 | |9;json-stringify-tinderbox;100;93;77;80;73;68;70;71;72;76 06:06:04 INFO - PROCESS | 1945 | |10;stanford-crypto-aes;103;87;90;88;90;88;88;90;86;92 06:06:04 INFO - PROCESS | 1945 | |11;stanford-crypto-ccm;149;152;149;148;150;144;151;161;219;158 06:06:04 INFO - PROCESS | 1945 | |12;stanford-crypto-pbkdf2;172;178;188;238;176;185;172;200;169;204 06:06:04 INFO - PROCESS | 1945 | |13;stanford-crypto-sha256-iterative;68;71;68;68;74;88;76;69;69;70 06:06:04 INFO - PROCESS | 1945 | __end_tp_report 06:06:04 INFO - PROCESS | 1945 | __start_cc_report 06:06:04 INFO - PROCESS | 1945 | _x_x_mozilla_cycle_collect,940 06:06:04 INFO - PROCESS | 1945 | __end_cc_report 06:06:04 INFO - PROCESS | 1945 | __startTimestamp1477400764548__endTimestamp 06:06:04 INFO - PROCESS | 1945 | 06:06:04 INFO - PROCESS | 1945 | ------- Summary: start ------- 06:06:04 INFO - PROCESS | 1945 | Number of tests: 14 06:06:04 INFO - PROCESS | 1945 | 06:06:04 INFO - PROCESS | 1945 | [#0] ai-astar Cycles:10 Average:134.30 Median:164.50 stddev:31.24 (19.0%) stddev-sans-first:31.29 06:06:04 INFO - PROCESS | 1945 | Values: 105.0 116.0 118.0 132.0 171.0 100.0 98.0 173.0 172.0 158.0 06:06:04 INFO - PROCESS | 1945 | 06:06:04 INFO - PROCESS | 1945 | [#1] audio-beat-detection Cycles:10 Average:124.40 Median:115.00 stddev:16.34 (14.2%) stddev-sans-first:15.35 06:06:04 INFO - PROCESS | 1945 | Values: 146.0 115.0 113.0 151.0 114.0 114.0 115.0 115.0 147.0 114.0 06:06:04 INFO - PROCESS | 1945 | 06:06:04 INFO - PROCESS | 1945 | [#2] audio-dft Cycles:10 Average:204.30 Median:202.50 stddev:10.85 (5.4%) stddev-sans-first:11.31 06:06:04 INFO - PROCESS | 1945 | Values: 210.0 203.0 233.0 203.0 196.0 199.0 200.0 202.0 196.0 201.0 06:06:04 INFO - PROCESS | 1945 | 06:06:04 INFO - PROCESS | 1945 | [#3] audio-fft Cycles:10 Average:74.60 Median:72.50 stddev:16.73 (23.1%) stddev-sans-first:17.46 06:06:04 INFO - PROCESS | 1945 | Values: 83.0 66.0 83.0 109.0 88.0 62.0 59.0 59.0 79.0 58.0 06:06:04 INFO - PROCESS | 1945 | 06:06:04 INFO - PROCESS | 1945 | [#4] audio-oscillator Cycles:10 Average:85.20 Median:85.50 stddev:16.27 (19.0%) stddev-sans-first:17.25 06:06:04 INFO - PROCESS | 1945 | Values: 87.0 57.0 87.0 84.0 98.0 101.0 111.0 70.0 69.0 88.0 06:06:04 INFO - PROCESS | 1945 | 06:06:04 INFO - PROCESS | 1945 | [#5] imaging-gaussian-blur Cycles:10 Average:90.80 Median:88.00 stddev:10.59 (12.0%) stddev-sans-first:11.20 06:06:04 INFO - PROCESS | 1945 | Values: 93.0 120.0 88.0 83.0 89.0 86.0 89.0 86.0 88.0 86.0 06:06:04 INFO - PROCESS | 1945 | 06:06:04 INFO - PROCESS | 1945 | [#6] imaging-darkroom Cycles:10 Average:115.10 Median:93.50 stddev:24.14 (25.8%) stddev-sans-first:24.48 06:06:04 INFO - PROCESS | 1945 | Values: 95.0 94.0 95.0 94.0 120.0 131.0 126.0 154.0 149.0 93.0 06:06:04 INFO - PROCESS | 1945 | 06:06:04 INFO - PROCESS | 1945 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:88.50 stddev:14.54 (16.4%) stddev-sans-first:15.39 06:06:04 INFO - PROCESS | 1945 | Values: 93.0 95.0 88.0 85.0 91.0 88.0 104.0 134.0 89.0 88.0 06:06:04 INFO - PROCESS | 1945 | 06:06:04 INFO - PROCESS | 1945 | [#8] json-parse-financial Cycles:10 Average:56.20 Median:56.00 stddev:0.92 (1.6%) stddev-sans-first:0.87 06:06:04 INFO - PROCESS | 1945 | Values: 55.0 56.0 55.0 57.0 56.0 56.0 56.0 57.0 58.0 56.0 06:06:04 INFO - PROCESS | 1945 | 06:06:04 INFO - PROCESS | 1945 | [#9] json-stringify-tinderbox Cycles:10 Average:78.00 Median:74.50 stddev:10.50 (14.1%) stddev-sans-first:7.54 06:06:04 INFO - PROCESS | 1945 | Values: 100.0 93.0 77.0 80.0 73.0 68.0 70.0 71.0 72.0 76.0 06:06:04 INFO - PROCESS | 1945 | 06:06:04 INFO - PROCESS | 1945 | [#10] stanford-crypto-aes Cycles:10 Average:90.20 Median:89.00 stddev:4.83 (5.4%) stddev-sans-first:1.86 06:06:04 INFO - PROCESS | 1945 | Values: 103.0 87.0 90.0 88.0 90.0 88.0 88.0 90.0 86.0 92.0 06:06:04 INFO - PROCESS | 1945 | 06:06:04 INFO - PROCESS | 1945 | [#11] stanford-crypto-ccm Cycles:10 Average:158.10 Median:151.50 stddev:21.95 (14.5%) stddev-sans-first:23.03 06:06:04 INFO - PROCESS | 1945 | Values: 149.0 152.0 149.0 148.0 150.0 144.0 151.0 161.0 219.0 158.0 06:06:04 INFO - PROCESS | 1945 | 06:06:04 INFO - PROCESS | 1945 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.20 Median:186.50 stddev:21.15 (11.3%) stddev-sans-first:21.60 06:06:04 INFO - PROCESS | 1945 | Values: 172.0 178.0 188.0 238.0 176.0 185.0 172.0 200.0 169.0 204.0 06:06:04 INFO - PROCESS | 1945 | 06:06:04 INFO - PROCESS | 1945 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.10 Median:70.50 stddev:6.21 (8.8%) stddev-sans-first:6.41 06:06:04 INFO - PROCESS | 1945 | Values: 68.0 71.0 68.0 68.0 74.0 88.0 76.0 69.0 69.0 70.0 06:06:04 INFO - PROCESS | 1945 | -------- Summary: end -------- 06:06:04 INFO - PROCESS | 1945 | 06:06:05 INFO - TEST-INFO | 1945: exit 0 06:06:05 INFO - TEST-OK | kraken | took 60822ms 06:06:05 INFO - SUITE-END | took 1175s 06:06:05 INFO - Completed test suite (00:19:35) 06:06:05 INFO - javascript benchmark 06:06:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7154.90971019574, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3294.505824126381, 3314.486254630533, 3315.2043509557684, 3352.6479719629447, 3353.178471274538, 4344.500382456018, 5510.669359838413, 5549.139883318087, 5594.104943209088, 5639.984802735506, 5455.5908306877045, 5540.279763630722, 5614.9157762633595, 5615.101583746601, 5630.493255606994, 5092.465291144432, 5098.464661210584, 5135.743212839343, 5139.897202055957, 5142.511461411179, 4484.902106544924, 5161.122609156434, 5200.453952334989, 5258.237555554442, 5326.92009619856, 5057.064443078042, 5199.324087868553, 5201.193814958649, 5229.267902493654, 5243.977424402243, 5069.645124841262, 5138.126518491866, 5167.431582525956, 5172.32759741235, 5224.582033437316, 5060.291559181717, 5089.643724939256, 5133.281340612317, 5135.666181698178, 5194.480551944813, 5352.545033672142, 5500.724963751836, 5517.393086760453, 5637.3517045539875, 5663.9716801415725, 6029.306629737549, 6051.909221361683, 6078.939210607881, 6127.504844864458, 6157.384261573851, 6156.415140561639, 6174.783882564134, 6185.195924529828, 6195.101710251988, 6201.9689901550655, 5528.115501519737, 5662.886742265132, 5672.234248376499, 5712.257406537124, 5720.685362305072, 6145.139680444741, 6165.075238714228, 6199.721012554446, 6226.40849119333, 6275.466585340298, 6013.865077897371, 6015.2781666200335, 6059.394060593905, 6077.9392206078255, 6123.173371594778, 6026.939730602638, 6150.354212807591, 6156.415140561639, 6167.259928808572, 6191.907121393183, 5663.9716801415725, 5944.7324870380935, 5954.166416701665, 5963.4334738199805, 6089.0, 6010.0, 6036.034234522455, 6056.121862330026, 6065.636061836304, 6078.483328917003, 4145.461090058273, 4781.091592597395, 4786.401699787527, 4800.183968725325, 4811.518848115161, 3413.9829300853335, 3441.5525981622227, 4198.685098617616, 4235.53409124996, 4350.695451318377, 5503.284573005483, 5666.46168613981, 5677.744501497443, 5715.914261286084, 5746.597738158289, 6043.153958445821, 6147.293061297919, 6186.072089186658, 6189.783357582463, 6203.689815509207, 5574.777008919597, 5619.690916999522, 5636.887262254814, 5649.4633009864, 5667.489925906688, 4299.763513006752, 4617.683960071364, 5338.813141540028, 5349.117395629678, 5402.91895621566, 6219.160413344141, 6224.719887604978, 6231.22109736283, 6246.6876656167915, 6247.437730604086, 6051.273847138372, 6066.817995460143, 6106.126990750407, 6218.06728990655, 6251.218597675291, 6147.754089836357, 6193.504519638508, 6246.0, 6267.373262673878, 6268.216472940882, 6098.725557349841, 6119.204503414528, 6138.324784273816, 6152.723127459186, 6157.507399408126, 5986.491148252448, 6110.114033465212, 6118.162361256354, 6118.785842495491, 6136.926037943253, 5915.822525324247, 5947.553933454921, 6006.909896351645, 6043.942310095627, 6075.27096748393, 5972.492338151305, 5986.88026239464, 6006.249218847644, 6024.427679370453, 6042.912275790224, 3786.053486628343, 5362.463753624763, 5813.244278243801, 5831.825045248649, 5855.795047173328, 5970.462658360769, 6019.638821670714, 6020.638761674313, 6042.244719410074, 6071.848203794941, 5813.3895940925595, 5822.679752613647, 5874.118882167709, 5893.381194974466, 5952.910706339322], "value": 5553.952523197631, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [481.3694060780378, 517.4876871896822, 530.1755769777997, 532.29205157141, 532.5393534592579, 30071.548926766118, 39378.0, 39973.800130999305, 40831.97920052, 42742.0, 42462.57537424633, 42956.570434295645, 42988.0, 43249.78375108128, 43310.7834460828, 9504.619815207401, 9686.0, 9698.951505242463, 9720.854187187162, 9744.366616169926, 9616.663416780415, 9642.517874106301, 9686.176674982635, 9692.0, 9703.902960970407, 8505.277051450634, 9564.80870382592, 9722.076402741764, 9743.853842192373, 9747.610095596168, 9617.42295462271, 9639.0, 9692.030796920288, 9698.030196980317, 9725.027497250288, 9513.762155946157, 9576.138147566753, 9587.42475451468, 9621.663241786573, 9651.758706032335, 40723.59276407257, 41040.58959410398, 41144.0, 41205.79397103025, 41249.96875078117, 3334.733221342287, 3399.9490007649906, 3558.822058897045, 3924.725269231155, 3961.564227934925, 28554.286142846388, 31001.914932977463, 31275.687243127504, 31346.843265783755, 32301.51547726786, 3221.5811944447305, 3252.560904277916, 3265.281638039651, 3315.8507867146036, 3362.899113026613, 251263.0, 360239.0, 360761.0, 361009.0, 362804.9999999974, 60269.69865150734, 60415.69792151011, 60686.0, 60735.78528429495, 60798.39201607927, 63368.0, 63708.36291637024, 63776.68111659505, 64068.679656601416, 64161.0, 15792.0, 15892.841071589137, 16020.359185632444, 16022.919885400499, 16083.437079702155, 12310.199837010565, 12376.257424554555, 12402.75194496105, 12416.875831241752, 12452.688682783002, 16690.83309166917, 16704.582385440463, 16709.74935375946, 16753.832461675473, 16842.24209910557, 2760.1857452051695, 6992.650367481708, 7133.89299160513, 7216.567005979553, 7292.96353518229, 881.4843316659826, 923.9861402078974, 945.5461378538203, 1007.6271779441654, 1052.2003277509148, 29410.0, 30057.549136762966, 30378.392432151235, 30473.0, 30541.85236284948, 995.9502024898726, 1031.515187861704, 1047.2145890582065, 1062.2458054781036, 1114.2478826791883, 14306.356213970397, 15722.528324150293, 15796.526104216891, 15878.047317161007, 16056.75914861278, 656962.0, 657411.0, 659793.0, 664122.0, 665614.343856565, 56284.43715562874, 56528.43471565314, 56717.14924276139, 56909.14636280459, 57151.0, 1354.6003928840819, 1376.7728324826292, 1380.827396575428, 1397.699494608664, 1399.979000314996, 23785.0, 28326.00858969926, 29875.0, 29875.55186672158, 30546.236344090685, 50425.73935651491, 50743.74628126836, 50915.999999998516, 50954.0, 51310.177940213645, 592051.0, 594669.026654864, 596395.0, 596742.2606191407, 599900.0, 26423.339416513973, 26489.470210596064, 26634.600480993187, 26769.0, 26775.732242677324, 44227.557724423634, 46899.0, 47960.5203947956, 48223.75888120537, 48603.0, 38673.61326386813, 38907.610923890395, 39710.205795884496, 40413.79793101016, 40785.77642670725, 33268.833655831564, 33993.98018059462, 34633.65366346405, 34727.13182170466, 40610.39084413677, 32237.0, 38172.23655526929, 38760.0, 39059.60940390673, 39277.803610982905, 31317.217069573442, 31727.0, 33933.0, 34689.479657805656, 34743.65256347404, 22916.0, 24209.757902421454, 24468.51062978766, 25480.617790733526, 25735.613965790904], "value": 19607.75409543228, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.4942753141962, 374.9662530372266, 381.66222892739927, 381.875890335641, 401.9296623090958, 18685.760826658527, 20390.0, 21039.47401314968, 21214.469638259052, 21275.787242127575, 19908.800911990875, 20231.79768202321, 20599.691004634944, 20718.342532597395, 21008.57982840346, 4772.6181905447565, 5690.516306113986, 5912.201852749867, 6004.85907677542, 6007.369226231248, 4131.421600975851, 4365.105153443552, 5890.5582081343855, 5891.351951285355, 5923.555733319997, 6232.844178895519, 6540.607563546178, 6589.472842172615, 6595.109660195884, 6652.70062847173, 6349.650769207692, 6579.9671001645165, 6611.570247933917, 6624.403803657646, 6643.269240383554, 5174.809793747421, 5393.946060539422, 5658.547316214735, 5696.515796157331, 5706.828795136111, 25555.87222063878, 25677.61483577729, 25769.840357183974, 26015.869920650464, 26042.34894127643, 2222.0667319725753, 2268.206127855254, 2350.471143992605, 2923.751481124086, 3078.7690923180853, 15127.470538531099, 15250.694986100216, 15270.618234544114, 15483.458078967182, 16027.599310017225, 1789.8389144976884, 1810.3482746211353, 1865.4590168851016, 1888.4617883903022, 1912.502749285182, 81121.78317325245, 89635.75910602222, 118968.40515797453, 123317.0, 124983.3750831249, 8381.371397145238, 9284.582193801296, 9947.303688741864, 10610.840837387444, 10741.19441041925, 13072.215667059816, 13489.0, 13502.392392342368, 13506.392212350467, 13561.525346612822, 7420.443466740016, 7583.544987300669, 8389.748307550783, 8427.789305267417, 8504.44721093127, 6919.861602767916, 8461.61922713467, 8510.234078932926, 8598.527081010605, 8603.827923441497, 5307.310049693592, 6323.620582765049, 6523.608583484911, 6525.249596296392, 6528.640924749089, 1443.5452832357773, 1549.5971047527498, 1775.4318618042362, 1808.7790741249867, 1824.6350729854082, 890.5547226386807, 922.3589605224305, 944.2729098594182, 946.1295608040482, 951.4766878216954, 20971.161153553992, 21749.67375489369, 22348.553028939332, 22355.44111397228, 22562.774372256066, 860.475110182788, 943.4433684126399, 983.7393090830935, 1026.630413051301, 1053.5311786255043, 8228.670853165926, 10629.84055239172, 11106.278091924, 11119.943605357477, 11173.0, 506792.0, 510087.0, 512689.0, 618771.0, 628705.0, 138884.91672624918, 146977.26511367375, 149027.0, 150817.24591376973, 152038.23980880025, 803.935685145187, 951.3103000324847, 989.0603926270072, 991.311038828022, 993.4089216915924, 18314.725279120557, 18741.437756867315, 18772.6245475087, 19028.809711902704, 19227.230910763414, 48847.0, 49865.75335616639, 55130.17304740352, 56764.0, 57755.71122144531, 81058.0, 81374.77937831056, 81618.18381816105, 82008.0, 83221.0, 7619.809504762426, 7620.085589729268, 7665.923340766521, 7777.611119443892, 8038.115807261313, 10547.419891906018, 12338.198017128858, 12856.678583035125, 13102.672433189247, 13433.462661493822, 9908.504574771088, 10997.835032474359, 11178.658560972735, 11191.776164476827, 11912.463939122934, 11018.669439916815, 11394.658160255205, 11508.194426390408, 11773.587924422603, 11780.52877884875, 11015.373543111751, 11359.318440893572, 11368.715782105513, 11599.246049006788, 11625.360605166798, 7949.085855126735, 7960.960195198987, 8189.0, 8291.875621865556, 8366.790830229293, 10294.227932904912, 10465.424401657981, 10619.840702389316, 11454.370009649549, 11704.94147529257], "value": 9713.673920844565, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1497.3636204613038, 1540.4454396417289, 1767.3372485318005, 1889.2348598817484, 1921.3179321340924, 6600.537962342638, 8023.31801796848, 8065.032196136472, 8168.652172391556, 8318.126596707349, 7412.444066694992, 8350.540720260384, 8630.48217106974, 8685.0, 8805.691800786974, 2264.1509433962224, 2282.5206706591553, 2289.301762962296, 2292.9656055159185, 2296.357020034393, 2017.7276067730816, 2122.4587730128796, 2293.312006398074, 2301.7122859642545, 2305.13557415969, 2265.954680906381, 2266.3880752196897, 2277.3737222263844, 2280.981331521599, 2292.9312120636323, 2251.4596496840804, 2258.0967612954787, 2270.829687773415, 2272.1365880965127, 2279.817614590837, 2207.514346843692, 2208.0505382685437, 2208.1277895231456, 2216.1578600132007, 2223.032980653421, 8642.913570864273, 8778.780530486789, 8801.779955501099, 8812.735617931408, 8824.470531768115, 5154.4587818278915, 5316.149416093445, 5318.494742999409, 5319.627626066177, 5328.547073498718, 2849.5155823510054, 3253.8210398428077, 3565.4295312750096, 3571.624979377154, 3580.122869896892, 1002.8495725641139, 1044.2533588484193, 1050.0287234308234, 1052.2897044495012, 1061.5117046158775, 11282.830757538644, 11295.887041129567, 11314.0, 11419.657410277705, 11441.027512661434, 4436.824241575985, 4565.931511027337, 4638.489766125724, 4677.041206552649, 4679.625629949596, 9798.31411801167, 9842.852357214648, 9875.753106172404, 9891.109800118023, 9903.158231550255, 5262.0, 5263.552598029134, 5269.841904742864, 5275.20871869215, 5276.419593844674, 4089.3661482470266, 4787.18617834962, 4812.205986012339, 4823.0, 4823.517648235148, 3295.8846440374473, 3740.102375429877, 3790.753601015925, 3851.730378873452, 3896.123372241223, 1261.3062815451465, 1263.1915574032625, 1287.0861688201294, 1288.4717265921115, 1296.125115547002, 896.6503063805121, 904.904984976568, 919.5034681272039, 937.1893312284825, 942.9721603452133, 5695.430456954271, 5733.455321744427, 5766.163906233573, 5835.679037652885, 5873.500752436091, 776.3362325211975, 912.3157631776168, 964.6864768950119, 995.4077323992213, 1006.2050979725934, 3629.0564453242346, 3733.794641294755, 3821.7706937583835, 3826.7895265760094, 3841.5774264830848, 10014.198864090855, 10227.7443063924, 10444.321119127233, 10505.212109091848, 10551.788964220672, 10458.424786636655, 10477.42374169428, 10484.161267098614, 10519.211059170593, 10553.84169237462, 7845.764627061198, 8129.918700813035, 8212.0, 8242.917570824215, 8255.545944972964, 4607.838725644587, 5137.794488220578, 5683.3748287687495, 5742.942570574408, 5856.736446859987, 7861.842763144819, 9573.473458959825, 9624.133827955238, 9744.707658770249, 9775.755606109904, 9285.396449230779, 9594.520273986413, 9603.423794572349, 9610.807783844144, 9711.0, 6284.811455656338, 6795.830104247433, 7001.579905205704, 7262.237465066092, 7511.474196806171, 8119.79700507492, 8337.666493340199, 8337.95831020841, 8339.874901876598, 8356.456830306011, 7387.113546374255, 7517.849643007, 7528.887066694114, 7670.348020418327, 7674.6162691864065, 6608.273089960005, 7170.641467926687, 7268.9273107270365, 7388.6675099621725, 7416.555006699616, 6927.411170050602, 7032.964835175791, 7042.190148133031, 7068.646567671493, 7070.540414873017, 4803.471618122074, 4826.107170173529, 4905.288733133747, 5037.345145131109, 5045.192769156917, 4952.504749525018, 4962.5533701967, 5037.622178336566, 5073.59411247092, 5143.5113664201845], "value": 4479.203171403002, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.70595874980637, 306.5738623313594, 314.3445915266669, 320.4600248581141, 321.69439032918706, 4672.836450724225, 5370.133503877212, 5421.349438067427, 5443.60481388189, 5501.202325662776, 5117.769700363483, 5302.946970530294, 5334.146536554152, 5368.436314187013, 5385.623006389555, 3744.868929587465, 4159.292920203573, 4164.625183733463, 4172.311568591179, 4182.393552934823, 3849.1331704123477, 4067.877963661095, 4186.183694179636, 4201.0802492267885, 4209.200251952135, 3399.388110140174, 3832.1569254764045, 3995.1011022519847, 4004.419359192915, 4041.939370909439, 4027.1744292419985, 4039.3537034074407, 4040.2323558523785, 4080.3471444569022, 4082.4692789937417, 3035.6357237131688, 3190.330030693557, 3350.3466823969334, 3487.232808782064, 3507.631698671654, 4597.60920321773, 4981.452040275567, 5180.2026905471, 5244.580433565306, 5319.840404787863, 4689.921318096857, 4704.45898721648, 4720.268358404453, 4721.716696998157, 4722.433308003062, 4987.576056035241, 5012.699238045729, 5607.915881261743, 5685.488306052475, 5714.257161559185, 3257.527658489506, 3863.03424143964, 3863.8840834775, 3865.627702165747, 3876.1588245583403, 5958.880822383571, 5969.731362088673, 5976.432238937337, 6002.399760024006, 6076.817695469143, 3618.113562177231, 3653.3606618842127, 3898.181381909774, 3930.4890364252465, 3934.5475270344277, 3310.4206763816237, 3348.4977253411694, 3381.053305074534, 3441.9330007697695, 3804.410316400907, 3505.4741788731894, 3657.65252301031, 4062.898427539276, 4214.473190851144, 4619.168549661026, 5015.313744397865, 5104.744762761847, 5686.5499297678825, 5815.4879731270175, 5815.865906148345, 1225.7681030564227, 1236.8330275412884, 1244.2037096258402, 1253.0289026004737, 1296.2241728831373, 691.5021184746975, 725.4704066031721, 734.4234775701049, 802.2378740196837, 831.5592735850009, 3955.841766329372, 4148.730332528376, 4156.896077598084, 4230.011894409623, 4301.806418711166, 3982.7212095153063, 4292.642967388104, 4533.818647254073, 4553.886152846206, 4633.62930965522, 4069.3895915612893, 4410.360497727812, 4432.138501829239, 4444.644428445717, 4501.447000784724, 2121.3105740634046, 2299.7700229977204, 2575.6265341525373, 2577.510273048115, 2607.687077550668, 4328.978355108267, 4960.883801144786, 4989.101961646866, 5051.974740126276, 5054.0686401886815, 4379.824807007684, 4646.930296045562, 5024.371953505812, 5026.220935754964, 5029.17018691919, 3832.827522761538, 4127.174565086994, 4314.482262128565, 4772.260299653559, 5077.669951453144, 4022.445746950766, 4140.896477587964, 4312.266914624459, 4787.54518320759, 4858.90282194347, 3817.8854634361014, 4000.8199631017255, 4149.398337241144, 4671.252599584051, 4883.212334854484, 4389.209942210305, 4696.7886445110735, 4757.690750101232, 4977.751112444291, 5145.562627176732, 3197.4084794313126, 3326.9168270793425, 3627.927441451209, 3745.5692595350793, 3833.5208098987628, 5742.397048309867, 5744.195812586157, 5769.1634712967225, 5773.278340207474, 5798.0, 4709.576138147583, 4918.852434426973, 5389.0, 5538.47384498472, 5576.637518561281, 4969.254611808257, 5488.368837583729, 5565.693886836263, 5567.7750894803075, 5585.497305242385, 4752.857414277577, 5442.755076021668, 5446.747248132872, 5494.478024587658, 5520.696361700145, 5158.710322419395, 5232.6249569027, 5267.446918073547, 5272.340957380327, 5561.63849349791, 5049.697018178921, 5344.812931547533, 5446.945530544803, 5539.335279766454, 5615.8876822464135], "value": 3830.7885886718755, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [722.9047992291679, 727.6107282603807, 784.4861615641756, 812.6221307092209, 838.387976776953, 13407.597772066842, 15271.312790924407, 15663.843361566382, 15679.216039198038, 15723.449679261228, 13072.346382680875, 13250.807427331538, 14210.218437985906, 14330.713385732306, 14403.13581185127, 883.0595415882082, 894.9731508054737, 905.1310741687987, 905.1672461335587, 907.9954600226989, 897.7172190759923, 912.3932584831113, 914.076782449724, 918.0956757593765, 918.4030380252823, 944.3295260365114, 960.7886265021684, 962.6534447598859, 962.7641227899175, 965.0735294117655, 951.8619800128977, 954.9618015279381, 956.4261443134134, 957.6504575829807, 960.346964064436, 683.641088428579, 701.9578825270501, 708.6810935079184, 718.1561665043553, 720.272524750001, 12536.623901282976, 12877.67805804853, 12889.355532223351, 12961.092723509359, 13708.3831227595, 11961.581344652895, 12513.499460021589, 12520.373981300898, 12577.937110314482, 12838.508542212247, 12226.510939562495, 12325.69185770354, 12484.563040293546, 12857.0, 12950.546730864375, 3896.636177337937, 4450.955490445119, 4537.591616754508, 4612.700174488681, 4700.31845382421, 66110.0, 97221.54167687561, 98620.50689746578, 99709.0029099707, 101005.0, 10414.218933580012, 10459.477026148663, 10500.632477863239, 10512.57949682004, 10908.945455272831, 19724.211031558865, 19965.301214457424, 19981.500462488264, 19983.300584479763, 20027.799722002885, 6401.64790936503, 6791.04925310447, 7647.694092236359, 7654.617269136521, 7728.806779830436, 3834.175652234783, 4131.607497287753, 4195.202911446876, 4238.936415953764, 4356.76037817923, 3422.760406771502, 3551.662592053751, 3564.7326450516316, 3664.17556049892, 3840.2895464339185, 702.817267510451, 702.8418605813753, 704.7286794584122, 708.9787306380816, 720.8702433561905, 553.7563472072355, 574.7758374234052, 641.3426238105978, 649.1658219188321, 663.6781161136845, 8481.745547633582, 8977.102289771101, 9047.457152570867, 9112.81774364509, 9305.581248843819, 554.5896036932721, 615.6213928433981, 620.0667994667996, 640.9935900641027, 653.2860686994452, 3688.0964163780045, 4402.361657559636, 4405.471343438808, 4416.823327066882, 4428.490723566831, 82548.58725706453, 83850.58074709709, 106385.46807265915, 113000.0, 116079.0, 94339.58490622646, 101167.98832011585, 101657.98342016633, 103239.0, 104807.4759626197, 88144.0, 88564.0, 89190.10809891818, 89196.0, 89561.0, 7274.6726397313305, 8603.913960860562, 8647.0920553341, 8674.132586741276, 8690.130986901258, 54051.72974135104, 55601.721991389786, 55797.721011394686, 57238.7138064307, 59465.10802338055, 62697.0, 75349.4790312583, 75576.0, 75931.62034189794, 76700.61649691715, 11177.329360238411, 11440.141989351, 11594.420278986187, 11615.186936914673, 11772.352520611448, 12192.0, 12199.268043917393, 12208.0, 12932.288724120264, 13091.756283153085, 11107.944460277648, 12145.757084858431, 12310.692232694255, 12432.818882206177, 13586.79619805684, 12785.0, 13387.330633468482, 13414.664633384244, 13778.931105344409, 13869.930650347087, 12830.743385132431, 12862.614121576367, 13025.609231723063, 13107.708569120836, 13370.465181393025, 8982.820343593223, 10574.179121574003, 10653.0, 11065.944670276596, 11201.831972520582, 8931.687390941286, 9859.605615775288, 11375.033122184706, 11399.658010259705, 11403.0], "value": 7390.477935994106, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1567.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 116.0, 118.0, 132.0, 171.0, 100.0, 98.0, 173.0, 172.0, 158.0], "value": 134.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 115.0, 113.0, 151.0, 114.0, 114.0, 115.0, 115.0, 147.0, 114.0], "value": 124.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [210.0, 203.0, 233.0, 203.0, 196.0, 199.0, 200.0, 202.0, 196.0, 201.0], "value": 204.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 66.0, 83.0, 109.0, 88.0, 62.0, 59.0, 59.0, 79.0, 58.0], "value": 74.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 57.0, 87.0, 84.0, 98.0, 101.0, 111.0, 70.0, 69.0, 88.0], "value": 85.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 120.0, 88.0, 83.0, 89.0, 86.0, 89.0, 86.0, 88.0, 86.0], "value": 90.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 94.0, 120.0, 131.0, 126.0, 154.0, 149.0, 93.0], "value": 115.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 88.0, 85.0, 91.0, 88.0, 104.0, 134.0, 89.0, 88.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 55.0, 57.0, 56.0, 56.0, 56.0, 57.0, 58.0, 56.0], "value": 56.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 93.0, 77.0, 80.0, 73.0, 68.0, 70.0, 71.0, 72.0, 76.0], "value": 78.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 87.0, 90.0, 88.0, 90.0, 88.0, 88.0, 90.0, 86.0, 92.0], "value": 90.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 152.0, 149.0, 148.0, 150.0, 144.0, 151.0, 161.0, 219.0, 158.0], "value": 158.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 178.0, 188.0, 238.0, 176.0, 185.0, 172.0, 200.0, 169.0, 204.0], "value": 188.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 71.0, 68.0, 68.0, 74.0, 88.0, 76.0, 69.0, 69.0, 70.0], "value": 72.1, "unit": "score"}]}]} 06:06:05 INFO - Return code: 0 06:06:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:06:05 ERROR - # TBPL SUCCESS # 06:06:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:06:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:06:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:06:05 INFO - 2016-10-25 06:06:05.853 screenresolution[1950:15415] starting screenresolution argv=screenresolution get 06:06:05 INFO - 2016-10-25 06:06:05.856 screenresolution[1950:15415] Display 0: 1600x1200x32@60 06:06:05 INFO - 2016-10-25 06:06:05.861 screenresolution[1951:15418] starting screenresolution argv=screenresolution list 06:06:05 INFO - Available Modes on Display 0 06:06:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:06:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:06:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:06:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:06:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:06:06 INFO - Intel Iris: 06:06:06 INFO - Chipset Model: Intel Iris 06:06:06 INFO - Type: GPU 06:06:06 INFO - Bus: Built-In 06:06:06 INFO - VRAM (Dynamic, Max): 1536 MB 06:06:06 INFO - Vendor: Intel (0x8086) 06:06:06 INFO - Device ID: 0x0a2e 06:06:06 INFO - Revision ID: 0x0009 06:06:06 INFO - Displays: 06:06:06 INFO - Display: 06:06:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:06:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:06:06 INFO - Display Serial Number: 1600x1200 60 06:06:06 INFO - Main Display: Yes 06:06:06 INFO - Mirror: Off 06:06:06 INFO - Online: Yes 06:06:06 INFO - Rotation: Supported 06:06:06 INFO - Return code: 0 06:06:06 INFO - Running post-action listener: _resource_record_post_action 06:06:06 INFO - [mozharness: 2016-10-25 13:06:06.098685Z] Finished run-tests step (success) 06:06:06 INFO - Running post-run listener: _resource_record_post_run 06:06:06 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 1766912; Write bytes: 709315072; Read time: 18; Write time: 2106 06:06:06 INFO - TinderboxPrint: CPU usage
54.8% 06:06:06 INFO - TinderboxPrint: I/O read bytes / time
1,766,912 / 18 06:06:06 INFO - TinderboxPrint: I/O write bytes / time
709,315,072 / 2,106 06:06:06 INFO - TinderboxPrint: CPU idle
2,186.8 (45.2%) 06:06:06 INFO - TinderboxPrint: CPU system
982.9 (20.3%) 06:06:06 INFO - TinderboxPrint: CPU user
1,672.2 (34.5%) 06:06:06 INFO - TinderboxPrint: Swap in / out
406,196,224 / 0 06:06:06 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 403548672; Write bytes: 398636544; Read time: 30000; Write time: 814 06:06:06 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 1701376; Write bytes: 300418048; Read time: 17; Write time: 1260 06:06:06 INFO - Running post-run listener: _upload_blobber_files 06:06:06 INFO - Blob upload gear active. 06:06:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:06:06 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 06:06:06 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'] 06:06:06 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 06:06:06 INFO - (blobuploader) - INFO - Open directory for files ... 06:06:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:06:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:06:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:06:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:06:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:06:08 INFO - (blobuploader) - INFO - Done attempting. 06:06:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:06:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:06:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:06:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:06:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:06:09 INFO - (blobuploader) - INFO - Done attempting. 06:06:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:06:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:06:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:06:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:06:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:06:11 INFO - (blobuploader) - INFO - Done attempting. 06:06:11 INFO - (blobuploader) - INFO - Iteration through files over. 06:06:11 INFO - Return code: 0 06:06:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:06:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:06:11 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/624a4037a5795ef31146d8db148879e027f3b80b43fc36009c0d9f52d66686d09798df759074cdf9162df53626fc181778ff326ba4e343382c469fc809726f96", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a044916eba1b21c42fe4fecadd005e306a53edd8cb2ff141241bc311b97f627e69eb2a9709149d0b9007cc5589cc03e4d553c489b7c69049018e959ccde1ed86", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eba2143161d2b3447d93f5381999fbdc1c5419568b09b9ae4b34144051a1ede4488f328c37cb6913809d1f3d04f370dacb20d2f13a3d1582d231601f6e13911b"} 06:06:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:06:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:06:11 INFO - Contents: 06:06:11 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/624a4037a5795ef31146d8db148879e027f3b80b43fc36009c0d9f52d66686d09798df759074cdf9162df53626fc181778ff326ba4e343382c469fc809726f96", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a044916eba1b21c42fe4fecadd005e306a53edd8cb2ff141241bc311b97f627e69eb2a9709149d0b9007cc5589cc03e4d553c489b7c69049018e959ccde1ed86", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eba2143161d2b3447d93f5381999fbdc1c5419568b09b9ae4b34144051a1ede4488f328c37cb6913809d1f3d04f370dacb20d2f13a3d1582d231601f6e13911b"} 06:06:11 INFO - Running post-run listener: copy_logs_to_upload_dir 06:06:11 INFO - Copying logs to upload dir... 06:06:11 INFO - mkdir: /builds/slave/test/build/upload/logs 06:06:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.298774 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-10-25 06:06:11.877507) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-25 06:06:11.880724) ========= 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.kovZ4aggJx/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.ON90of9EUb/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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/624a4037a5795ef31146d8db148879e027f3b80b43fc36009c0d9f52d66686d09798df759074cdf9162df53626fc181778ff326ba4e343382c469fc809726f96", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a044916eba1b21c42fe4fecadd005e306a53edd8cb2ff141241bc311b97f627e69eb2a9709149d0b9007cc5589cc03e4d553c489b7c69049018e959ccde1ed86", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eba2143161d2b3447d93f5381999fbdc1c5419568b09b9ae4b34144051a1ede4488f328c37cb6913809d1f3d04f370dacb20d2f13a3d1582d231601f6e13911b"} build_url:https://queue.taskcluster.net/v1/task/ALkgmStASCaRckuCrXipNA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011280 build_url: 'https://queue.taskcluster.net/v1/task/ALkgmStASCaRckuCrXipNA/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/624a4037a5795ef31146d8db148879e027f3b80b43fc36009c0d9f52d66686d09798df759074cdf9162df53626fc181778ff326ba4e343382c469fc809726f96", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a044916eba1b21c42fe4fecadd005e306a53edd8cb2ff141241bc311b97f627e69eb2a9709149d0b9007cc5589cc03e4d553c489b7c69049018e959ccde1ed86", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eba2143161d2b3447d93f5381999fbdc1c5419568b09b9ae4b34144051a1ede4488f328c37cb6913809d1f3d04f370dacb20d2f13a3d1582d231601f6e13911b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-25 06:06:11.915693) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 06:06:11.916272) ========= 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.kovZ4aggJx/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.ON90of9EUb/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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.005364 ========= master_lag: 0.13 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 06:06:12.054383) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-25 06:06:12.054871) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-25 06:06:12.060722) ========= ========= Total master_lag: 0.29 =========