builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0061 starttime: 1480146057.3 results: success (0) buildid: 20161125212719 builduid: 4f59f4cef4704afbb5a94e40fcfc63e9 revision: f8f4eaac1701107f794b48891bcca2c95d39d503 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-25 23:40:57.296977) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-25 23:40:57.297679) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-25 23:40:57.297973) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-25 23:40:57.379191) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 23:40:57.379481) ========= 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.WUct8QxthJ/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.bJQbzKhatL/Listeners TMPDIR=/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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-11-25 23:40:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 58.4M=0s 2016-11-25 23:40:57 (58.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.213455 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 23:40:57.617429) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 23:40:57.617726) ========= 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.WUct8QxthJ/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.bJQbzKhatL/Listeners TMPDIR=/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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.027597 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 23:40:57.666337) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-25 23:40:57.666641) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f8f4eaac1701107f794b48891bcca2c95d39d503 --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 f8f4eaac1701107f794b48891bcca2c95d39d503 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WUct8QxthJ/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.bJQbzKhatL/Listeners TMPDIR=/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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-11-25 23:40:57,761 truncating revision to first 12 chars 2016-11-25 23:40:57,761 Setting DEBUG logging. 2016-11-25 23:40:57,761 attempt 1/10 2016-11-25 23:40:57,761 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f8f4eaac1701?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-25 23:40:58,648 unpacking tar archive at: mozilla-central-f8f4eaac1701/testing/mozharness/ program finished with exit code 0 elapsedTime=1.522892 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-25 23:40:59.212949) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-25 23:40:59.213251) ========= script_repo_revision: f8f4eaac1701107f794b48891bcca2c95d39d503 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-25 23:40:59.213630) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-25 23:40:59.213906) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-25 23:40:59.237442) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-11-25 23:40:59.237744) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.WUct8QxthJ/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.bJQbzKhatL/Listeners TMPDIR=/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 23:40:59 INFO - MultiFileLogger online at 20161125 23:40:59 in /builds/slave/test 23:40:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 23:40:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:40:59 INFO - {'append_to_log': False, 23:40:59 INFO - 'base_work_dir': '/builds/slave/test', 23:40:59 INFO - 'blob_upload_branch': 'Firefox', 23:40:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:40:59 INFO - 'branch': 'Firefox', 23:40:59 INFO - 'buildbot_json_path': 'buildprops.json', 23:40:59 INFO - 'config_files': ('talos/mac_config.py',), 23:40:59 INFO - 'default_actions': ('clobber', 23:40:59 INFO - 'read-buildbot-config', 23:40:59 INFO - 'download-and-extract', 23:40:59 INFO - 'populate-webroot', 23:40:59 INFO - 'create-virtualenv', 23:40:59 INFO - 'install', 23:40:59 INFO - 'run-tests'), 23:40:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:40:59 INFO - 'download_minidump_stackwalk': True, 23:40:59 INFO - 'download_symbols': 'ondemand', 23:40:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:40:59 INFO - 'tooltool.py': '/tools/tooltool.py', 23:40:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:40:59 INFO - '/tools/misc-python/virtualenv.py')}, 23:40:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:40:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:40:59 INFO - 'installer_path': 'installer.exe', 23:40:59 INFO - 'log_level': 'info', 23:40:59 INFO - 'log_name': 'talos', 23:40:59 INFO - 'log_to_console': True, 23:40:59 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:40:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:40:59 INFO - 'opt_config_files': (), 23:40:59 INFO - 'pip_index': False, 23:40:59 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:40:59 INFO - 'cmd': ('bash', 23:40:59 INFO - '-c', 23:40:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:40:59 INFO - 'enabled': True, 23:40:59 INFO - 'halt_on_failure': False, 23:40:59 INFO - 'name': 'check_screen_resolution'},), 23:40:59 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:40:59 INFO - 'cmd': ('bash', 23:40:59 INFO - '-c', 23:40:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:40:59 INFO - 'enabled': True, 23:40:59 INFO - 'halt_on_failure': False, 23:40:59 INFO - 'name': 'check_screen_resolution'},), 23:40:59 INFO - 'run_cmd_checks_enabled': True, 23:40:59 INFO - 'sps_profile': False, 23:40:59 INFO - 'sps_profile_interval': 0, 23:40:59 INFO - 'suite': 'dromaeojs', 23:40:59 INFO - 'system_bits': '32', 23:40:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:40:59 INFO - 'title': 't-yosemite-r7-0061', 23:40:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:40:59 INFO - 'use_talos_json': True, 23:40:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:40:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:40:59 INFO - 'work_dir': 'build'} 23:40:59 INFO - [mozharness: 2016-11-26 07:40:59.448988Z] Running clobber step. 23:40:59 INFO - Running pre-action listener: _resource_record_pre_action 23:40:59 INFO - Running main action method: clobber 23:40:59 INFO - rmtree: /builds/slave/test/build 23:40:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:41:01 INFO - Running post-action listener: _resource_record_post_action 23:41:01 INFO - [mozharness: 2016-11-26 07:41:01.911269Z] Finished clobber step (success) 23:41:01 INFO - [mozharness: 2016-11-26 07:41:01.911456Z] Running read-buildbot-config step. 23:41:01 INFO - Running pre-action listener: _resource_record_pre_action 23:41:01 INFO - Running main action method: read_buildbot_config 23:41:01 INFO - Using buildbot properties: 23:41:01 INFO - { 23:41:01 INFO - "project": "", 23:41:01 INFO - "product": "firefox", 23:41:01 INFO - "script_repo_revision": "production", 23:41:01 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 23:41:01 INFO - "repository": "", 23:41:01 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 23:41:01 INFO - "buildid": "20161125212719", 23:41:01 INFO - "pgo_build": "False", 23:41:01 INFO - "basedir": "/builds/slave/test", 23:41:01 INFO - "buildnumber": 81, 23:41:01 INFO - "slavename": "t-yosemite-r7-0061", 23:41:01 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 23:41:01 INFO - "platform": "yosemite_r7", 23:41:01 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 23:41:01 INFO - "branch": "mozilla-central", 23:41:01 INFO - "repo_path": "mozilla-central", 23:41:01 INFO - "revision": "f8f4eaac1701107f794b48891bcca2c95d39d503", 23:41:01 INFO - "stage_platform": "macosx64", 23:41:01 INFO - "builduid": "4f59f4cef4704afbb5a94e40fcfc63e9", 23:41:01 INFO - "slavebuilddir": "test" 23:41:01 INFO - } 23:41:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/UJO46V69QtS6io5-Q0MnCw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 23:41:01 INFO - Running post-action listener: _resource_record_post_action 23:41:01 INFO - [mozharness: 2016-11-26 07:41:01.914633Z] Finished read-buildbot-config step (success) 23:41:01 INFO - [mozharness: 2016-11-26 07:41:01.914759Z] Running download-and-extract step. 23:41:01 INFO - Running pre-action listener: _resource_record_pre_action 23:41:01 INFO - Running main action method: download_and_extract 23:41:01 INFO - mkdir: /builds/slave/test/build/tests 23:41:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:41:01 INFO - trying https://queue.taskcluster.net/v1/task/UJO46V69QtS6io5-Q0MnCw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 23:41:01 INFO - Downloading https://queue.taskcluster.net/v1/task/UJO46V69QtS6io5-Q0MnCw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 23:41:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UJO46V69QtS6io5-Q0MnCw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 23:41:03 INFO - Downloaded 1199 bytes. 23:41:03 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 23:41:03 INFO - Using the following test package requirements: 23:41:03 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 23:41:03 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:41:03 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 23:41:03 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:41:03 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 23:41:03 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:41:03 INFO - u'jsshell-mac.zip'], 23:41:03 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:41:03 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 23:41:03 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 23:41:03 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:41:03 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 23:41:03 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:41:03 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 23:41:03 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:41:03 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 23:41:03 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:41:03 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 23:41:03 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 23:41:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UJO46V69QtS6io5-Q0MnCw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 23:41:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UJO46V69QtS6io5-Q0MnCw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 23:41:03 INFO - Fetch https://queue.taskcluster.net/v1/task/UJO46V69QtS6io5-Q0MnCw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 23:41:03 INFO - Http code: 200 23:41:03 INFO - via: 1.1 bb8a7ff26b131d51953dc2d3e19a2751.cloudfront.net (CloudFront) 23:41:03 INFO - x-amz-cf-id: u0C9V8jp7gpGC3iakuadvi3sbsCUFjTDULNKglv3UuwlbZ2gwaUU_w== 23:41:03 INFO - x-amz-version-id: 8tFTGm0iJ9qWugkQ3RExU4EtjE9w3j22 23:41:04 INFO - Expected file size: 16273360 23:41:04 INFO - Obtained file size: 16273360 23:41:10 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 23:41:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UJO46V69QtS6io5-Q0MnCw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 23:41:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UJO46V69QtS6io5-Q0MnCw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 23:41:10 INFO - Fetch https://queue.taskcluster.net/v1/task/UJO46V69QtS6io5-Q0MnCw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 23:41:11 INFO - Http code: 200 23:41:11 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 23:41:11 INFO - x-amz-cf-id: hloPVhb24tRb1c7VmQtHa84YWVXkVUra55WHXCYtD2TcnKOl9zDcig== 23:41:11 INFO - x-amz-version-id: 8tFTGm0iJ9qWugkQ3RExU4EtjE9w3j22 23:41:11 INFO - Expected file size: 16273360 23:41:11 INFO - Obtained file size: 16273360 23:41:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UJO46V69QtS6io5-Q0MnCw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 23:41:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UJO46V69QtS6io5-Q0MnCw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 23:41:18 INFO - Fetch https://queue.taskcluster.net/v1/task/UJO46V69QtS6io5-Q0MnCw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 23:41:18 INFO - Http code: 200 23:41:18 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 23:41:18 INFO - x-amz-cf-id: SQl8QnFyGkHiW2CW3Yu0dHDF4YNwrvJpWXT85eMqWC60sgSzJUfJ9w== 23:41:18 INFO - x-amz-version-id: lZ3STaChykfoXtOiOeN2mpVYSSZTH.I. 23:41:18 INFO - Expected file size: 14409702 23:41:18 INFO - Obtained file size: 14409702 23:41:19 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')]} 23:41:19 INFO - trying https://queue.taskcluster.net/v1/task/UJO46V69QtS6io5-Q0MnCw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 23:41:19 INFO - Downloading https://queue.taskcluster.net/v1/task/UJO46V69QtS6io5-Q0MnCw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 23:41:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UJO46V69QtS6io5-Q0MnCw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 23:41:23 INFO - Downloaded 107608165 bytes. 23:41:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UJO46V69QtS6io5-Q0MnCw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 23:41:23 INFO - mkdir: /builds/slave/test/properties 23:41:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:41:23 INFO - Writing to file /builds/slave/test/properties/build_url 23:41:23 INFO - Contents: 23:41:23 INFO - build_url:https://queue.taskcluster.net/v1/task/UJO46V69QtS6io5-Q0MnCw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 23:41:24 INFO - Running post-action listener: _resource_record_post_action 23:41:24 INFO - Running post-action listener: set_extra_try_arguments 23:41:24 INFO - [mozharness: 2016-11-26 07:41:24.596186Z] Finished download-and-extract step (success) 23:41:24 INFO - [mozharness: 2016-11-26 07:41:24.596314Z] Running populate-webroot step. 23:41:24 INFO - Running pre-action listener: _resource_record_pre_action 23:41:24 INFO - Running main action method: populate_webroot 23:41:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:41:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:41:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:41:24 INFO - '--tppagecycles', 23:41:24 INFO - '7'], 23:41:24 INFO - 'tests': ['tsvgm']}}, 23:41:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:41:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:41:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:41:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:41:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:41:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:41:24 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 23:41:24 INFO - 'talos_options': ['--disable-e10s'], 23:41:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:41:24 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 23:41:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:41:24 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 23:41:24 INFO - 'talos_options': ['--disable-e10s'], 23:41:24 INFO - 'tests': ['damp', 'tps']}, 23:41:24 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 23:41:24 INFO - 'tests': ['damp', 'tps']}, 23:41:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:41:24 INFO - 'tests': ['dromaeo_dom']}, 23:41:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:41:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:41:24 INFO - 'tests': ['basic_compositor_video']}, 23:41:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:41:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:41:24 INFO - 'tests': ['a11yr', 23:41:24 INFO - 'ts_paint', 23:41:24 INFO - 'tpaint', 23:41:24 INFO - 'sessionrestore', 23:41:24 INFO - 'sessionrestore_no_auto_restore', 23:41:24 INFO - 'tabpaint']}, 23:41:24 INFO - 'other-e10s': {'tests': ['a11yr', 23:41:24 INFO - 'ts_paint', 23:41:24 INFO - 'tpaint', 23:41:24 INFO - 'sessionrestore', 23:41:24 INFO - 'sessionrestore_no_auto_restore', 23:41:24 INFO - 'tabpaint']}, 23:41:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:41:24 INFO - 'ts_paint', 23:41:24 INFO - 'tpaint', 23:41:24 INFO - 'sessionrestore', 23:41:24 INFO - 'sessionrestore_no_auto_restore', 23:41:24 INFO - 'tabpaint']}, 23:41:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:41:24 INFO - 'ts_paint', 23:41:24 INFO - 'tpaint', 23:41:24 INFO - 'sessionrestore', 23:41:24 INFO - 'sessionrestore_no_auto_restore', 23:41:24 INFO - 'tabpaint']}, 23:41:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:41:24 INFO - 'tests': ['a11yr', 23:41:24 INFO - 'ts_paint', 23:41:24 INFO - 'tpaint', 23:41:24 INFO - 'sessionrestore', 23:41:24 INFO - 'sessionrestore_no_auto_restore', 23:41:24 INFO - 'tabpaint']}, 23:41:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:41:24 INFO - 'tests': ['a11yr', 23:41:24 INFO - 'ts_paint', 23:41:24 INFO - 'tpaint', 23:41:24 INFO - 'sessionrestore', 23:41:24 INFO - 'sessionrestore_no_auto_restore', 23:41:24 INFO - 'tabpaint']}, 23:41:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:41:24 INFO - 'tests': ['tsvgx', 23:41:24 INFO - 'tsvgr_opacity', 23:41:24 INFO - 'tart', 23:41:24 INFO - 'tscrollx', 23:41:24 INFO - 'cart', 23:41:24 INFO - 'tsvg_static']}, 23:41:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:41:24 INFO - 'tsvgr_opacity', 23:41:24 INFO - 'tart', 23:41:24 INFO - 'tscrollx', 23:41:24 INFO - 'cart', 23:41:24 INFO - 'tsvg_static']}, 23:41:24 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 23:41:24 INFO - 'talos_options': ['--disable-e10s'], 23:41:24 INFO - 'tests': ['tp5o']}, 23:41:24 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 23:41:24 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 23:41:24 INFO - 'talos_options': ['--disable-e10s', 23:41:24 INFO - '--xperf_path', 23:41:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:41:24 INFO - 'tests': ['tp5n']}, 23:41:24 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 23:41:24 INFO - 'talos_options': ['--xperf_path', 23:41:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:41:24 INFO - 'tests': ['tp5n']}}, 23:41:24 INFO - 'talos.zip': {'path': '', 23:41:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:41:24 INFO - Running post-action listener: _resource_record_post_action 23:41:24 INFO - [mozharness: 2016-11-26 07:41:24.614567Z] Finished populate-webroot step (success) 23:41:24 INFO - [mozharness: 2016-11-26 07:41:24.614695Z] Running create-virtualenv step. 23:41:24 INFO - Running pre-action listener: _resource_record_pre_action 23:41:24 INFO - Running main action method: create_virtualenv 23:41:24 INFO - Creating virtualenv /builds/slave/test/build/venv 23:41: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 23:41:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:41:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:41:24 INFO - Using real prefix '/tools/python27' 23:41:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:41:25 INFO - Installing distribute.............................................................................................................................................................................................done. 23:41:28 INFO - Installing pip.................done. 23:41:28 INFO - Return code: 0 23:41:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 23:41:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 23:41:28 INFO - Reading from file tmpfile_stdout 23:41:28 INFO - Output received: 23:41: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) 23:41:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:41: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')]} 23:41:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:41:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:41: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')]} 23:41:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:41:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:41: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 0x10ac763f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abec570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1f84d2630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa8cd30>, '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 0x10a8f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa84b20>, '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.WUct8QxthJ/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.bJQbzKhatL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 23:41: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 23:41: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 23:41:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WUct8QxthJ/Render', 23:41:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:41:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:41:28 INFO - 'HOME': '/Users/cltbld', 23:41:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:41:28 INFO - 'LOGNAME': 'cltbld', 23:41:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:41:28 INFO - 'MOZ_NO_REMOTE': '1', 23:41:28 INFO - 'NO_EM_RESTART': '1', 23:41:28 INFO - 'PAGER': '/bin/cat', 23:41:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:41:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:41:28 INFO - 'PWD': '/builds/slave/test', 23:41:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:41:28 INFO - 'SHELL': '/bin/bash', 23:41:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJQbzKhatL/Listeners', 23:41:28 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 23:41:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:41:28 INFO - 'USER': 'cltbld', 23:41:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:41:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:41:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:41:28 INFO - 'XPC_FLAGS': '0x0', 23:41:28 INFO - 'XPC_SERVICE_NAME': '0', 23:41:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:41:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:41: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 23:41:28 INFO - Cleaning up... 23:41:28 INFO - Return code: 0 23:41:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:41: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')]} 23:41:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:41:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:41: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')]} 23:41:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:41:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:41: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 0x10ac763f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abec570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1f84d2630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa8cd30>, '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 0x10a8f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa84b20>, '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.WUct8QxthJ/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.bJQbzKhatL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 23:41: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 23:41: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 23:41:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WUct8QxthJ/Render', 23:41:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:41:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:41:28 INFO - 'HOME': '/Users/cltbld', 23:41:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:41:28 INFO - 'LOGNAME': 'cltbld', 23:41:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:41:28 INFO - 'MOZ_NO_REMOTE': '1', 23:41:28 INFO - 'NO_EM_RESTART': '1', 23:41:28 INFO - 'PAGER': '/bin/cat', 23:41:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:41:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:41:28 INFO - 'PWD': '/builds/slave/test', 23:41:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:41:28 INFO - 'SHELL': '/bin/bash', 23:41:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJQbzKhatL/Listeners', 23:41:28 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 23:41:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:41:28 INFO - 'USER': 'cltbld', 23:41:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:41:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:41:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:41:28 INFO - 'XPC_FLAGS': '0x0', 23:41:28 INFO - 'XPC_SERVICE_NAME': '0', 23:41:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:41:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:41:29 INFO - Downloading/unpacking psutil>=3.1.1 23:41:29 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 23:41:29 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 23:41:29 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 23:41:29 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 23:41:29 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 23:41:29 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 23:41:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:41:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:41:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:41:33 INFO - Installing collected packages: psutil 23:41:33 INFO - Running setup.py install for psutil 23:41:33 INFO - building 'psutil._psutil_osx' extension 23:41:33 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 23:41:33 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 23:41:33 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 23:41:33 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 23:41:33 INFO - building 'psutil._psutil_posix' extension 23:41:33 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 23:41:33 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:41:33 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:41:33 INFO - ^ 23:41:34 INFO - 1 warning generated. 23:41:34 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 23:41:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:41:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:41:34 INFO - Successfully installed psutil 23:41:34 INFO - Cleaning up... 23:41:34 INFO - Return code: 0 23:41:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:41:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:41:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:41:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:41:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:41:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:41:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:41:34 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 0x10ac763f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abec570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1f84d2630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa8cd30>, '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 0x10a8f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa84b20>, '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.WUct8QxthJ/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.bJQbzKhatL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 23:41:34 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 23:41:34 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 23:41:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WUct8QxthJ/Render', 23:41:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:41:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:41:34 INFO - 'HOME': '/Users/cltbld', 23:41:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:41:34 INFO - 'LOGNAME': 'cltbld', 23:41:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:41:34 INFO - 'MOZ_NO_REMOTE': '1', 23:41:34 INFO - 'NO_EM_RESTART': '1', 23:41:34 INFO - 'PAGER': '/bin/cat', 23:41:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:41:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:41:34 INFO - 'PWD': '/builds/slave/test', 23:41:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:41:34 INFO - 'SHELL': '/bin/bash', 23:41:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJQbzKhatL/Listeners', 23:41:34 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 23:41:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:41:34 INFO - 'USER': 'cltbld', 23:41:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:41:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:41:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:41:34 INFO - 'XPC_FLAGS': '0x0', 23:41:34 INFO - 'XPC_SERVICE_NAME': '0', 23:41:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:41:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:41:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:41:34 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 23:41:34 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 23:41:34 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 23:41:34 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 23:41:34 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 23:41:34 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 23:41:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:41:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:41:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:41:37 INFO - Installing collected packages: mozsystemmonitor 23:41:37 INFO - Running setup.py install for mozsystemmonitor 23:41:37 INFO - Successfully installed mozsystemmonitor 23:41:37 INFO - Cleaning up... 23:41:37 INFO - Return code: 0 23:41:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:41:37 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')]} 23:41:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:41:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:41:37 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')]} 23:41:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:41:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:41:37 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 0x10ac763f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abec570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1f84d2630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa8cd30>, '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 0x10a8f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa84b20>, '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.WUct8QxthJ/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.bJQbzKhatL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 23:41:37 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 23:41:37 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 23:41:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WUct8QxthJ/Render', 23:41:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:41:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:41:37 INFO - 'HOME': '/Users/cltbld', 23:41:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:41:37 INFO - 'LOGNAME': 'cltbld', 23:41:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:41:37 INFO - 'MOZ_NO_REMOTE': '1', 23:41:37 INFO - 'NO_EM_RESTART': '1', 23:41:37 INFO - 'PAGER': '/bin/cat', 23:41:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:41:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:41:37 INFO - 'PWD': '/builds/slave/test', 23:41:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:41:37 INFO - 'SHELL': '/bin/bash', 23:41:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJQbzKhatL/Listeners', 23:41:37 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 23:41:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:41:37 INFO - 'USER': 'cltbld', 23:41:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:41:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:41:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:41:37 INFO - 'XPC_FLAGS': '0x0', 23:41:37 INFO - 'XPC_SERVICE_NAME': '0', 23:41:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:41:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:41:37 INFO - Downloading/unpacking jsonschema==2.5.1 23:41:37 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 23:41:37 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 23:41:37 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 23:41:37 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 23:41:37 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 23:41:37 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 23:41:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:41:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:41: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 23:41: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 23:41: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 23:41: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 23:41: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 23:41: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 23:41:40 INFO - Downloading functools32-3.2.3-2.tar.gz 23:41:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:41:40 INFO - warning: no files found matching '*.txt' 23:41:40 INFO - no previously-included directories found matching 'build' 23:41:40 INFO - no previously-included directories found matching 'dist' 23:41:40 INFO - no previously-included directories found matching '.git*' 23:41:40 INFO - Installing collected packages: jsonschema, functools32 23:41:40 INFO - Running setup.py install for functools32 23:41:40 INFO - warning: no files found matching '*.txt' 23:41:40 INFO - no previously-included directories found matching 'build' 23:41:40 INFO - no previously-included directories found matching 'dist' 23:41:40 INFO - no previously-included directories found matching '.git*' 23:41:40 INFO - Successfully installed jsonschema functools32 23:41:40 INFO - Cleaning up... 23:41:40 INFO - Return code: 0 23:41:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:41:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:41:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:41:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:41:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:41:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:41:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:41:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ac763f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abec570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1f84d2630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa8cd30>, '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 0x10a8f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa84b20>, '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.WUct8QxthJ/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.bJQbzKhatL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 23:41:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 23:41:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 23:41:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WUct8QxthJ/Render', 23:41:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:41:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:41:40 INFO - 'HOME': '/Users/cltbld', 23:41:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:41:40 INFO - 'LOGNAME': 'cltbld', 23:41:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:41:40 INFO - 'MOZ_NO_REMOTE': '1', 23:41:40 INFO - 'NO_EM_RESTART': '1', 23:41:40 INFO - 'PAGER': '/bin/cat', 23:41:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:41:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:41:40 INFO - 'PWD': '/builds/slave/test', 23:41:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:41:40 INFO - 'SHELL': '/bin/bash', 23:41:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJQbzKhatL/Listeners', 23:41:40 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 23:41:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:41:40 INFO - 'USER': 'cltbld', 23:41:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:41:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:41:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:41:40 INFO - 'XPC_FLAGS': '0x0', 23:41:40 INFO - 'XPC_SERVICE_NAME': '0', 23:41:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:41:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:41:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:41:41 INFO - Cleaning up... 23:41:41 INFO - Return code: 0 23:41:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:41:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:41:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:41:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:41:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:41:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:41:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:41:41 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 0x10ac763f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abec570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1f84d2630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa8cd30>, '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 0x10a8f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa84b20>, '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.WUct8QxthJ/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.bJQbzKhatL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 23:41:41 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 23:41:41 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 23:41:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WUct8QxthJ/Render', 23:41:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:41:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:41:41 INFO - 'HOME': '/Users/cltbld', 23:41:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:41:41 INFO - 'LOGNAME': 'cltbld', 23:41:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:41:41 INFO - 'MOZ_NO_REMOTE': '1', 23:41:41 INFO - 'NO_EM_RESTART': '1', 23:41:41 INFO - 'PAGER': '/bin/cat', 23:41:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:41:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:41:41 INFO - 'PWD': '/builds/slave/test', 23:41:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:41:41 INFO - 'SHELL': '/bin/bash', 23:41:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJQbzKhatL/Listeners', 23:41:41 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 23:41:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:41:41 INFO - 'USER': 'cltbld', 23:41:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:41:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:41:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:41:41 INFO - 'XPC_FLAGS': '0x0', 23:41:41 INFO - 'XPC_SERVICE_NAME': '0', 23:41:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:41:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:41:41 INFO - Downloading/unpacking blobuploader==1.2.4 23:41:41 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 23:41:41 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 23:41:41 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 23:41:41 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 23:41:41 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 23:41:41 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 23:41:44 INFO - Downloading blobuploader-1.2.4.tar.gz 23:41:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:41:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:41:44 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 23:41:44 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 23:41:44 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 23:41:44 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 23:41:44 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 23:41:44 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 23:41:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:41:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:41:44 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 23:41:44 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 23:41:44 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 23:41:44 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 23:41:44 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 23:41:44 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 23:41:45 INFO - Downloading docopt-0.6.1.tar.gz 23:41:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:41:45 INFO - Installing collected packages: blobuploader, requests, docopt 23:41:45 INFO - Running setup.py install for blobuploader 23:41:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:41:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:41:45 INFO - Running setup.py install for requests 23:41:45 INFO - Running setup.py install for docopt 23:41:46 INFO - Successfully installed blobuploader requests docopt 23:41:46 INFO - Cleaning up... 23:41:46 INFO - Return code: 0 23:41:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:41:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:41:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:41:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:41:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:41:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:41:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:41:46 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 0x10ac763f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abec570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1f84d2630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa8cd30>, '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 0x10a8f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa84b20>, '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.WUct8QxthJ/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.bJQbzKhatL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 23:41:46 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 23:41:46 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 23:41:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WUct8QxthJ/Render', 23:41:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:41:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:41:46 INFO - 'HOME': '/Users/cltbld', 23:41:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:41:46 INFO - 'LOGNAME': 'cltbld', 23:41:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:41:46 INFO - 'MOZ_NO_REMOTE': '1', 23:41:46 INFO - 'NO_EM_RESTART': '1', 23:41:46 INFO - 'PAGER': '/bin/cat', 23:41:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:41:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:41:46 INFO - 'PWD': '/builds/slave/test', 23:41:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:41:46 INFO - 'SHELL': '/bin/bash', 23:41:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJQbzKhatL/Listeners', 23:41:46 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 23:41:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:41:46 INFO - 'USER': 'cltbld', 23:41:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:41:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:41:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:41:46 INFO - 'XPC_FLAGS': '0x0', 23:41:46 INFO - 'XPC_SERVICE_NAME': '0', 23:41:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:41:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:41:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:41:46 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-nhRih8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:41:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:41:46 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-6v_xgm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:41:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:41:46 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-uzQNS5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:41:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:41:46 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-1fLMMj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:41:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:41:46 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-tuh0DD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:41:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:41:46 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-gaTKVJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:41:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:41:47 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-chQ4p8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:41:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:41:47 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-6R0yf4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:41:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:41:47 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-yiQk49-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:41:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:41:47 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-a83Iey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:41:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:41:47 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-7rrdEy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:41:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:41:47 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-WVb6JB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:41:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:41:47 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-yiynAF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:41:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:41:47 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-ruwPkz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:41:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:41:47 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-T7b6Ih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:41:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:41:47 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-NrkLsN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:41:48 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-DP0bN2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:41:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:41:48 INFO - Running setup.py install for manifestparser 23:41:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:41:48 INFO - Running setup.py install for mozcrash 23:41:48 INFO - Running setup.py install for mozdebug 23:41:48 INFO - Running setup.py install for mozdevice 23:41:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:41:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:41:48 INFO - Running setup.py install for mozfile 23:41:48 INFO - Running setup.py install for mozhttpd 23:41:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:41:49 INFO - Running setup.py install for mozinfo 23:41:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:41:49 INFO - Running setup.py install for mozInstall 23:41:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:41:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:41:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:41:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:41:49 INFO - Running setup.py install for mozleak 23:41:49 INFO - Running setup.py install for mozlog 23:41:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:41:49 INFO - Running setup.py install for moznetwork 23:41:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:41:49 INFO - Running setup.py install for mozprocess 23:41:50 INFO - Running setup.py install for mozprofile 23:41:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:41:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:41:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:41:50 INFO - Running setup.py install for mozrunner 23:41:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:41:50 INFO - Running setup.py install for mozscreenshot 23:41:50 INFO - Running setup.py install for moztest 23:41:50 INFO - Running setup.py install for mozversion 23:41:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:41:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:41:50 INFO - Cleaning up... 23:41:50 INFO - Return code: 0 23:41:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:41:50 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')]} 23:41:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:41:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:41:50 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')]} 23:41:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:41:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:41:50 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 0x10ac763f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abec570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1f84d2630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa8cd30>, '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 0x10a8f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa84b20>, '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.WUct8QxthJ/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.bJQbzKhatL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 23:41:50 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 23:41:50 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 23:41:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WUct8QxthJ/Render', 23:41:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:41:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:41:50 INFO - 'HOME': '/Users/cltbld', 23:41:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:41:50 INFO - 'LOGNAME': 'cltbld', 23:41:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:41:50 INFO - 'MOZ_NO_REMOTE': '1', 23:41:50 INFO - 'NO_EM_RESTART': '1', 23:41:50 INFO - 'PAGER': '/bin/cat', 23:41:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:41:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:41:50 INFO - 'PWD': '/builds/slave/test', 23:41:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:41:50 INFO - 'SHELL': '/bin/bash', 23:41:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJQbzKhatL/Listeners', 23:41:50 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 23:41:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:41:50 INFO - 'USER': 'cltbld', 23:41:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:41:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:41:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:41:50 INFO - 'XPC_FLAGS': '0x0', 23:41:50 INFO - 'XPC_SERVICE_NAME': '0', 23:41:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:41:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:41:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:41:51 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-b9V77_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:41:51 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)) 23:41:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:41:51 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-eRjzK7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:41:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:41:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:41:51 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-keRCeV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:41:51 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)) 23:41:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:41:51 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-x27Ypj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:41:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:41:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:41:51 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-L_Mn4B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:41:51 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)) 23:41:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:41:51 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-lb0Xxe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:41:51 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)) 23:41:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:41:51 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-TdpQYK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:41:51 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)) 23:41:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:41:51 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-R8EMlL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:41:51 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)) 23:41:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:41:51 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-NYoAgO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:41:52 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)) 23:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:41:52 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-ll2rjo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:41:52 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)) 23:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:41:52 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-UlNupn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:41:52 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)) 23:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:41:52 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-TpDcCc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:41:52 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)) 23:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:41:52 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-C2PFEc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:41:52 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)) 23:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:41:52 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-DVv0tR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:41:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 23:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:41:52 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-7zORIz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:41:52 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)) 23:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:41:52 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-mhrSG3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:41:52 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)) 23:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:41:52 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-VEBLIY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:41:52 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)) 23:41:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:41:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:41:52 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)) 23:41:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:41:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:41:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:41:52 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 23:41:52 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 23:41:52 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 23:41:52 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 23:41:52 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 23:41:52 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 23:41:55 INFO - Downloading blessings-1.6.tar.gz 23:41:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:41:55 INFO - Installing collected packages: blessings 23:41:55 INFO - Running setup.py install for blessings 23:41:55 INFO - Successfully installed blessings 23:41:55 INFO - Cleaning up... 23:41:55 INFO - Return code: 0 23:41:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:41:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:41:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:41:56 INFO - Reading from file tmpfile_stdout 23:41:56 INFO - Current package versions: 23:41:56 INFO - blessings == 1.6 23:41:56 INFO - blobuploader == 1.2.4 23:41:56 INFO - docopt == 0.6.1 23:41:56 INFO - functools32 == 3.2.3-2 23:41:56 INFO - jsonschema == 2.5.1 23:41:56 INFO - manifestparser == 1.1 23:41:56 INFO - mozInstall == 1.12 23:41:56 INFO - mozcrash == 1.0 23:41:56 INFO - mozdebug == 0.1 23:41:56 INFO - mozdevice == 0.49 23:41:56 INFO - mozfile == 1.2 23:41:56 INFO - mozhttpd == 0.7 23:41:56 INFO - mozinfo == 0.9 23:41:56 INFO - mozleak == 0.1 23:41:56 INFO - mozlog == 3.4 23:41:56 INFO - moznetwork == 0.27 23:41:56 INFO - mozprocess == 0.23 23:41:56 INFO - mozprofile == 0.28 23:41:56 INFO - mozrunner == 6.13 23:41:56 INFO - mozscreenshot == 0.1 23:41:56 INFO - mozsystemmonitor == 0.3 23:41:56 INFO - moztest == 0.7 23:41:56 INFO - mozversion == 1.4 23:41:56 INFO - psutil == 3.1.1 23:41:56 INFO - requests == 1.2.3 23:41:56 INFO - wsgiref == 0.1.2 23:41:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:41:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:41:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:41:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:41:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:41:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:41:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:41:56 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 0x10ac763f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abec570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1f84d2630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa8cd30>, '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 0x10a8f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa84b20>, '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.WUct8QxthJ/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.bJQbzKhatL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 23:41:56 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 23:41:56 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 23:41:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WUct8QxthJ/Render', 23:41:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:41:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:41:56 INFO - 'HOME': '/Users/cltbld', 23:41:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:41:56 INFO - 'LOGNAME': 'cltbld', 23:41:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:41:56 INFO - 'MOZ_NO_REMOTE': '1', 23:41:56 INFO - 'NO_EM_RESTART': '1', 23:41:56 INFO - 'PAGER': '/bin/cat', 23:41:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:41:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:41:56 INFO - 'PWD': '/builds/slave/test', 23:41:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:41:56 INFO - 'SHELL': '/bin/bash', 23:41:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJQbzKhatL/Listeners', 23:41:56 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 23:41:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:41:56 INFO - 'USER': 'cltbld', 23:41:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:41:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:41:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:41:56 INFO - 'XPC_FLAGS': '0x0', 23:41:56 INFO - 'XPC_SERVICE_NAME': '0', 23:41:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:41:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:41:56 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)) 23:41:56 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)) 23:41:56 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)) 23:41:56 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)) 23:41:56 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)) 23:41:56 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)) 23:41:56 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)) 23:41:56 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)) 23:41:56 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)) 23:41:56 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)) 23:41:56 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)) 23:41:56 INFO - Cleaning up... 23:41:56 INFO - Return code: 0 23:41:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:41:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:41:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:41:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:41:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:41:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:41:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:41:56 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 0x10ac763f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abec570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1f84d2630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa8cd30>, '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 0x10a8f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa84b20>, '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.WUct8QxthJ/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.bJQbzKhatL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 23:41:56 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 23:41:56 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 23:41:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WUct8QxthJ/Render', 23:41:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:41:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:41:56 INFO - 'HOME': '/Users/cltbld', 23:41:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:41:56 INFO - 'LOGNAME': 'cltbld', 23:41:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:41:56 INFO - 'MOZ_NO_REMOTE': '1', 23:41:56 INFO - 'NO_EM_RESTART': '1', 23:41:56 INFO - 'PAGER': '/bin/cat', 23:41:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:41:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:41:56 INFO - 'PWD': '/builds/slave/test', 23:41:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:41:56 INFO - 'SHELL': '/bin/bash', 23:41:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJQbzKhatL/Listeners', 23:41:56 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 23:41:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:41:56 INFO - 'USER': 'cltbld', 23:41:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:41:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:41:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:41:56 INFO - 'XPC_FLAGS': '0x0', 23:41:56 INFO - 'XPC_SERVICE_NAME': '0', 23:41:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:41:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:41:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:41:56 INFO - Cleaning up... 23:41:56 INFO - Return code: 0 23:41:56 INFO - Running post-action listener: _resource_record_post_action 23:41:56 INFO - Running post-action listener: _start_resource_monitoring 23:41:56 INFO - Starting resource monitoring. 23:41:56 INFO - [mozharness: 2016-11-26 07:41:56.570940Z] Finished create-virtualenv step (success) 23:41:56 INFO - [mozharness: 2016-11-26 07:41:56.571966Z] Running install step. 23:41:56 INFO - Running pre-action listener: _resource_record_pre_action 23:41:56 INFO - Running main action method: install 23:41:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:41:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:41:56 INFO - Reading from file tmpfile_stdout 23:41:56 INFO - Detecting whether we're running mozinstall >=1.0... 23:41:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:41:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:41:56 INFO - Reading from file tmpfile_stdout 23:41:56 INFO - Output received: 23:41:56 INFO - Usage: mozinstall [options] installer 23:41:56 INFO - Options: 23:41:56 INFO - -h, --help show this help message and exit 23:41:56 INFO - -d DEST, --destination=DEST 23:41:56 INFO - Directory to install application into. [default: 23:41:56 INFO - "/builds/slave/test"] 23:41:56 INFO - --app=APP Application being installed. [default: firefox] 23:41:56 INFO - mkdir: /builds/slave/test/build/application 23:41:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 23:41:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 23:42:29 INFO - Reading from file tmpfile_stdout 23:42:29 INFO - Output received: 23:42:29 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 23:42:29 INFO - Running post-action listener: _resource_record_post_action 23:42:29 INFO - [mozharness: 2016-11-26 07:42:29.164945Z] Finished install step (success) 23:42:29 INFO - [mozharness: 2016-11-26 07:42:29.165098Z] Running run-tests step. 23:42:29 INFO - Running pre-action listener: _resource_record_pre_action 23:42:29 INFO - Running main action method: run_tests 23:42:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:42:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:42:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:42:29 INFO - 2016-11-25 23:42:29.179 screenresolution[1628:8270] starting screenresolution argv=screenresolution get 23:42:29 INFO - 2016-11-25 23:42:29.182 screenresolution[1628:8270] Display 0: 1600x1200x32@60 23:42:29 INFO - 2016-11-25 23:42:29.188 screenresolution[1629:8272] starting screenresolution argv=screenresolution list 23:42:29 INFO - Available Modes on Display 0 23:42:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:42:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:42:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:42:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:42:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:42:29 INFO - Intel Iris: 23:42:29 INFO - Chipset Model: Intel Iris 23:42:29 INFO - Type: GPU 23:42:29 INFO - Bus: Built-In 23:42:29 INFO - VRAM (Dynamic, Max): 1536 MB 23:42:29 INFO - Vendor: Intel (0x8086) 23:42:29 INFO - Device ID: 0x0a2e 23:42:29 INFO - Revision ID: 0x0009 23:42:29 INFO - Displays: 23:42:29 INFO - Display: 23:42:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:42:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:42:29 INFO - Display Serial Number: 1600x1200 60 23:42:29 INFO - Main Display: Yes 23:42:29 INFO - Mirror: Off 23:42:29 INFO - Online: Yes 23:42:29 INFO - Rotation: Supported 23:42:29 INFO - Return code: 0 23:42:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:42:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:42:29 INFO - Python 2.7.3 23:42:29 INFO - Return code: 0 23:42:29 INFO - grabbing minidump binary from tooltool 23:42: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')]} 23:42: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 0x10aa8cd30>, '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 0x10a8f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa84b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:42: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 23:42: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 23:42: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 23:42:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:42:29 INFO - Return code: 0 23:42:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:42:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:42:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:42:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:42:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:42: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', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UJO46V69QtS6io5-Q0MnCw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0061', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 23:42: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 --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UJO46V69QtS6io5-Q0MnCw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0061 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 23:42:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WUct8QxthJ/Render', 23:42:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:42:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:42:29 INFO - 'HOME': '/Users/cltbld', 23:42:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:42:29 INFO - 'LOGNAME': 'cltbld', 23:42:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:42:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:42:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:42:29 INFO - 'MOZ_NO_REMOTE': '1', 23:42:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:42:29 INFO - 'NO_EM_RESTART': '1', 23:42:29 INFO - 'PAGER': '/bin/cat', 23:42:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:42:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:42:29 INFO - 'PWD': '/builds/slave/test', 23:42:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:42:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:42:29 INFO - 'SHELL': '/bin/bash', 23:42:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJQbzKhatL/Listeners', 23:42:29 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 23:42:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:42:29 INFO - 'USER': 'cltbld', 23:42:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:42:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:42:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:42:29 INFO - 'XPC_FLAGS': '0x0', 23:42:29 INFO - 'XPC_SERVICE_NAME': '0', 23:42:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:42:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UJO46V69QtS6io5-Q0MnCw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0061', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 23:42:29 INFO - mozversion application_buildid: 20161125212719 23:42:29 INFO - mozversion application_changeset: f8f4eaac1701107f794b48891bcca2c95d39d503 23:42:29 INFO - mozversion application_display_name: Nightly 23:42:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:42:29 INFO - mozversion application_name: Firefox 23:42:29 INFO - mozversion application_remotingname: firefox 23:42:29 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 23:42:29 INFO - mozversion application_vendor: Mozilla 23:42:29 INFO - mozversion application_version: 53.0a1 23:42:29 INFO - mozversion platform_buildid: 20161125212719 23:42:29 INFO - mozversion platform_changeset: f8f4eaac1701107f794b48891bcca2c95d39d503 23:42:29 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 23:42:29 INFO - mozversion platform_version: 53.0a1 23:42:29 INFO - using testdate: 1480146149 23:42:29 INFO - actual date: 1480146149 23:42:29 INFO - starting webserver on 'localhost:49240' 23:42:29 INFO - SUITE-START | Running 2 tests 23:42:29 INFO - TEST-START | dromaeo_css 23:42:29 INFO - operating with platform_type : mac_ 23:42:29 INFO - Initialising browser for dromaeo_css test... 23:42:29 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/tmppWEaWF/profile http://localhost:49240/getInfo.html) 23:42:32 INFO - PROCESS | 1639 | __metrics Screen width/height:1600/1200 23:42:32 INFO - PROCESS | 1639 | colorDepth:24 23:42:32 INFO - PROCESS | 1639 | Browser inner width/height: 1024/689 23:42:32 INFO - PROCESS | 1639 | __metrics 23:42:32 INFO - PROCESS | 1639 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:42:32 INFO - PROCESS | 1639 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:42:33 INFO - TEST-INFO | 1639: exit 0 23:42:33 INFO - Browser initialized. 23:42:33 INFO - Running cycle 1/1 for dromaeo_css test... 23:42:33 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/tmppWEaWF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:42:45 INFO - PROCESS | 1642 | RSS: Main: 245456896 23:42:45 INFO - PROCESS | 1642 | 23:43:36 INFO - PROCESS | 1642 | 1480146216566 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] 23:45:49 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 23:45:52 INFO - PROCESS | 1642 | RSS: Main: 452943872 23:45:52 INFO - PROCESS | 1642 | 23:48:55 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 23:48:56 INFO - PROCESS | 1642 | RSS: Main: 374857728 23:48:56 INFO - PROCESS | 1642 | 23:51:58 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 23:51:59 INFO - PROCESS | 1642 | RSS: Main: 343756800 23:51:59 INFO - PROCESS | 1642 | 23:55:01 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 23:55:02 INFO - PROCESS | 1642 | RSS: Main: 344825856 23:55:02 INFO - PROCESS | 1642 | 23:58:05 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 23:58:06 INFO - PROCESS | 1642 | RSS: Main: 328740864 23:58:06 INFO - PROCESS | 1642 | 00:01:09 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 00:01:09 INFO - PROCESS | 1642 | RSS: Main: 449708032 00:01:09 INFO - PROCESS | 1642 | 00:01:09 INFO - PROCESS | 1642 | __start_tp_report 00:01:09 INFO - PROCESS | 1642 | _x_x_mozilla_page_load 00:01:09 INFO - PROCESS | 1642 | _x_x_mozilla_page_load_details 00:01:09 INFO - PROCESS | 1642 | |i|pagename|runs| 00:01:09 INFO - PROCESS | 1642 | |0;dojo.html;3272.492763621638;3302.9504557431655;3321.90034298971;3335.566376371072;3357.6978071973517;5517.172424136382;5584.246126772884;5622.4939755422;5697.230873832032;5779.219805326285;5587.692676902769;5691.800786972457;5769.04810706233;5774.422557744224;5798.304203495565;5034.144195486758;5082.618803589746;5103.336566246383;5168.302279192318;5212;5250.976059668366;5289.920651190216;5301.019311427398;5303.814366497173;5363.115086010804;5241.475852414766;5268.868278293035;5281.8943621127555;5312.2828418163635;5367.812126575571;5101.489851014906;5144.4083930348115;5158.819441319573;5187.584993200535;5188.066148093341;5057.797688092472;5146.202338637517;5156.407013193493;5156.793728250866;5174.896502069938;5660.801871934504;5662.8301150965535;5664;5671.404502527216;5686.800961966353;6131.449496612492;6133.1510485532735;6152.5693201475915;6188.195534580521;6189.6905154742535;6289.276733175698;6292;6309.274433440121;6310.87378252437;6315.084312774622;5724.542036637059;5780.9710951445395;5802.187693722883;5804.622699524602;5840.707964601753;6207.317195108534;6243.406876346739;6259.217597800275;6281.654509001957;6282.528810339151;6103.125543060039;6124.11200375943;6149.169862068653;6173.166622505994;6190.350013248634;6261.467775239155;6264.21697287839;6284.277308109626;6285.151504546828;6294.685265736694;6014.308354539284;6051.757929682852;6195.4424101831055;6209.161763162006;6245.125682404467;6139.259792094406;6140;6166.568340216142;6168.660723660242;6192.969035154795;4971.055499455092;4971.850844474599;4978.726170060609;4980.128477516492;4993.8501844943985;4436.223660859337;4437.378766972626;4442.378067070613;4446.199684056836;4492.415985921809;5411.972940135274;5426.348838139448;5440.455954404528;5751.913721294184;5761.567882408753;5269.420363759983;6255.46828519581;6258.874822503524;6290;6297.622142671455;5793.217915581343;5810.535157187415;5826.563007774349;5836.4747172753805;5895.29256489224;5283.550898173621;5292.735363231823;5398.514133727984;5413.918791218135;5421.539169170585;6217;6254.687265636791;6257.7184026718;6272.247330320391;6354.4281014708895;6282.905756413566;6292.370762923854;6316.147320111726;6317.463015643538;6327.462165715966;6264.708674557943;6268.905966410416;6289.811305660654;6322.3045464997895;6332.145160403471;6029.125776762257;6101.974255744823;6127.509799215963;6143.986242269975;6147.385261473817;6265.310815810349;6266.592671476339;6272.5922815016875;6281.528885333527;6294.276158241861;5936.3470018297085;6123.08153776937;6128.387161283836;6128.448439640276;6131.172291740736;5925.585209035498;5969.671668058299;5985.012472941916;5996.820095397145;6091.817245482643;4587.908241835078;5840.2991641003355;5882.647041177543;5902.557308201988;5940.851478713067;5219.8434046978655;5912.935671578985;5917.378675239038;6059.818205453844;6081.239845019372;5191.32512773337;5633.605647604629;5786.971065144648;5802.854928626818;5821.38875418075 00:01:09 INFO - PROCESS | 1642 | |1;ext.html;463.4855310605229;493.4917035453482;516.599635282656;534.126702840855;559.4573263933983;34101.82949085254;39937.80031099844;40263.99999999996;41794;41883.371749423706;40873.7956310218;40936.59063409365;41413.58586414135;41458.79270603643;41478.792606037;8589.914100859005;9428.434293942368;9606.519674016306;9620.230381569458;9627.374220675669;8649.65401383944;9370.718878433658;11525.711857203552;11525.769484610302;11671.474783634714;11410.030147437477;11487.195896287263;11492.367919764409;11496.942515287412;11504.42477876103;8598.05421403645;8806.955965220133;11448.542058317698;11519.36643484608;11531.250468719507;10989.230753847234;11355.205135640508;11368.431578421047;11498.65504034872;11503.769924601545;38736.22527549462;39085;39091;39124;39127.60872391268;3373.746968977312;3386.6274709781906;3510.3681337359267;3711.851525938986;3913.9217215655813;30148.57758663705;30350.54474182889;30467.847660761556;30470.847645761853;30604.84697576498;3131.9530207046914;3141.387429451258;3318.1041118898;3354.480055591387;3358.983205083983;461320;488497;488725;488830;490867;56483.71758141183;56614.867702645715;56718.14922776162;57227.71386143126;57241.713791430775;60531;60709.69645151746;60873.78252434926;61038.08442873361;61080.38919610836;14986;15209.695806083817;15216.543503694907;15218.847811521964;15249.313780879666;10887.83668244977;10933.070688991525;11147.442627868573;11170.497327620276;11913.22564033335;15531.378744850079;15665.373385064699;15978.280977356048;16082.517524474284;16198.433054843024;6745.9662701686175;6754.257031726506;6758.121444212221;6864.141982252218;6873.965630171817;830.1490971754;893.5820486638634;911.64901512918;931.1759093202564;1080.4165750494644;25191.74808251894;28052.43895122127;29152.854235728686;29619.703802961696;29634.55548166779;980.4656462228103;1093.6172339681207;1099.9010089092021;1115.8884111588939;1161.814109742437;14926.552203433914;14980.550583482513;15126.697466050617;15167.469138580318;15245;623845;663912;675015;676259;680101.1913987367;55266.894662107334;55528.611784704895;55873.720631397395;55928.72035639796;56098.59753506195;1251.6933351329164;1290.2581015915698;1373.1486478383467;1393.4704812171512;1407.240090351199;21421.46446338854;25806;28453.715462845106;29970;30742.385152297276;53660.73169634127;54312;54477.72761136169;54527.72736136294;54569.454305456435;561038;565439;571165;573455;573551;25377.238682839543;25470.363240919127;25725.970961161343;25810;26316.21051368462;43616;44454.88862778457;44858.55141448544;44954.576730920366;45207;37003.629963701096;37191.25617487689;37436.43845342268;38120.23759524739;38596.421053683654;37051.444228336055;37221.25557488781;37502.729962078476;38002.619973799905;38369.2326153481;35553;36458.63541364553;36570.634293657786;37439.43840842444;37513.2497350046;28336.291592709524;30179.849100754356;32195;33103.66896331102;33185.66814331826;20214.696779548023;24126.51746965086;24675.876620616782;24978.62532062057;25052.248432547054 00:01:09 INFO - PROCESS | 1642 | |2;jquery.html;346.9254110366274;362.1308858739026;368.1807977250616;376.7814667492854;398.67507980995487;14337.139771613698;14671.339789709462;15514.052330057295;19566.51083722904;19992.30026949057;18915;19551.902240488776;20073.799262007375;20316.796832031676;20386.286479973205;5050.419201791786;5513.9448605513935;5808.767649294024;5814.447627475383;5819.098039803837;4756.929690819573;5180.430152683201;5650.74571644277;5695.430456954292;5731.656100633975;5668;5675.148727690838;5676.120201368773;5682.034054210773;5687.289088863892;4561;5583.609147359666;5634.661920284755;5637.661740295554;5664.376918538957;4738.6209103272;4965.801367945278;4976.950230497721;4981.850544483671;4983.252512123175;18697.62604747911;24075;24442.511149776907;24821.379465513324;25130.874345628155;1842.4012196036208;2184.049938276839;2191.5726433345503;2542.224621490445;2779.9444011119663;9639.566219520138;10179.491025448699;10417.27079104463;14559.78160327596;15822.683546329125;1766.611345504;1811.411291330323;1822.1526989949605;1915.4445210888825;1915.4636701723539;109899.45050274697;114574.42712786466;125891;130393;131078;10984.725381865517;11333.60332388379;11435.542578296941;11441.828372574419;11621.76756464866;14367.568972930829;14382.28088595566;14389.28053597316;14405.207713575854;14424.134551926918;8029.197080291924;8122.959385203036;8151.918480815235;8169.795755106051;8223.712170074139;7897.60511974399;7898.842023159505;7989.800254993672;8051.4766540174705;8069.233422824939;4335.111302183045;4415.757133357632;4463.218936686132;4475.261581839025;4551.6813823032735;1885.114885114885;1889.7590929156584;1909.3794516782102;1912.2064343297654;1947.6786330255632;716.781381678593;745.7166276814776;774.4385320642489;858.347655781598;868.4572142410993;17259.79181457311;17668.558286042953;20271.986400679907;20679;21012.894935525226;699.8460338725574;824.6660102658433;888.3426264564305;902.4838271703601;926.2821313482106;9093.454392736458;10562.630307939186;10968;10976.176786741027;10983.450827458595;515891;516285;525955;526594.175728034;526730;121266;125708.0294160295;125814;127924;132134;932.6036434515304;1000.3847633705219;1021.8467229915572;1022.2639699416409;1022.3661329975314;17730.113494324974;17930.731039034687;18079.36722214716;18663.44009679712;18883.150258238686;51943;54095.729521353715;54884.17673734817;55400;55601.88796224134;78181.60909195417;78391.21608783839;79144;79513.2048679529;80124.17702725015;6994.965025174842;7245.963770181115;7328.816779580553;7874.488158269924;7971.601419929097;12456.813147802972;12499.625011249676;13021.088523803623;13224.404901779635;13291.80062299047;10076.992300769864;10372.688819335432;10846.783064338826;10860.076893464144;11226.494807733508;10009.547660823146;10402.791944161227;10884.51019704131;11110.000099991039;11181.32912025281;10159.88241293472;10462.424566648602;10990.285631433931;11627.093226693327;11639.709007274885;7336.156342020522;7374.225706300976;7453.2546745325035;7536.623168841646;7586.468947173645;10044.849327260243;10957.890421095686;11243.325400475998;11354.659360219206;11372.943135284271 00:01:09 INFO - PROCESS | 1642 | |3;mootools.html;1408.0847449158045;1452.114210331698;1603.4468108502563;1758.9296428142875;1809.3938530592243;7680.807979800507;7897.170797066305;7919.366450683946;8308.459950103239;8316.417850750435;7276.199618042011;7569.810754731121;7571.6592753326095;7888.211178882123;8157.306628936532;2112.609167304046;2159.827213822898;2272.250157448038;2313.710786151731;2315.988420057897;2288.2220045184636;2315.166540045582;2329.860208387494;2332.300309907021;2335.318570629153;1819.3814103204907;2025.43287879394;2108.8102070813625;2109.2090466075224;2120.0883620043373;2081.271554955761;2226.7105276313973;2293.0942277800177;2305.827062970284;2311.3874823171873;2287.5996700577334;2312.606856834342;2318.07277089164;2318.953620927572;2324.5002324500147;8183.71357002502;8190.754277371689;8271.1315311892;8285;8292.087870334259;4970.403551573798;5136.229565565158;5140.974295128538;5166.354205724285;5166.380034395859;3344.9498257526157;3433.673800988927;3501.037214765934;3515.859365625372;3522.4540196269363;907.3195103672246;1043.4417586591137;1061.9044286014296;1077.3643550305358;1083.1659622090947;10643.148548116133;10709.143268538499;10779.083777878812;10779.4610269487;10780.245382823205;4156.168766246702;4330.480342358874;4411.382406463098;4442.089371678798;4457;8521.744347669579;8535.402521823413;8547.786305342415;8552.059273479912;8553.914460855436;5040.571551418097;5041.344625198701;5041.369828771403;5043.420006699276;5045.646804723708;4543.727376357429;4681.906361872743;4714.15145273854;4727.905441891143;4748.453927798348;3557.377458944726;3599.5140656011627;3612.259486805199;3624.836882340301;3694.4692885373606;1115.4980258883536;1130.5364800431782;1130.7003644034337;1140.4126874659557;1145.0553293532869;636.5353292096795;781.261707686231;786.3826895886701;893.8525143351404;907.274180655473;5031.647784655112;5626.043572592669;5668.234788303526;5681.147827825859;5791.594588378857;762.92752188542;895.7268033249921;929.8140371925507;946.8437707778277;979.5934687104772;3261.673832616719;3373.4433818420175;3648.087978005499;3803.67668748154;3922.2547715933883;9477.28920330978;10062.597496100221;10097;10098.343607665625;10138.391696498235;9337.253019758404;9796.804063918682;10032.598696052222;10040.397576145455;10048.69853904384;7560.168381478033;7770;7773.883391749128;7789.3768498519985;7793.688252469838;5459.918101228564;5491.203775452457;5502.58730595215;5801.216835727123;5917.733701983507;8669.696560620348;9348.205402540589;9350.438973661601;9355.578998944928;9383.577739001625;8978.877640294962;9347.345685801929;9432.481213533321;9582.28132890022;9604.183644390305;7136;7167.713291468492;7228.024216730674;7231.204567497466;7278.344948954619;7706.691732330644;7805.570693611906;7820.452568320163;7834.529928204326;7850.921490785019;6691.933080669131;6893.276205998298;6901.482388820959;6952.895706564506;7031.261717519586;6764.526483146133;6777.8983315249325;6784.830379240361;6829.282925292773;6861.318976850704;6237.656928868774;6453.774117905851;6548.345165483413;6586.50601204902;6619.172603424572;4776.617870570277;4801.303810947463;4811.326414302071;4834.347363105936;4932.8520144395725;4781.713097214179;4835.854924352275;4867.14824905633;4932.136876046606;4975.527324904128 00:01:09 INFO - PROCESS | 1642 | |4;prototype.html;279.49132578706747;302.1027548181899;305.2369077306733;311.7646177136263;317.6442384529325;5114.905482183277;5158.6646867953605;5160.922586161201;5166.5866730661555;5193.532582067608;4807.3269742236025;4967.205247160455;4972.925406118911;4981.227909673998;4983.127952608297;3295.2256219788333;3535.1515636247373;3538.7345949053797;3769.6230376962358;3896.201278737852;3246.448103822344;3577.409727394977;3906.5507466641275;3921.1373497830573;3956.802159892008;3453.4474484082552;3483.076984599083;3588.1567831559564;3879.4568760373572;3900.941485877714;3521.982390088046;3795.551869044451;3811.3711237645757;3814.1036856338737;3891.708121890855;3437.535932649085;3437.553118094657;3438;3442.621311655716;3449.9827500862334;4946.455889852114;4947.925781113287;4948.703077815343;4956.826511072131;4969.676970996874;4205.38092834261;4430.047539778963;4437.955620443787;4455.888602784924;4462.642988560908;4379.890502737425;4469.508354081048;5245.396779370345;5272.710000949946;5312.476573627535;3770.8491660333552;3774.2828862516035;3781.0736369589354;3784.8675296364495;3803.3344164771056;4284.371938663319;4491.461024677027;5179.964007198575;5405.540504063881;5622.85942851432;3377.5102610121394;3795.2978698940783;3846.1730727893637;3848.5189351331082;3852;3717.219383929351;3898.4737060497037;3905.6094390560716;3909.2963266611714;3909.9218015639526;3765.002274397287;4429.379886815848;4442.955570444319;4470.614109626026;4502.482214545304;3973;4493.707908985905;5301.920471192935;5360.812371566976;5423.755930983115;1044.7858189071221;1180.5631916191064;1262.4508338872436;1262.4950019991893;1265.8227848101274;811.8619834628128;842.751388340441;852.861429990969;881.2025117268882;888.8400087984096;4045.604266528043;4282.6359759420175;4381.759003254788;4445.621327790902;4759.9524004759505;4417.889552761142;4651.906961860744;5204.817831375959;5246.842594722164;5261.605379596545;3934.1738234970976;4212.378234379786;4733.763311834464;4759.547842954914;4784.665073444826;2466.469709012571;2468.3829042739317;2777.6389069421252;2783.4433113377404;2813.7467627913584;4638.652101092432;5019.949800501949;5091.137963245092;5226.712530810766;5413.566914646819;4840.685355451885;4878.2681248844665;5171.4311425743135;5320.467953204648;5422.511973922287;4861.708297502232;5040.067587496325;5799.739011744482;5857.677827719516;5903.291605007426;4667.463241727245;5486.930048640476;5504.4770746778995;5660.688662123623;5662.961333919868;4843.515648435128;5247.291615631841;5481.616286859881;5555.333359996826;5580.86047848807;5039.370078740158;5067.442581315979;5844.327902291121;5846.473817356458;5857.707114644336;3739.7195210358786;3797.582265950688;3809.3333666608787;3812.8856134316015;3828.1960788234783;4992;5274.393444753749;5618.494335509824;5641.19219526826;5719.628224165416;5430.348358197041;5463;5467.890642187054;5505.724713764376;5509.044289277249;5474.671519708831;5493.890122197614;5505.697186654773;5507.642003269774;5556.555475561864;4558.3618293438285;5103.7192954387865;5126.410462796826;5401.783928642811;5416.891662166813;4825.782839772289;5155.587552995827;5387.973060134675;5402.5677945763455;5496.587755918402;4674.322223277538;4782.7847746850775;5129.74351282427;5154.664946778597;5343.839684809462 00:01:09 INFO - PROCESS | 1642 | |5;yui.html;693.2097408953792;713.4114355656582;746.1160569561856;769.1731388757088;792.4532072869721;13837.377318020688;14690.338934747935;14721.411143554245;14728.779068313985;14731.926340368296;13197.406116724747;13292.335383230848;13343.466261349558;13361.933190334059;13372.465101395932;682.0962225051801;691.8201267670416;827.8758186272078;840.0717207485717;851.5699571716306;833.3832963606948;834.4576025583389;838.7861095420659;841.898972123343;844.2233145506149;902.6163880350857;903.8644203369514;905.0858632780872;907.2016625369665;907.8320510705507;699.7061234281614;888.0897080492515;889.5285498685707;889.7330800759746;891.4562117108559;538.7090970875722;650.1483057195089;656.5699466849221;660.4551245222663;664.2228592546732;13636.454541818315;13665;13668.248246346448;13675.452981880811;13707.246101464416;12590.74818503634;12665.936670316682;12869;12927.67680807978;12997.935010324887;11651.170884502544;11756.294622322688;11806;11819.940900295529;12201.877981220163;4292.849750258726;4309.4397728295435;4386.8903277418;4488.865334039984;4493.842715504941;84232;84508;84617.57691211505;85068;85664;10204.540795664212;10267;10342.276040677081;10345.534450949724;10678;18854.905725471286;19158.904205478884;19232;19244.134013969404;19338.226470941005;7122.964385178041;7425.517341372793;7438.144613369532;7473.103227612722;7683.500572462772;4143;4257.787110644455;4293.677974151951;4305.590968857953;4341.435613370241;2954.5568164775027;3298.406286868387;3337.349216902681;3338.449155889251;3344.7658663893294;621.2606997672755;625.3381044314714;626.154691166921;627.438442593876;644.5745807766851;523.2804893271751;566.4731799426573;608.6256951974503;609.8658295175054;634.0267689097213;7123;8607.698730544402;8805.955970220108;8820.382573219813;8877.911220887838;559.30645998961;609.9115628233881;632.1371328137152;654.1855390039336;665.8535122273092;3757.9248415032093;3949.5852935441367;4065.836387787493;4304.677149213821;4401.603855653007;65502.34497655058;66859;69855;80264.59867700624;97640;95078.5246073779;97940.51029744805;98283;98297.50851245841;100012;53464.46535534673;54982.45017549773;63221;88425.67361489583;88917.11082889089;8583;8631.741047768577;8803.955980220058;9036.954815225881;9107.772305692411;49171.50828491813;49321.76695582639;49443.75278123586;49890.25164622461;50732.731681708254;70499.89251235969;71137.6850356685;71577;72750.63624682055;72903;11658;12013.579524716593;12067.215630983957;12134.211276267015;12288.692782680504;13315.267660278778;13549.932250338685;13909;13936.79094813599;14763.409463621334;12186.14696971239;12327.568535101229;12486.875131248935;12566.246025238515;12745.745085098431;12690.780679528463;12716.936415317865;12751;12789;13089.672758181121;11960.521579136737;12054.75890482203;12172.506685055723;12241.326727030098;12293.323867187391;9940.254480913816;9975.351602145838;10167.491625418552;10424.478776061318;10690.358578485315;10552.894471055191;10559.366438013743;10798.136149107895;10802;10844 00:01:09 INFO - PROCESS | 1642 | __end_tp_report 00:01:09 INFO - PROCESS | 1642 | __start_cc_report 00:01:09 INFO - PROCESS | 1642 | _x_x_mozilla_cycle_collect,2608 00:01:09 INFO - PROCESS | 1642 | __end_cc_report 00:01:09 INFO - PROCESS | 1642 | __startTimestamp1480147269982__endTimestamp 00:01:09 INFO - PROCESS | 1642 | 00:01:09 INFO - PROCESS | 1642 | ------- Summary: start ------- 00:01:09 INFO - PROCESS | 1642 | Number of tests: 6 00:01:09 INFO - PROCESS | 1642 | 00:01:09 INFO - PROCESS | 1642 | [#0] dojo.html Cycles:165 Average:5706.60 Median:5840.71 stddev:644.97 (11.0%) stddev-sans-first:618.04 00:01:09 INFO - PROCESS | 1642 | Values: 3272.5 3303.0 3321.9 3335.6 3357.7 5517.2 5584.2 5622.5 5697.2 5779.2 5587.7 5691.8 5769.0 5774.4 5798.3 5034.1 5082.6 5103.3 5168.3 5212.0 5251.0 5289.9 5301.0 5303.8 5363.1 5241.5 5268.9 5281.9 5312.3 5367.8 5101.5 5144.4 5158.8 5187.6 5188.1 5057.8 5146.2 5156.4 5156.8 5174.9 5660.8 5662.8 5664.0 5671.4 5686.8 6131.4 6133.2 6152.6 6188.2 6189.7 6289.3 6292.0 6309.3 6310.9 6315.1 5724.5 5781.0 5802.2 5804.6 5840.7 6207.3 6243.4 6259.2 6281.7 6282.5 6103.1 6124.1 6149.2 6173.2 6190.4 6261.5 6264.2 6284.3 6285.2 6294.7 6014.3 6051.8 6195.4 6209.2 6245.1 6139.3 6140.0 6166.6 6168.7 6193.0 4971.1 4971.9 4978.7 4980.1 4993.9 4436.2 4437.4 4442.4 4446.2 4492.4 5412.0 5426.3 5440.5 5751.9 5761.6 5269.4 6255.5 6258.9 6290.0 6297.6 5793.2 5810.5 5826.6 5836.5 5895.3 5283.6 5292.7 5398.5 5413.9 5421.5 6217.0 6254.7 6257.7 6272.2 6354.4 6282.9 6292.4 6316.1 6317.5 6327.5 6264.7 6268.9 6289.8 6322.3 6332.1 6029.1 6102.0 6127.5 6144.0 6147.4 6265.3 6266.6 6272.6 6281.5 6294.3 5936.3 6123.1 6128.4 6128.4 6131.2 5925.6 5969.7 5985.0 5996.8 6091.8 4587.9 5840.3 5882.6 5902.6 5940.9 5219.8 5912.9 5917.4 6059.8 6081.2 5191.3 5633.6 5787.0 5802.9 5821.4 00:01:09 INFO - PROCESS | 1642 | 00:01:09 INFO - PROCESS | 1642 | [#1] ext.html Cycles:180 Average:69878.26 Median:20451.55 stddev:154378.23 (754.8%) stddev-sans-first:154723.33 00:01:09 INFO - PROCESS | 1642 | Values: 463.5 493.5 516.6 534.1 559.5 34101.8 39937.8 40264.0 41794.0 41883.4 40873.8 40936.6 41413.6 41458.8 41478.8 8589.9 9428.4 9606.5 9620.2 9627.4 8649.7 9370.7 11525.7 11525.8 11671.5 11410.0 11487.2 11492.4 11496.9 11504.4 8598.1 8807.0 11448.5 11519.4 11531.3 10989.2 11355.2 11368.4 11498.7 11503.8 38736.2 39085.0 39091.0 39124.0 39127.6 3373.7 3386.6 3510.4 3711.9 3913.9 30148.6 30350.5 30467.8 30470.8 30604.8 3132.0 3141.4 3318.1 3354.5 3359.0 461320.0 488497.0 488725.0 488830.0 490867.0 56483.7 56614.9 56718.1 57227.7 57241.7 60531.0 60709.7 60873.8 61038.1 61080.4 14986.0 15209.7 15216.5 15218.8 15249.3 10887.8 10933.1 11147.4 11170.5 11913.2 15531.4 15665.4 15978.3 16082.5 16198.4 6746.0 6754.3 6758.1 6864.1 6874.0 830.1 893.6 911.6 931.2 1080.4 25191.7 28052.4 29152.9 29619.7 29634.6 980.5 1093.6 1099.9 1115.9 1161.8 14926.6 14980.6 15126.7 15167.5 15245.0 623845.0 663912.0 675015.0 676259.0 680101.2 55266.9 55528.6 55873.7 55928.7 56098.6 1251.7 1290.3 1373.1 1393.5 1407.2 21421.5 25806.0 28453.7 29970.0 30742.4 53660.7 54312.0 54477.7 54527.7 54569.5 561038.0 565439.0 571165.0 573455.0 573551.0 25377.2 25470.4 25726.0 25810.0 26316.2 43616.0 44454.9 44858.6 44954.6 45207.0 37003.6 37191.3 37436.4 38120.2 38596.4 37051.4 37221.3 37502.7 38002.6 38369.2 35553.0 36458.6 36570.6 37439.4 37513.2 28336.3 30179.8 32195.0 33103.7 33185.7 20214.7 24126.5 24675.9 24978.6 25052.2 00:01:09 INFO - PROCESS | 1642 | 00:01:09 INFO - PROCESS | 1642 | [#2] jquery.html Cycles:180 Average:32741.41 Median:13133.29 stddev:88141.12 (671.1%) stddev-sans-first:88354.82 00:01:10 INFO - PROCESS | 1642 | Values: 346.9 362.1 368.2 376.8 398.7 14337.1 14671.3 15514.1 19566.5 19992.3 18915.0 19551.9 20073.8 20316.8 20386.3 5050.4 5513.9 5808.8 5814.4 5819.1 4756.9 5180.4 5650.7 5695.4 5731.7 5668.0 5675.1 5676.1 5682.0 5687.3 4561.0 5583.6 5634.7 5637.7 5664.4 4738.6 4965.8 4977.0 4981.9 4983.3 18697.6 24075.0 24442.5 24821.4 25130.9 1842.4 2184.0 2191.6 2542.2 2779.9 9639.6 10179.5 10417.3 14559.8 15822.7 1766.6 1811.4 1822.2 1915.4 1915.5 109899.5 114574.4 125891.0 130393.0 131078.0 10984.7 11333.6 11435.5 11441.8 11621.8 14367.6 14382.3 14389.3 14405.2 14424.1 8029.2 8123.0 8151.9 8169.8 8223.7 7897.6 7898.8 7989.8 8051.5 8069.2 4335.1 4415.8 4463.2 4475.3 4551.7 1885.1 1889.8 1909.4 1912.2 1947.7 716.8 745.7 774.4 858.3 868.5 17259.8 17668.6 20272.0 20679.0 21012.9 699.8 824.7 888.3 902.5 926.3 9093.5 10562.6 10968.0 10976.2 10983.5 515891.0 516285.0 525955.0 526594.2 526730.0 121266.0 125708.0 125814.0 127924.0 132134.0 932.6 1000.4 1021.8 1022.3 1022.4 17730.1 17930.7 18079.4 18663.4 18883.2 51943.0 54095.7 54884.2 55400.0 55601.9 78181.6 78391.2 79144.0 79513.2 80124.2 6995.0 7246.0 7328.8 7874.5 7971.6 12456.8 12499.6 13021.1 13224.4 13291.8 10077.0 10372.7 10846.8 10860.1 11226.5 10009.5 10402.8 10884.5 11110.0 11181.3 10159.9 10462.4 10990.3 11627.1 11639.7 7336.2 7374.2 7453.3 7536.6 7586.5 10044.8 10957.9 11243.3 11354.7 11372.9 00:01:10 INFO - PROCESS | 1642 | 00:01:10 INFO - PROCESS | 1642 | [#3] mootools.html Cycles:180 Average:5334.11 Median:5042.39 stddev:2925.24 (58.0%) stddev-sans-first:2918.57 00:01:10 INFO - PROCESS | 1642 | Values: 1408.1 1452.1 1603.4 1758.9 1809.4 7680.8 7897.2 7919.4 8308.5 8316.4 7276.2 7569.8 7571.7 7888.2 8157.3 2112.6 2159.8 2272.3 2313.7 2316.0 2288.2 2315.2 2329.9 2332.3 2335.3 1819.4 2025.4 2108.8 2109.2 2120.1 2081.3 2226.7 2293.1 2305.8 2311.4 2287.6 2312.6 2318.1 2319.0 2324.5 8183.7 8190.8 8271.1 8285.0 8292.1 4970.4 5136.2 5141.0 5166.4 5166.4 3344.9 3433.7 3501.0 3515.9 3522.5 907.3 1043.4 1061.9 1077.4 1083.2 10643.1 10709.1 10779.1 10779.5 10780.2 4156.2 4330.5 4411.4 4442.1 4457.0 8521.7 8535.4 8547.8 8552.1 8553.9 5040.6 5041.3 5041.4 5043.4 5045.6 4543.7 4681.9 4714.2 4727.9 4748.5 3557.4 3599.5 3612.3 3624.8 3694.5 1115.5 1130.5 1130.7 1140.4 1145.1 636.5 781.3 786.4 893.9 907.3 5031.6 5626.0 5668.2 5681.1 5791.6 762.9 895.7 929.8 946.8 979.6 3261.7 3373.4 3648.1 3803.7 3922.3 9477.3 10062.6 10097.0 10098.3 10138.4 9337.3 9796.8 10032.6 10040.4 10048.7 7560.2 7770.0 7773.9 7789.4 7793.7 5459.9 5491.2 5502.6 5801.2 5917.7 8669.7 9348.2 9350.4 9355.6 9383.6 8978.9 9347.3 9432.5 9582.3 9604.2 7136.0 7167.7 7228.0 7231.2 7278.3 7706.7 7805.6 7820.5 7834.5 7850.9 6691.9 6893.3 6901.5 6952.9 7031.3 6764.5 6777.9 6784.8 6829.3 6861.3 6237.7 6453.8 6548.3 6586.5 6619.2 4776.6 4801.3 4811.3 4834.3 4932.9 4781.7 4835.9 4867.1 4932.1 4975.5 00:01:10 INFO - PROCESS | 1642 | 00:01:10 INFO - PROCESS | 1642 | [#4] prototype.html Cycles:180 Average:4295.79 Median:4771.37 stddev:1314.60 (27.6%) stddev-sans-first:1283.25 00:01:10 INFO - PROCESS | 1642 | Values: 279.5 302.1 305.2 311.8 317.6 5114.9 5158.7 5160.9 5166.6 5193.5 4807.3 4967.2 4972.9 4981.2 4983.1 3295.2 3535.2 3538.7 3769.6 3896.2 3246.4 3577.4 3906.6 3921.1 3956.8 3453.4 3483.1 3588.2 3879.5 3900.9 3522.0 3795.6 3811.4 3814.1 3891.7 3437.5 3437.6 3438.0 3442.6 3450.0 4946.5 4947.9 4948.7 4956.8 4969.7 4205.4 4430.0 4438.0 4455.9 4462.6 4379.9 4469.5 5245.4 5272.7 5312.5 3770.8 3774.3 3781.1 3784.9 3803.3 4284.4 4491.5 5180.0 5405.5 5622.9 3377.5 3795.3 3846.2 3848.5 3852.0 3717.2 3898.5 3905.6 3909.3 3909.9 3765.0 4429.4 4443.0 4470.6 4502.5 3973.0 4493.7 5301.9 5360.8 5423.8 1044.8 1180.6 1262.5 1262.5 1265.8 811.9 842.8 852.9 881.2 888.8 4045.6 4282.6 4381.8 4445.6 4760.0 4417.9 4651.9 5204.8 5246.8 5261.6 3934.2 4212.4 4733.8 4759.5 4784.7 2466.5 2468.4 2777.6 2783.4 2813.7 4638.7 5019.9 5091.1 5226.7 5413.6 4840.7 4878.3 5171.4 5320.5 5422.5 4861.7 5040.1 5799.7 5857.7 5903.3 4667.5 5486.9 5504.5 5660.7 5663.0 4843.5 5247.3 5481.6 5555.3 5580.9 5039.4 5067.4 5844.3 5846.5 5857.7 3739.7 3797.6 3809.3 3812.9 3828.2 4992.0 5274.4 5618.5 5641.2 5719.6 5430.3 5463.0 5467.9 5505.7 5509.0 5474.7 5493.9 5505.7 5507.6 5556.6 4558.4 5103.7 5126.4 5401.8 5416.9 4825.8 5155.6 5388.0 5402.6 5496.6 4674.3 4782.8 5129.7 5154.7 5343.8 00:01:10 INFO - PROCESS | 1642 | 00:01:10 INFO - PROCESS | 1642 | [#5] yui.html Cycles:180 Average:18815.99 Median:49382.76 stddev:26616.95 (53.9%) stddev-sans-first:26656.84 00:01:10 INFO - PROCESS | 1642 | Values: 693.2 713.4 746.1 769.2 792.5 13837.4 14690.3 14721.4 14728.8 14731.9 13197.4 13292.3 13343.5 13361.9 13372.5 682.1 691.8 827.9 840.1 851.6 833.4 834.5 838.8 841.9 844.2 902.6 903.9 905.1 907.2 907.8 699.7 888.1 889.5 889.7 891.5 538.7 650.1 656.6 660.5 664.2 13636.5 13665.0 13668.2 13675.5 13707.2 12590.7 12665.9 12869.0 12927.7 12997.9 11651.2 11756.3 11806.0 11819.9 12201.9 4292.8 4309.4 4386.9 4488.9 4493.8 84232.0 84508.0 84617.6 85068.0 85664.0 10204.5 10267.0 10342.3 10345.5 10678.0 18854.9 19158.9 19232.0 19244.1 19338.2 7123.0 7425.5 7438.1 7473.1 7683.5 4143.0 4257.8 4293.7 4305.6 4341.4 2954.6 3298.4 3337.3 3338.4 3344.8 621.3 625.3 626.2 627.4 644.6 523.3 566.5 608.6 609.9 634.0 7123.0 8607.7 8806.0 8820.4 8877.9 559.3 609.9 632.1 654.2 665.9 3757.9 3949.6 4065.8 4304.7 4401.6 65502.3 66859.0 69855.0 80264.6 97640.0 95078.5 97940.5 98283.0 98297.5 100012.0 53464.5 54982.5 63221.0 88425.7 88917.1 8583.0 8631.7 8804.0 9037.0 9107.8 49171.5 49321.8 49443.8 49890.3 50732.7 70499.9 71137.7 71577.0 72750.6 72903.0 11658.0 12013.6 12067.2 12134.2 12288.7 13315.3 13549.9 13909.0 13936.8 14763.4 12186.1 12327.6 12486.9 12566.2 12745.7 12690.8 12716.9 12751.0 12789.0 13089.7 11960.5 12054.8 12172.5 12241.3 12293.3 9940.3 9975.4 10167.5 10424.5 10690.4 10552.9 10559.4 10798.1 10802.0 10844.0 00:01:10 INFO - PROCESS | 1642 | -------- Summary: end -------- 00:01:10 INFO - PROCESS | 1642 | 00:01:10 INFO - TEST-INFO | 1642: exit 0 00:01:10 INFO - TEST-OK | dromaeo_css | took 1120868ms 00:01:10 INFO - TEST-START | kraken 00:01:10 INFO - operating with platform_type : mac_ 00:01:10 INFO - Initialising browser for kraken test... 00:01:10 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/tmpAtINaz/profile http://localhost:49240/getInfo.html) 00:01:13 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 00:01:13 INFO - PROCESS | 1648 | colorDepth:24 00:01:13 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 00:01:13 INFO - PROCESS | 1648 | __metrics 00:01:13 INFO - PROCESS | 1648 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 00:01:13 INFO - PROCESS | 1648 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 00:01:14 INFO - TEST-INFO | 1648: exit 0 00:01:14 INFO - Browser initialized. 00:01:14 INFO - Running cycle 1/1 for kraken test... 00:01:14 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/tmpAtINaz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:01:26 INFO - PROCESS | 1649 | RSS: Main: 236941312 00:01:26 INFO - PROCESS | 1649 | 00:01:29 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 00:01:30 INFO - PROCESS | 1649 | RSS: Main: 295448576 00:01:30 INFO - PROCESS | 1649 | 00:01:32 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 00:01:33 INFO - PROCESS | 1649 | RSS: Main: 297930752 00:01:33 INFO - PROCESS | 1649 | 00:01:36 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 00:01:37 INFO - PROCESS | 1649 | RSS: Main: 390615040 00:01:37 INFO - PROCESS | 1649 | 00:01:39 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 00:01:40 INFO - PROCESS | 1649 | RSS: Main: 291807232 00:01:40 INFO - PROCESS | 1649 | 00:01:41 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:01:42 INFO - PROCESS | 1649 | RSS: Main: 295440384 00:01:42 INFO - PROCESS | 1649 | 00:01:45 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 00:01:45 INFO - PROCESS | 1649 | RSS: Main: 339341312 00:01:45 INFO - PROCESS | 1649 | 00:01:48 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 00:01:49 INFO - PROCESS | 1649 | RSS: Main: 339021824 00:01:49 INFO - PROCESS | 1649 | 00:01:52 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 00:01:53 INFO - PROCESS | 1649 | RSS: Main: 336683008 00:01:53 INFO - PROCESS | 1649 | 00:01:54 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:01:55 INFO - PROCESS | 1649 | RSS: Main: 310755328 00:01:55 INFO - PROCESS | 1649 | 00:01:57 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:01:57 INFO - PROCESS | 1649 | RSS: Main: 313499648 00:01:57 INFO - PROCESS | 1649 | 00:02:00 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:02:00 INFO - PROCESS | 1649 | RSS: Main: 315375616 00:02:00 INFO - PROCESS | 1649 | 00:02:03 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:02:04 INFO - PROCESS | 1649 | RSS: Main: 333697024 00:02:04 INFO - PROCESS | 1649 | 00:02:07 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:02:08 INFO - PROCESS | 1649 | RSS: Main: 327618560 00:02:08 INFO - PROCESS | 1649 | 00:02:09 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 00:02:10 INFO - PROCESS | 1649 | RSS: Main: 371691520 00:02:10 INFO - PROCESS | 1649 | 00:02:10 INFO - PROCESS | 1649 | __start_tp_report 00:02:10 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 00:02:10 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 00:02:10 INFO - PROCESS | 1649 | |i|pagename|runs| 00:02:10 INFO - PROCESS | 1649 | |0;ai-astar;102;114;116;114;115;115;115;113;114;113 00:02:10 INFO - PROCESS | 1649 | |1;audio-beat-detection;147;114;133;139;144;142;143;121;130;109 00:02:10 INFO - PROCESS | 1649 | |2;audio-dft;186;181;183;185;186;181;182;184;183;182 00:02:10 INFO - PROCESS | 1649 | |3;audio-fft;78;59;59;85;65;60;58;59;73;58 00:02:10 INFO - PROCESS | 1649 | |4;audio-oscillator;83;73;86;71;69;69;83;68;69;83 00:02:10 INFO - PROCESS | 1649 | |5;imaging-gaussian-blur;86;84;87;84;86;83;86;83;87;83 00:02:10 INFO - PROCESS | 1649 | |6;imaging-darkroom;116;159;106;100;95;94;94;95;94;95 00:02:10 INFO - PROCESS | 1649 | |7;imaging-desaturate;126;130;131;86;84;84;87;85;84;83 00:02:10 INFO - PROCESS | 1649 | |8;json-parse-financial;59;58;57;58;55;57;58;56;57;53 00:02:10 INFO - PROCESS | 1649 | |9;json-stringify-tinderbox;67;80;77;72;74;73;70;71;70;71 00:02:10 INFO - PROCESS | 1649 | |10;stanford-crypto-aes;87;88;87;87;89;89;89;87;89;90 00:02:10 INFO - PROCESS | 1649 | |11;stanford-crypto-ccm;152;151;148;153;152;159;172;149;195;163 00:02:10 INFO - PROCESS | 1649 | |12;stanford-crypto-pbkdf2;177;172;171;175;174;173;177;172;177;174 00:02:10 INFO - PROCESS | 1649 | |13;stanford-crypto-sha256-iterative;70;70;69;70;69;68;67;70;69;69 00:02:10 INFO - PROCESS | 1649 | __end_tp_report 00:02:10 INFO - PROCESS | 1649 | __start_cc_report 00:02:10 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,1936 00:02:10 INFO - PROCESS | 1649 | __end_cc_report 00:02:10 INFO - PROCESS | 1649 | __startTimestamp1480147330037__endTimestamp 00:02:10 INFO - PROCESS | 1649 | 00:02:10 INFO - PROCESS | 1649 | ------- Summary: start ------- 00:02:10 INFO - PROCESS | 1649 | Number of tests: 14 00:02:10 INFO - PROCESS | 1649 | 00:02:10 INFO - PROCESS | 1649 | [#0] ai-astar Cycles:10 Average:113.10 Median:114.50 stddev:4.01 (3.5%) stddev-sans-first:1.00 00:02:10 INFO - PROCESS | 1649 | Values: 102.0 114.0 116.0 114.0 115.0 115.0 115.0 113.0 114.0 113.0 00:02:10 INFO - PROCESS | 1649 | 00:02:10 INFO - PROCESS | 1649 | [#1] audio-beat-detection Cycles:10 Average:132.20 Median:140.50 stddev:13.41 (9.5%) stddev-sans-first:13.11 00:02:10 INFO - PROCESS | 1649 | Values: 147.0 114.0 133.0 139.0 144.0 142.0 143.0 121.0 130.0 109.0 00:02:10 INFO - PROCESS | 1649 | 00:02:10 INFO - PROCESS | 1649 | [#2] audio-dft Cycles:10 Average:183.30 Median:183.50 stddev:1.89 (1.0%) stddev-sans-first:1.73 00:02:10 INFO - PROCESS | 1649 | Values: 186.0 181.0 183.0 185.0 186.0 181.0 182.0 184.0 183.0 182.0 00:02:10 INFO - PROCESS | 1649 | 00:02:10 INFO - PROCESS | 1649 | [#3] audio-fft Cycles:10 Average:65.40 Median:62.50 stddev:9.79 (15.7%) stddev-sans-first:9.26 00:02:10 INFO - PROCESS | 1649 | Values: 78.0 59.0 59.0 85.0 65.0 60.0 58.0 59.0 73.0 58.0 00:02:10 INFO - PROCESS | 1649 | 00:02:10 INFO - PROCESS | 1649 | [#4] audio-oscillator Cycles:10 Average:75.40 Median:78.00 stddev:7.37 (9.4%) stddev-sans-first:7.28 00:02:10 INFO - PROCESS | 1649 | Values: 83.0 73.0 86.0 71.0 69.0 69.0 83.0 68.0 69.0 83.0 00:02:10 INFO - PROCESS | 1649 | 00:02:10 INFO - PROCESS | 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:84.90 Median:86.00 stddev:1.66 (1.9%) stddev-sans-first:1.72 00:02:10 INFO - PROCESS | 1649 | Values: 86.0 84.0 87.0 84.0 86.0 83.0 86.0 83.0 87.0 83.0 00:02:10 INFO - PROCESS | 1649 | 00:02:10 INFO - PROCESS | 1649 | [#6] imaging-darkroom Cycles:10 Average:104.80 Median:94.00 stddev:20.35 (21.6%) stddev-sans-first:21.17 00:02:10 INFO - PROCESS | 1649 | Values: 116.0 159.0 106.0 100.0 95.0 94.0 94.0 95.0 94.0 95.0 00:02:10 INFO - PROCESS | 1649 | 00:02:10 INFO - PROCESS | 1649 | [#7] imaging-desaturate Cycles:10 Average:98.00 Median:84.00 stddev:21.46 (25.5%) stddev-sans-first:20.23 00:02:10 INFO - PROCESS | 1649 | Values: 126.0 130.0 131.0 86.0 84.0 84.0 87.0 85.0 84.0 83.0 00:02:10 INFO - PROCESS | 1649 | 00:02:10 INFO - PROCESS | 1649 | [#8] json-parse-financial Cycles:10 Average:56.80 Median:57.50 stddev:1.75 (3.0%) stddev-sans-first:1.67 00:02:10 INFO - PROCESS | 1649 | Values: 59.0 58.0 57.0 58.0 55.0 57.0 58.0 56.0 57.0 53.0 00:02:10 INFO - PROCESS | 1649 | 00:02:10 INFO - PROCESS | 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:72.50 Median:72.50 stddev:3.75 (5.2%) stddev-sans-first:3.41 00:02:10 INFO - PROCESS | 1649 | Values: 67.0 80.0 77.0 72.0 74.0 73.0 70.0 71.0 70.0 71.0 00:02:10 INFO - PROCESS | 1649 | 00:02:10 INFO - PROCESS | 1649 | [#10] stanford-crypto-aes Cycles:10 Average:88.20 Median:89.00 stddev:1.14 (1.3%) stddev-sans-first:1.12 00:02:10 INFO - PROCESS | 1649 | Values: 87.0 88.0 87.0 87.0 89.0 89.0 89.0 87.0 89.0 90.0 00:02:10 INFO - PROCESS | 1649 | 00:02:10 INFO - PROCESS | 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:159.40 Median:156.00 stddev:14.52 (9.3%) stddev-sans-first:15.16 00:02:10 INFO - PROCESS | 1649 | Values: 152.0 151.0 148.0 153.0 152.0 159.0 172.0 149.0 195.0 163.0 00:02:10 INFO - PROCESS | 1649 | 00:02:10 INFO - PROCESS | 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.20 Median:174.50 stddev:2.25 (1.3%) stddev-sans-first:2.15 00:02:10 INFO - PROCESS | 1649 | Values: 177.0 172.0 171.0 175.0 174.0 173.0 177.0 172.0 177.0 174.0 00:02:10 INFO - PROCESS | 1649 | 00:02:10 INFO - PROCESS | 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:69.50 stddev:0.99 (1.4%) stddev-sans-first:1.00 00:02:10 INFO - PROCESS | 1649 | Values: 70.0 70.0 69.0 70.0 69.0 68.0 67.0 70.0 69.0 69.0 00:02:10 INFO - PROCESS | 1649 | -------- Summary: end -------- 00:02:10 INFO - PROCESS | 1649 | 00:02:11 INFO - TEST-INFO | 1649: exit 0 00:02:11 INFO - TEST-OK | kraken | took 60420ms 00:02:11 INFO - SUITE-END | took 1181s 00:02:11 INFO - Completed test suite (00:19:41) 00:02:11 INFO - javascript benchmark 00:02:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3272.492763621638, 3302.9504557431655, 3321.90034298971, 3335.566376371072, 3357.6978071973517, 5517.172424136382, 5584.246126772884, 5622.4939755422, 5697.230873832032, 5779.219805326285, 5587.692676902769, 5691.800786972457, 5769.04810706233, 5774.422557744224, 5798.304203495565, 5034.144195486758, 5082.618803589746, 5103.336566246383, 5168.302279192318, 5212.0, 5250.976059668366, 5289.920651190216, 5301.019311427398, 5303.814366497173, 5363.115086010804, 5241.475852414766, 5268.868278293035, 5281.8943621127555, 5312.2828418163635, 5367.812126575571, 5101.489851014906, 5144.4083930348115, 5158.819441319573, 5187.584993200535, 5188.066148093341, 5057.797688092472, 5146.202338637517, 5156.407013193493, 5156.793728250866, 5174.896502069938, 5660.801871934504, 5662.8301150965535, 5664.0, 5671.404502527216, 5686.800961966353, 6131.449496612492, 6133.1510485532735, 6152.5693201475915, 6188.195534580521, 6189.6905154742535, 6289.276733175698, 6292.0, 6309.274433440121, 6310.87378252437, 6315.084312774622, 5724.542036637059, 5780.9710951445395, 5802.187693722883, 5804.622699524602, 5840.707964601753, 6207.317195108534, 6243.406876346739, 6259.217597800275, 6281.654509001957, 6282.528810339151, 6103.125543060039, 6124.11200375943, 6149.169862068653, 6173.166622505994, 6190.350013248634, 6261.467775239155, 6264.21697287839, 6284.277308109626, 6285.151504546828, 6294.685265736694, 6014.308354539284, 6051.757929682852, 6195.4424101831055, 6209.161763162006, 6245.125682404467, 6139.259792094406, 6140.0, 6166.568340216142, 6168.660723660242, 6192.969035154795, 4971.055499455092, 4971.850844474599, 4978.726170060609, 4980.128477516492, 4993.8501844943985, 4436.223660859337, 4437.378766972626, 4442.378067070613, 4446.199684056836, 4492.415985921809, 5411.972940135274, 5426.348838139448, 5440.455954404528, 5751.913721294184, 5761.567882408753, 5269.420363759983, 6255.46828519581, 6258.874822503524, 6290.0, 6297.622142671455, 5793.217915581343, 5810.535157187415, 5826.563007774349, 5836.4747172753805, 5895.29256489224, 5283.550898173621, 5292.735363231823, 5398.514133727984, 5413.918791218135, 5421.539169170585, 6217.0, 6254.687265636791, 6257.7184026718, 6272.247330320391, 6354.4281014708895, 6282.905756413566, 6292.370762923854, 6316.147320111726, 6317.463015643538, 6327.462165715966, 6264.708674557943, 6268.905966410416, 6289.811305660654, 6322.3045464997895, 6332.145160403471, 6029.125776762257, 6101.974255744823, 6127.509799215963, 6143.986242269975, 6147.385261473817, 6265.310815810349, 6266.592671476339, 6272.5922815016875, 6281.528885333527, 6294.276158241861, 5936.3470018297085, 6123.08153776937, 6128.387161283836, 6128.448439640276, 6131.172291740736, 5925.585209035498, 5969.671668058299, 5985.012472941916, 5996.820095397145, 6091.817245482643, 4587.908241835078, 5840.2991641003355, 5882.647041177543, 5902.557308201988, 5940.851478713067, 5219.8434046978655, 5912.935671578985, 5917.378675239038, 6059.818205453844, 6081.239845019372, 5191.32512773337, 5633.605647604629, 5786.971065144648, 5802.854928626818, 5821.38875418075], "value": 5665.520497031541, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [463.4855310605229, 493.4917035453482, 516.599635282656, 534.126702840855, 559.4573263933983, 34101.82949085254, 39937.80031099844, 40263.99999999996, 41794.0, 41883.371749423706, 40873.7956310218, 40936.59063409365, 41413.58586414135, 41458.79270603643, 41478.792606037, 8589.914100859005, 9428.434293942368, 9606.519674016306, 9620.230381569458, 9627.374220675669, 8649.65401383944, 9370.718878433658, 11525.711857203552, 11525.769484610302, 11671.474783634714, 11410.030147437477, 11487.195896287263, 11492.367919764409, 11496.942515287412, 11504.42477876103, 8598.05421403645, 8806.955965220133, 11448.542058317698, 11519.36643484608, 11531.250468719507, 10989.230753847234, 11355.205135640508, 11368.431578421047, 11498.65504034872, 11503.769924601545, 38736.22527549462, 39085.0, 39091.0, 39124.0, 39127.60872391268, 3373.746968977312, 3386.6274709781906, 3510.3681337359267, 3711.851525938986, 3913.9217215655813, 30148.57758663705, 30350.54474182889, 30467.847660761556, 30470.847645761853, 30604.84697576498, 3131.9530207046914, 3141.387429451258, 3318.1041118898, 3354.480055591387, 3358.983205083983, 461320.0, 488497.0, 488725.0, 488830.0, 490867.0, 56483.71758141183, 56614.867702645715, 56718.14922776162, 57227.71386143126, 57241.713791430775, 60531.0, 60709.69645151746, 60873.78252434926, 61038.08442873361, 61080.38919610836, 14986.0, 15209.695806083817, 15216.543503694907, 15218.847811521964, 15249.313780879666, 10887.83668244977, 10933.070688991525, 11147.442627868573, 11170.497327620276, 11913.22564033335, 15531.378744850079, 15665.373385064699, 15978.280977356048, 16082.517524474284, 16198.433054843024, 6745.9662701686175, 6754.257031726506, 6758.121444212221, 6864.141982252218, 6873.965630171817, 830.1490971754, 893.5820486638634, 911.64901512918, 931.1759093202564, 1080.4165750494644, 25191.74808251894, 28052.43895122127, 29152.854235728686, 29619.703802961696, 29634.55548166779, 980.4656462228103, 1093.6172339681207, 1099.9010089092021, 1115.8884111588939, 1161.814109742437, 14926.552203433914, 14980.550583482513, 15126.697466050617, 15167.469138580318, 15245.0, 623845.0, 663912.0, 675015.0, 676259.0, 680101.1913987367, 55266.894662107334, 55528.611784704895, 55873.720631397395, 55928.72035639796, 56098.59753506195, 1251.6933351329164, 1290.2581015915698, 1373.1486478383467, 1393.4704812171512, 1407.240090351199, 21421.46446338854, 25806.0, 28453.715462845106, 29970.0, 30742.385152297276, 53660.73169634127, 54312.0, 54477.72761136169, 54527.72736136294, 54569.454305456435, 561038.0, 565439.0, 571165.0, 573455.0, 573551.0, 25377.238682839543, 25470.363240919127, 25725.970961161343, 25810.0, 26316.21051368462, 43616.0, 44454.88862778457, 44858.55141448544, 44954.576730920366, 45207.0, 37003.629963701096, 37191.25617487689, 37436.43845342268, 38120.23759524739, 38596.421053683654, 37051.444228336055, 37221.25557488781, 37502.729962078476, 38002.619973799905, 38369.2326153481, 35553.0, 36458.63541364553, 36570.634293657786, 37439.43840842444, 37513.2497350046, 28336.291592709524, 30179.849100754356, 32195.0, 33103.66896331102, 33185.66814331826, 20214.696779548023, 24126.51746965086, 24675.876620616782, 24978.62532062057, 25052.248432547054], "value": 19686.194583744953, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.9254110366274, 362.1308858739026, 368.1807977250616, 376.7814667492854, 398.67507980995487, 14337.139771613698, 14671.339789709462, 15514.052330057295, 19566.51083722904, 19992.30026949057, 18915.0, 19551.902240488776, 20073.799262007375, 20316.796832031676, 20386.286479973205, 5050.419201791786, 5513.9448605513935, 5808.767649294024, 5814.447627475383, 5819.098039803837, 4756.929690819573, 5180.430152683201, 5650.74571644277, 5695.430456954292, 5731.656100633975, 5668.0, 5675.148727690838, 5676.120201368773, 5682.034054210773, 5687.289088863892, 4561.0, 5583.609147359666, 5634.661920284755, 5637.661740295554, 5664.376918538957, 4738.6209103272, 4965.801367945278, 4976.950230497721, 4981.850544483671, 4983.252512123175, 18697.62604747911, 24075.0, 24442.511149776907, 24821.379465513324, 25130.874345628155, 1842.4012196036208, 2184.049938276839, 2191.5726433345503, 2542.224621490445, 2779.9444011119663, 9639.566219520138, 10179.491025448699, 10417.27079104463, 14559.78160327596, 15822.683546329125, 1766.611345504, 1811.411291330323, 1822.1526989949605, 1915.4445210888825, 1915.4636701723539, 109899.45050274697, 114574.42712786466, 125891.0, 130393.0, 131078.0, 10984.725381865517, 11333.60332388379, 11435.542578296941, 11441.828372574419, 11621.76756464866, 14367.568972930829, 14382.28088595566, 14389.28053597316, 14405.207713575854, 14424.134551926918, 8029.197080291924, 8122.959385203036, 8151.918480815235, 8169.795755106051, 8223.712170074139, 7897.60511974399, 7898.842023159505, 7989.800254993672, 8051.4766540174705, 8069.233422824939, 4335.111302183045, 4415.757133357632, 4463.218936686132, 4475.261581839025, 4551.6813823032735, 1885.114885114885, 1889.7590929156584, 1909.3794516782102, 1912.2064343297654, 1947.6786330255632, 716.781381678593, 745.7166276814776, 774.4385320642489, 858.347655781598, 868.4572142410993, 17259.79181457311, 17668.558286042953, 20271.986400679907, 20679.0, 21012.894935525226, 699.8460338725574, 824.6660102658433, 888.3426264564305, 902.4838271703601, 926.2821313482106, 9093.454392736458, 10562.630307939186, 10968.0, 10976.176786741027, 10983.450827458595, 515891.0, 516285.0, 525955.0, 526594.175728034, 526730.0, 121266.0, 125708.0294160295, 125814.0, 127924.0, 132134.0, 932.6036434515304, 1000.3847633705219, 1021.8467229915572, 1022.2639699416409, 1022.3661329975314, 17730.113494324974, 17930.731039034687, 18079.36722214716, 18663.44009679712, 18883.150258238686, 51943.0, 54095.729521353715, 54884.17673734817, 55400.0, 55601.88796224134, 78181.60909195417, 78391.21608783839, 79144.0, 79513.2048679529, 80124.17702725015, 6994.965025174842, 7245.963770181115, 7328.816779580553, 7874.488158269924, 7971.601419929097, 12456.813147802972, 12499.625011249676, 13021.088523803623, 13224.404901779635, 13291.80062299047, 10076.992300769864, 10372.688819335432, 10846.783064338826, 10860.076893464144, 11226.494807733508, 10009.547660823146, 10402.791944161227, 10884.51019704131, 11110.000099991039, 11181.32912025281, 10159.88241293472, 10462.424566648602, 10990.285631433931, 11627.093226693327, 11639.709007274885, 7336.156342020522, 7374.225706300976, 7453.2546745325035, 7536.623168841646, 7586.468947173645, 10044.849327260243, 10957.890421095686, 11243.325400475998, 11354.659360219206, 11372.943135284271], "value": 9221.040167895395, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1408.0847449158045, 1452.114210331698, 1603.4468108502563, 1758.9296428142875, 1809.3938530592243, 7680.807979800507, 7897.170797066305, 7919.366450683946, 8308.459950103239, 8316.417850750435, 7276.199618042011, 7569.810754731121, 7571.6592753326095, 7888.211178882123, 8157.306628936532, 2112.609167304046, 2159.827213822898, 2272.250157448038, 2313.710786151731, 2315.988420057897, 2288.2220045184636, 2315.166540045582, 2329.860208387494, 2332.300309907021, 2335.318570629153, 1819.3814103204907, 2025.43287879394, 2108.8102070813625, 2109.2090466075224, 2120.0883620043373, 2081.271554955761, 2226.7105276313973, 2293.0942277800177, 2305.827062970284, 2311.3874823171873, 2287.5996700577334, 2312.606856834342, 2318.07277089164, 2318.953620927572, 2324.5002324500147, 8183.71357002502, 8190.754277371689, 8271.1315311892, 8285.0, 8292.087870334259, 4970.403551573798, 5136.229565565158, 5140.974295128538, 5166.354205724285, 5166.380034395859, 3344.9498257526157, 3433.673800988927, 3501.037214765934, 3515.859365625372, 3522.4540196269363, 907.3195103672246, 1043.4417586591137, 1061.9044286014296, 1077.3643550305358, 1083.1659622090947, 10643.148548116133, 10709.143268538499, 10779.083777878812, 10779.4610269487, 10780.245382823205, 4156.168766246702, 4330.480342358874, 4411.382406463098, 4442.089371678798, 4457.0, 8521.744347669579, 8535.402521823413, 8547.786305342415, 8552.059273479912, 8553.914460855436, 5040.571551418097, 5041.344625198701, 5041.369828771403, 5043.420006699276, 5045.646804723708, 4543.727376357429, 4681.906361872743, 4714.15145273854, 4727.905441891143, 4748.453927798348, 3557.377458944726, 3599.5140656011627, 3612.259486805199, 3624.836882340301, 3694.4692885373606, 1115.4980258883536, 1130.5364800431782, 1130.7003644034337, 1140.4126874659557, 1145.0553293532869, 636.5353292096795, 781.261707686231, 786.3826895886701, 893.8525143351404, 907.274180655473, 5031.647784655112, 5626.043572592669, 5668.234788303526, 5681.147827825859, 5791.594588378857, 762.92752188542, 895.7268033249921, 929.8140371925507, 946.8437707778277, 979.5934687104772, 3261.673832616719, 3373.4433818420175, 3648.087978005499, 3803.67668748154, 3922.2547715933883, 9477.28920330978, 10062.597496100221, 10097.0, 10098.343607665625, 10138.391696498235, 9337.253019758404, 9796.804063918682, 10032.598696052222, 10040.397576145455, 10048.69853904384, 7560.168381478033, 7770.0, 7773.883391749128, 7789.3768498519985, 7793.688252469838, 5459.918101228564, 5491.203775452457, 5502.58730595215, 5801.216835727123, 5917.733701983507, 8669.696560620348, 9348.205402540589, 9350.438973661601, 9355.578998944928, 9383.577739001625, 8978.877640294962, 9347.345685801929, 9432.481213533321, 9582.28132890022, 9604.183644390305, 7136.0, 7167.713291468492, 7228.024216730674, 7231.204567497466, 7278.344948954619, 7706.691732330644, 7805.570693611906, 7820.452568320163, 7834.529928204326, 7850.921490785019, 6691.933080669131, 6893.276205998298, 6901.482388820959, 6952.895706564506, 7031.261717519586, 6764.526483146133, 6777.8983315249325, 6784.830379240361, 6829.282925292773, 6861.318976850704, 6237.656928868774, 6453.774117905851, 6548.345165483413, 6586.50601204902, 6619.172603424572, 4776.617870570277, 4801.303810947463, 4811.326414302071, 4834.347363105936, 4932.8520144395725, 4781.713097214179, 4835.854924352275, 4867.14824905633, 4932.136876046606, 4975.527324904128], "value": 4309.504590532834, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [279.49132578706747, 302.1027548181899, 305.2369077306733, 311.7646177136263, 317.6442384529325, 5114.905482183277, 5158.6646867953605, 5160.922586161201, 5166.5866730661555, 5193.532582067608, 4807.3269742236025, 4967.205247160455, 4972.925406118911, 4981.227909673998, 4983.127952608297, 3295.2256219788333, 3535.1515636247373, 3538.7345949053797, 3769.6230376962358, 3896.201278737852, 3246.448103822344, 3577.409727394977, 3906.5507466641275, 3921.1373497830573, 3956.802159892008, 3453.4474484082552, 3483.076984599083, 3588.1567831559564, 3879.4568760373572, 3900.941485877714, 3521.982390088046, 3795.551869044451, 3811.3711237645757, 3814.1036856338737, 3891.708121890855, 3437.535932649085, 3437.553118094657, 3438.0, 3442.621311655716, 3449.9827500862334, 4946.455889852114, 4947.925781113287, 4948.703077815343, 4956.826511072131, 4969.676970996874, 4205.38092834261, 4430.047539778963, 4437.955620443787, 4455.888602784924, 4462.642988560908, 4379.890502737425, 4469.508354081048, 5245.396779370345, 5272.710000949946, 5312.476573627535, 3770.8491660333552, 3774.2828862516035, 3781.0736369589354, 3784.8675296364495, 3803.3344164771056, 4284.371938663319, 4491.461024677027, 5179.964007198575, 5405.540504063881, 5622.85942851432, 3377.5102610121394, 3795.2978698940783, 3846.1730727893637, 3848.5189351331082, 3852.0, 3717.219383929351, 3898.4737060497037, 3905.6094390560716, 3909.2963266611714, 3909.9218015639526, 3765.002274397287, 4429.379886815848, 4442.955570444319, 4470.614109626026, 4502.482214545304, 3973.0, 4493.707908985905, 5301.920471192935, 5360.812371566976, 5423.755930983115, 1044.7858189071221, 1180.5631916191064, 1262.4508338872436, 1262.4950019991893, 1265.8227848101274, 811.8619834628128, 842.751388340441, 852.861429990969, 881.2025117268882, 888.8400087984096, 4045.604266528043, 4282.6359759420175, 4381.759003254788, 4445.621327790902, 4759.9524004759505, 4417.889552761142, 4651.906961860744, 5204.817831375959, 5246.842594722164, 5261.605379596545, 3934.1738234970976, 4212.378234379786, 4733.763311834464, 4759.547842954914, 4784.665073444826, 2466.469709012571, 2468.3829042739317, 2777.6389069421252, 2783.4433113377404, 2813.7467627913584, 4638.652101092432, 5019.949800501949, 5091.137963245092, 5226.712530810766, 5413.566914646819, 4840.685355451885, 4878.2681248844665, 5171.4311425743135, 5320.467953204648, 5422.511973922287, 4861.708297502232, 5040.067587496325, 5799.739011744482, 5857.677827719516, 5903.291605007426, 4667.463241727245, 5486.930048640476, 5504.4770746778995, 5660.688662123623, 5662.961333919868, 4843.515648435128, 5247.291615631841, 5481.616286859881, 5555.333359996826, 5580.86047848807, 5039.370078740158, 5067.442581315979, 5844.327902291121, 5846.473817356458, 5857.707114644336, 3739.7195210358786, 3797.582265950688, 3809.3333666608787, 3812.8856134316015, 3828.1960788234783, 4992.0, 5274.393444753749, 5618.494335509824, 5641.19219526826, 5719.628224165416, 5430.348358197041, 5463.0, 5467.890642187054, 5505.724713764376, 5509.044289277249, 5474.671519708831, 5493.890122197614, 5505.697186654773, 5507.642003269774, 5556.555475561864, 4558.3618293438285, 5103.7192954387865, 5126.410462796826, 5401.783928642811, 5416.891662166813, 4825.782839772289, 5155.587552995827, 5387.973060134675, 5402.5677945763455, 5496.587755918402, 4674.322223277538, 4782.7847746850775, 5129.74351282427, 5154.664946778597, 5343.839684809462], "value": 3880.417698703967, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [693.2097408953792, 713.4114355656582, 746.1160569561856, 769.1731388757088, 792.4532072869721, 13837.377318020688, 14690.338934747935, 14721.411143554245, 14728.779068313985, 14731.926340368296, 13197.406116724747, 13292.335383230848, 13343.466261349558, 13361.933190334059, 13372.465101395932, 682.0962225051801, 691.8201267670416, 827.8758186272078, 840.0717207485717, 851.5699571716306, 833.3832963606948, 834.4576025583389, 838.7861095420659, 841.898972123343, 844.2233145506149, 902.6163880350857, 903.8644203369514, 905.0858632780872, 907.2016625369665, 907.8320510705507, 699.7061234281614, 888.0897080492515, 889.5285498685707, 889.7330800759746, 891.4562117108559, 538.7090970875722, 650.1483057195089, 656.5699466849221, 660.4551245222663, 664.2228592546732, 13636.454541818315, 13665.0, 13668.248246346448, 13675.452981880811, 13707.246101464416, 12590.74818503634, 12665.936670316682, 12869.0, 12927.67680807978, 12997.935010324887, 11651.170884502544, 11756.294622322688, 11806.0, 11819.940900295529, 12201.877981220163, 4292.849750258726, 4309.4397728295435, 4386.8903277418, 4488.865334039984, 4493.842715504941, 84232.0, 84508.0, 84617.57691211505, 85068.0, 85664.0, 10204.540795664212, 10267.0, 10342.276040677081, 10345.534450949724, 10678.0, 18854.905725471286, 19158.904205478884, 19232.0, 19244.134013969404, 19338.226470941005, 7122.964385178041, 7425.517341372793, 7438.144613369532, 7473.103227612722, 7683.500572462772, 4143.0, 4257.787110644455, 4293.677974151951, 4305.590968857953, 4341.435613370241, 2954.5568164775027, 3298.406286868387, 3337.349216902681, 3338.449155889251, 3344.7658663893294, 621.2606997672755, 625.3381044314714, 626.154691166921, 627.438442593876, 644.5745807766851, 523.2804893271751, 566.4731799426573, 608.6256951974503, 609.8658295175054, 634.0267689097213, 7123.0, 8607.698730544402, 8805.955970220108, 8820.382573219813, 8877.911220887838, 559.30645998961, 609.9115628233881, 632.1371328137152, 654.1855390039336, 665.8535122273092, 3757.9248415032093, 3949.5852935441367, 4065.836387787493, 4304.677149213821, 4401.603855653007, 65502.34497655058, 66859.0, 69855.0, 80264.59867700624, 97640.0, 95078.5246073779, 97940.51029744805, 98283.0, 98297.50851245841, 100012.0, 53464.46535534673, 54982.45017549773, 63221.0, 88425.67361489583, 88917.11082889089, 8583.0, 8631.741047768577, 8803.955980220058, 9036.954815225881, 9107.772305692411, 49171.50828491813, 49321.76695582639, 49443.75278123586, 49890.25164622461, 50732.731681708254, 70499.89251235969, 71137.6850356685, 71577.0, 72750.63624682055, 72903.0, 11658.0, 12013.579524716593, 12067.215630983957, 12134.211276267015, 12288.692782680504, 13315.267660278778, 13549.932250338685, 13909.0, 13936.79094813599, 14763.409463621334, 12186.14696971239, 12327.568535101229, 12486.875131248935, 12566.246025238515, 12745.745085098431, 12690.780679528463, 12716.936415317865, 12751.0, 12789.0, 13089.672758181121, 11960.521579136737, 12054.75890482203, 12172.506685055723, 12241.326727030098, 12293.323867187391, 9940.254480913816, 9975.351602145838, 10167.491625418552, 10424.478776061318, 10690.358578485315, 10552.894471055191, 10559.366438013743, 10798.136149107895, 10802.0, 10844.0], "value": 7049.74585112946, "unit": "score"}], "name": "dromaeo_css", "value": 7035.3398966896675, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 114.0, 116.0, 114.0, 115.0, 115.0, 115.0, 113.0, 114.0, 113.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 114.0, 133.0, 139.0, 144.0, 142.0, 143.0, 121.0, 130.0, 109.0], "value": 132.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 181.0, 183.0, 185.0, 186.0, 181.0, 182.0, 184.0, 183.0, 182.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 59.0, 59.0, 85.0, 65.0, 60.0, 58.0, 59.0, 73.0, 58.0], "value": 65.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 73.0, 86.0, 71.0, 69.0, 69.0, 83.0, 68.0, 69.0, 83.0], "value": 75.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 87.0, 84.0, 86.0, 83.0, 86.0, 83.0, 87.0, 83.0], "value": 84.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 159.0, 106.0, 100.0, 95.0, 94.0, 94.0, 95.0, 94.0, 95.0], "value": 104.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 130.0, 131.0, 86.0, 84.0, 84.0, 87.0, 85.0, 84.0, 83.0], "value": 98.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 58.0, 57.0, 58.0, 55.0, 57.0, 58.0, 56.0, 57.0, 53.0], "value": 56.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 80.0, 77.0, 72.0, 74.0, 73.0, 70.0, 71.0, 70.0, 71.0], "value": 72.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 87.0, 87.0, 89.0, 89.0, 89.0, 87.0, 89.0, 90.0], "value": 88.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 151.0, 148.0, 153.0, 152.0, 159.0, 172.0, 149.0, 195.0, 163.0], "value": 159.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 172.0, 171.0, 175.0, 174.0, 173.0, 177.0, 172.0, 177.0, 174.0], "value": 174.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 69.0, 70.0, 69.0, 68.0, 67.0, 70.0, 69.0, 69.0], "value": 69.1, "unit": "score"}], "name": "kraken", "value": 1477.3, "alertThreshold": 2.0}]} 00:02:11 INFO - Return code: 0 00:02:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 00:02:11 INFO - # TBPL SUCCESS # 00:02:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:02:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:02:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:02:11 INFO - 2016-11-26 00:02:11.330 screenresolution[1653:10646] starting screenresolution argv=screenresolution get 00:02:11 INFO - 2016-11-26 00:02:11.333 screenresolution[1653:10646] Display 0: 1600x1200x32@60 00:02:11 INFO - 2016-11-26 00:02:11.339 screenresolution[1654:10649] starting screenresolution argv=screenresolution list 00:02:11 INFO - Available Modes on Display 0 00:02:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:02:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:02:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:02:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:02:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:02:11 INFO - Intel Iris: 00:02:11 INFO - Chipset Model: Intel Iris 00:02:11 INFO - Type: GPU 00:02:11 INFO - Bus: Built-In 00:02:11 INFO - VRAM (Dynamic, Max): 1536 MB 00:02:11 INFO - Vendor: Intel (0x8086) 00:02:11 INFO - Device ID: 0x0a2e 00:02:11 INFO - Revision ID: 0x0009 00:02:11 INFO - Displays: 00:02:11 INFO - Display: 00:02:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:02:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:02:11 INFO - Display Serial Number: 1600x1200 60 00:02:11 INFO - Main Display: Yes 00:02:11 INFO - Mirror: Off 00:02:11 INFO - Online: Yes 00:02:11 INFO - Rotation: Supported 00:02:11 INFO - Return code: 0 00:02:11 INFO - Running post-action listener: _resource_record_post_action 00:02:11 INFO - [mozharness: 2016-11-26 08:02:11.582624Z] Finished run-tests step (success) 00:02:11 INFO - Running post-run listener: _resource_record_post_run 00:02:11 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 37579776; Write bytes: 699766784; Read time: 476; Write time: 2030 00:02:11 INFO - TinderboxPrint: CPU usage
53.7% 00:02:11 INFO - TinderboxPrint: I/O read bytes / time
37,579,776 / 476 00:02:11 INFO - TinderboxPrint: I/O write bytes / time
699,766,784 / 2,030 00:02:11 INFO - TinderboxPrint: CPU idle
2,249.5 (46.3%) 00:02:11 INFO - TinderboxPrint: CPU system
971.4 (20.0%) 00:02:11 INFO - TinderboxPrint: CPU user
1,636.7 (33.7%) 00:02:11 INFO - TinderboxPrint: Swap in / out
416,243,712 / 0 00:02:11 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 372355584; Write bytes: 368817152; Read time: 27787; Write time: 665 00:02:11 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 35793920; Write bytes: 311546880; Read time: 458; Write time: 1323 00:02:12 INFO - Running post-run listener: _upload_blobber_files 00:02:12 INFO - Blob upload gear active. 00:02:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:02:12 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 00:02:12 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'] 00:02:12 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 00:02:12 INFO - (blobuploader) - INFO - Open directory for files ... 00:02:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 00:02:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:02:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:02:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 00:02:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:02:13 INFO - (blobuploader) - INFO - Done attempting. 00:02:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 00:02:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:02:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:02:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 00:02:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:02:14 INFO - (blobuploader) - INFO - Done attempting. 00:02:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 00:02:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:02:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:02:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:02:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:02:16 INFO - (blobuploader) - INFO - Done attempting. 00:02:16 INFO - (blobuploader) - INFO - Iteration through files over. 00:02:16 INFO - Return code: 0 00:02:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:02:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:02:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0a0ce15506575d19d2cd125375dfaea3330ab4a550a62af8edc39bd7a948d178b53f26cd9996dbb48961ce78f0b615fcb01336a42379d3cafbc1f0f18800e333", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c01ecfe1b5bf1b4c1a973deb17ae5ec07eb2e9775b2331389e6b1013ec5f122059f7fe4414f2895eddae864cd1b3433bc17416c3f7f534e5b26a8f57ba5cb9e9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af1c9a6fae2101c33dab6d38a2d01dc5f63654d2612042c6ce98688479acb6b56167c07433ff0f636f6461e5bf61a079516dc295877d50895e25d76cb74f7b4b"} 00:02:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:02:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:02:16 INFO - Contents: 00:02:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0a0ce15506575d19d2cd125375dfaea3330ab4a550a62af8edc39bd7a948d178b53f26cd9996dbb48961ce78f0b615fcb01336a42379d3cafbc1f0f18800e333", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c01ecfe1b5bf1b4c1a973deb17ae5ec07eb2e9775b2331389e6b1013ec5f122059f7fe4414f2895eddae864cd1b3433bc17416c3f7f534e5b26a8f57ba5cb9e9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af1c9a6fae2101c33dab6d38a2d01dc5f63654d2612042c6ce98688479acb6b56167c07433ff0f636f6461e5bf61a079516dc295877d50895e25d76cb74f7b4b"} 00:02:16 INFO - Running post-run listener: copy_logs_to_upload_dir 00:02:16 INFO - Copying logs to upload dir... 00:02:16 INFO - mkdir: /builds/slave/test/build/upload/logs 00:02:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.701896 ========= master_lag: 0.63 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-11-26 00:02:17.567626) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-26 00:02:17.571051) ========= 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.WUct8QxthJ/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.bJQbzKhatL/Listeners TMPDIR=/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0a0ce15506575d19d2cd125375dfaea3330ab4a550a62af8edc39bd7a948d178b53f26cd9996dbb48961ce78f0b615fcb01336a42379d3cafbc1f0f18800e333", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c01ecfe1b5bf1b4c1a973deb17ae5ec07eb2e9775b2331389e6b1013ec5f122059f7fe4414f2895eddae864cd1b3433bc17416c3f7f534e5b26a8f57ba5cb9e9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af1c9a6fae2101c33dab6d38a2d01dc5f63654d2612042c6ce98688479acb6b56167c07433ff0f636f6461e5bf61a079516dc295877d50895e25d76cb74f7b4b"} build_url:https://queue.taskcluster.net/v1/task/UJO46V69QtS6io5-Q0MnCw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011009 build_url: 'https://queue.taskcluster.net/v1/task/UJO46V69QtS6io5-Q0MnCw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0a0ce15506575d19d2cd125375dfaea3330ab4a550a62af8edc39bd7a948d178b53f26cd9996dbb48961ce78f0b615fcb01336a42379d3cafbc1f0f18800e333", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c01ecfe1b5bf1b4c1a973deb17ae5ec07eb2e9775b2331389e6b1013ec5f122059f7fe4414f2895eddae864cd1b3433bc17416c3f7f534e5b26a8f57ba5cb9e9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af1c9a6fae2101c33dab6d38a2d01dc5f63654d2612042c6ce98688479acb6b56167c07433ff0f636f6461e5bf61a079516dc295877d50895e25d76cb74f7b4b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-26 00:02:17.604886) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-26 00:02:17.605168) ========= 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.WUct8QxthJ/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.bJQbzKhatL/Listeners TMPDIR=/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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.005262 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-26 00:02:17.653248) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-26 00:02:17.653539) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-26 00:02:17.653855) ========= ========= Total master_lag: 0.76 =========