builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0388 starttime: 1476096946.71 results: success (0) buildid: 20161010025716 builduid: c5d4090080264abea832958343d6bae5 revision: a2c1d4e7e5e01c580877d5e4d7b2680047529bde ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-10 03:55:46.709899) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-10 03:55:46.710341) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-10 03:55:46.710677) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-10 03:55:46.735324) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 03:55:46.735747) ========= 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.SZIZxdpgRo/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.mnljo94mzn/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-10 03:55:46-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.5M=0.001s 2016-10-10 03:55:46 (18.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.124112 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 03:55:46.882823) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 03:55:46.883165) ========= 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.SZIZxdpgRo/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.mnljo94mzn/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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.026391 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 03:55:46.931500) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-10 03:55:46.931873) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev a2c1d4e7e5e01c580877d5e4d7b2680047529bde --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev a2c1d4e7e5e01c580877d5e4d7b2680047529bde --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SZIZxdpgRo/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.mnljo94mzn/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-10 03:55:47,016 truncating revision to first 12 chars 2016-10-10 03:55:47,016 Setting DEBUG logging. 2016-10-10 03:55:47,016 attempt 1/10 2016-10-10 03:55:47,016 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/a2c1d4e7e5e0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-10 03:55:48,067 unpacking tar archive at: autoland-a2c1d4e7e5e0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.330576 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-10 03:55:48.285756) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-10 03:55:48.286121) ========= script_repo_revision: a2c1d4e7e5e01c580877d5e4d7b2680047529bde ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-10 03:55:48.286699) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-10 03:55:48.286976) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-10 03:55:48.305928) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 28 secs) (at 2016-10-10 03:55:48.306334) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SZIZxdpgRo/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.mnljo94mzn/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 03:55:48 INFO - MultiFileLogger online at 20161010 03:55:48 in /builds/slave/test 03:55:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 03:55:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:55:48 INFO - {'append_to_log': False, 03:55:48 INFO - 'base_work_dir': '/builds/slave/test', 03:55:48 INFO - 'blob_upload_branch': 'Autoland', 03:55:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:55:48 INFO - 'branch': 'Autoland', 03:55:48 INFO - 'buildbot_json_path': 'buildprops.json', 03:55:48 INFO - 'config_files': ('talos/mac_config.py',), 03:55:48 INFO - 'default_actions': ('clobber', 03:55:48 INFO - 'read-buildbot-config', 03:55:48 INFO - 'download-and-extract', 03:55:48 INFO - 'populate-webroot', 03:55:48 INFO - 'create-virtualenv', 03:55:48 INFO - 'install', 03:55:48 INFO - 'run-tests'), 03:55:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:55:48 INFO - 'download_minidump_stackwalk': True, 03:55:48 INFO - 'download_symbols': 'ondemand', 03:55:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:55:48 INFO - 'tooltool.py': '/tools/tooltool.py', 03:55:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:55:48 INFO - '/tools/misc-python/virtualenv.py')}, 03:55:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:55:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:55:48 INFO - 'installer_path': 'installer.exe', 03:55:48 INFO - 'log_level': 'info', 03:55:48 INFO - 'log_name': 'talos', 03:55:48 INFO - 'log_to_console': True, 03:55:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:55:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:55:48 INFO - 'opt_config_files': (), 03:55:48 INFO - 'pip_index': False, 03:55:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:55:48 INFO - 'cmd': ('bash', 03:55:48 INFO - '-c', 03:55:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:55:48 INFO - 'enabled': True, 03:55:48 INFO - 'halt_on_failure': False, 03:55:48 INFO - 'name': 'check_screen_resolution'},), 03:55:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:55:48 INFO - 'cmd': ('bash', 03:55:48 INFO - '-c', 03:55:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:55:48 INFO - 'enabled': True, 03:55:48 INFO - 'halt_on_failure': False, 03:55:48 INFO - 'name': 'check_screen_resolution'},), 03:55:48 INFO - 'run_cmd_checks_enabled': True, 03:55:48 INFO - 'sps_profile': False, 03:55:48 INFO - 'sps_profile_interval': 0, 03:55:48 INFO - 'suite': 'dromaeojs', 03:55:48 INFO - 'system_bits': '32', 03:55:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:55:48 INFO - 'title': 't-yosemite-r7-0388', 03:55:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:55:48 INFO - 'use_talos_json': True, 03:55:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:55:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:55:48 INFO - 'work_dir': 'build'} 03:55:48 INFO - [mozharness: 2016-10-10 10:55:48.511920Z] Running clobber step. 03:55:48 INFO - Running pre-action listener: _resource_record_pre_action 03:55:48 INFO - Running main action method: clobber 03:55:48 INFO - rmtree: /builds/slave/test/build 03:55:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:55:50 INFO - Running post-action listener: _resource_record_post_action 03:55:50 INFO - [mozharness: 2016-10-10 10:55:50.567019Z] Finished clobber step (success) 03:55:50 INFO - [mozharness: 2016-10-10 10:55:50.567138Z] Running read-buildbot-config step. 03:55:50 INFO - Running pre-action listener: _resource_record_pre_action 03:55:50 INFO - Running main action method: read_buildbot_config 03:55:50 INFO - Using buildbot properties: 03:55:50 INFO - { 03:55:50 INFO - "project": "", 03:55:50 INFO - "product": "firefox", 03:55:50 INFO - "script_repo_revision": "production", 03:55:50 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 03:55:50 INFO - "repository": "", 03:55:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 03:55:50 INFO - "buildid": "20161010025716", 03:55:50 INFO - "pgo_build": "False", 03:55:50 INFO - "basedir": "/builds/slave/test", 03:55:50 INFO - "buildnumber": 221, 03:55:50 INFO - "slavename": "t-yosemite-r7-0388", 03:55:50 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 03:55:50 INFO - "platform": "yosemite_r7", 03:55:50 INFO - "scheduler": "tests-autoland-macosx64-talos", 03:55:50 INFO - "branch": "autoland", 03:55:50 INFO - "repo_path": "integration/autoland", 03:55:50 INFO - "revision": "a2c1d4e7e5e01c580877d5e4d7b2680047529bde", 03:55:50 INFO - "stage_platform": "macosx64", 03:55:50 INFO - "builduid": "c5d4090080264abea832958343d6bae5", 03:55:50 INFO - "slavebuilddir": "test" 03:55:50 INFO - } 03:55:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/TjKfoploQnejlkXZvpUb0g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 03:55:50 INFO - Running post-action listener: _resource_record_post_action 03:55:50 INFO - [mozharness: 2016-10-10 10:55:50.570353Z] Finished read-buildbot-config step (success) 03:55:50 INFO - [mozharness: 2016-10-10 10:55:50.570482Z] Running download-and-extract step. 03:55:50 INFO - Running pre-action listener: _resource_record_pre_action 03:55:50 INFO - Running main action method: download_and_extract 03:55:50 INFO - mkdir: /builds/slave/test/build/tests 03:55: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')]} 03:55:50 INFO - trying https://queue.taskcluster.net/v1/task/TjKfoploQnejlkXZvpUb0g/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 03:55:50 INFO - Downloading https://queue.taskcluster.net/v1/task/TjKfoploQnejlkXZvpUb0g/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 03:55:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TjKfoploQnejlkXZvpUb0g/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 03:55:51 INFO - Downloaded 1199 bytes. 03:55:51 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 03:55:51 INFO - Using the following test package requirements: 03:55:51 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 03:55:51 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:55:51 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 03:55:51 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:55:51 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 03:55:51 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:55:51 INFO - u'jsshell-mac.zip'], 03:55:51 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:55:51 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 03:55:51 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 03:55:51 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:55:51 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 03:55:51 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:55:51 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 03:55:51 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:55:51 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 03:55:51 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:55:51 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 03:55:51 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:55:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TjKfoploQnejlkXZvpUb0g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 03:55:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TjKfoploQnejlkXZvpUb0g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:55:51 INFO - Fetch https://queue.taskcluster.net/v1/task/TjKfoploQnejlkXZvpUb0g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 03:55:52 INFO - Http code: 200 03:55:52 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 03:55:52 INFO - x-amz-cf-id: o32jozb3GepKDsDlQ1MjLjbk3lsGs-xgshwCm0wol8rOMlgRznmBZw== 03:55:52 INFO - x-amz-version-id: OmyobkPE1ZpeAu_GOnhymZpXwtgneIiU 03:55:52 INFO - Expected file size: 16496350 03:55:52 INFO - Obtained file size: 16496350 03:55:59 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 03:55:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TjKfoploQnejlkXZvpUb0g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 03:55:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TjKfoploQnejlkXZvpUb0g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:55:59 INFO - Fetch https://queue.taskcluster.net/v1/task/TjKfoploQnejlkXZvpUb0g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 03:56:00 INFO - Http code: 200 03:56:00 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 03:56:00 INFO - x-amz-cf-id: VwRTEiCY9hC093WrS-9fjMUOggS2jZl_hL861kaESxfVppyeqREpJg== 03:56:00 INFO - x-amz-version-id: OmyobkPE1ZpeAu_GOnhymZpXwtgneIiU 03:56:00 INFO - Expected file size: 16496350 03:56:00 INFO - Obtained file size: 16496350 03:56:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TjKfoploQnejlkXZvpUb0g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 03:56:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TjKfoploQnejlkXZvpUb0g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 03:56:06 INFO - Fetch https://queue.taskcluster.net/v1/task/TjKfoploQnejlkXZvpUb0g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 03:56:07 INFO - Http code: 200 03:56:07 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 03:56:07 INFO - x-amz-cf-id: -auK6Lvw-OheWJiSdeYk6uf4Igo259JcYHqwAFnkTUVfeRHaWwi4Aw== 03:56:07 INFO - x-amz-version-id: nTn7QyIw06YDAn9tqt3E.bQjmSUAKyGq 03:56:08 INFO - Expected file size: 14409006 03:56:08 INFO - Obtained file size: 14409006 03:56:08 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')]} 03:56:08 INFO - trying https://queue.taskcluster.net/v1/task/TjKfoploQnejlkXZvpUb0g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 03:56:08 INFO - Downloading https://queue.taskcluster.net/v1/task/TjKfoploQnejlkXZvpUb0g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 03:56:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TjKfoploQnejlkXZvpUb0g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 03:56:40 WARNING - Socket error when accessing https://queue.taskcluster.net/v1/task/TjKfoploQnejlkXZvpUb0g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg: The read operation timed out 03:56:40 INFO - retry: attempt #1 caught exception: The read operation timed out 03:56:40 INFO - retry: Failed, sleeping 30 seconds before retrying 03:57:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TjKfoploQnejlkXZvpUb0g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #2 03:57:13 INFO - Downloaded 111714607 bytes. 03:57:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TjKfoploQnejlkXZvpUb0g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 03:57:13 INFO - mkdir: /builds/slave/test/properties 03:57:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:57:13 INFO - Writing to file /builds/slave/test/properties/build_url 03:57:13 INFO - Contents: 03:57:13 INFO - build_url:https://queue.taskcluster.net/v1/task/TjKfoploQnejlkXZvpUb0g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 03:57:24 INFO - Running post-action listener: _resource_record_post_action 03:57:24 INFO - Running post-action listener: set_extra_try_arguments 03:57:24 INFO - [mozharness: 2016-10-10 10:57:24.940605Z] Finished download-and-extract step (success) 03:57:24 INFO - [mozharness: 2016-10-10 10:57:24.940753Z] Running populate-webroot step. 03:57:24 INFO - Running pre-action listener: _resource_record_pre_action 03:57:24 INFO - Running main action method: populate_webroot 03:57:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:57:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:57:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:57:24 INFO - '--tppagecycles', 03:57:24 INFO - '7'], 03:57:24 INFO - 'tests': ['tsvgm']}}, 03:57:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:57:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:57:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:57:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:57:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:57:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:57:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:57:24 INFO - 'talos_options': ['--disable-e10s'], 03:57:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:57:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:57:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:57:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:57:24 INFO - 'talos_options': ['--disable-e10s'], 03:57:24 INFO - 'tests': ['damp', 'tps']}, 03:57:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:57:24 INFO - 'tests': ['damp', 'tps']}, 03:57:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:57:24 INFO - 'tests': ['dromaeo_dom']}, 03:57:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:57:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:57:24 INFO - 'tests': ['basic_compositor_video']}, 03:57:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:57:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:57:24 INFO - 'tests': ['a11yr', 03:57:24 INFO - 'ts_paint', 03:57:24 INFO - 'tpaint', 03:57:24 INFO - 'sessionrestore', 03:57:24 INFO - 'sessionrestore_no_auto_restore', 03:57:24 INFO - 'tabpaint']}, 03:57:24 INFO - 'other-e10s': {'tests': ['a11yr', 03:57:24 INFO - 'ts_paint', 03:57:24 INFO - 'tpaint', 03:57:24 INFO - 'sessionrestore', 03:57:24 INFO - 'sessionrestore_no_auto_restore', 03:57:24 INFO - 'tabpaint']}, 03:57:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:57:24 INFO - 'ts_paint', 03:57:24 INFO - 'tpaint', 03:57:24 INFO - 'sessionrestore', 03:57:24 INFO - 'sessionrestore_no_auto_restore', 03:57:24 INFO - 'tabpaint']}, 03:57:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:57:24 INFO - 'ts_paint', 03:57:24 INFO - 'tpaint', 03:57:24 INFO - 'sessionrestore', 03:57:24 INFO - 'sessionrestore_no_auto_restore', 03:57:24 INFO - 'tabpaint']}, 03:57:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:57:24 INFO - 'tests': ['a11yr', 03:57:24 INFO - 'ts_paint', 03:57:24 INFO - 'tpaint', 03:57:24 INFO - 'sessionrestore', 03:57:24 INFO - 'sessionrestore_no_auto_restore', 03:57:24 INFO - 'tabpaint']}, 03:57:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:57:24 INFO - 'tests': ['a11yr', 03:57:24 INFO - 'ts_paint', 03:57:24 INFO - 'tpaint', 03:57:24 INFO - 'sessionrestore', 03:57:24 INFO - 'sessionrestore_no_auto_restore', 03:57:24 INFO - 'tabpaint']}, 03:57:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:57:24 INFO - 'tests': ['tsvgx', 03:57:24 INFO - 'tsvgr_opacity', 03:57:24 INFO - 'tart', 03:57:24 INFO - 'tscrollx', 03:57:24 INFO - 'cart']}, 03:57:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:57:24 INFO - 'tsvgr_opacity', 03:57:24 INFO - 'tart', 03:57:24 INFO - 'tscrollx', 03:57:24 INFO - 'cart']}, 03:57:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:57:24 INFO - 'talos_options': ['--disable-e10s'], 03:57:24 INFO - 'tests': ['tp5o']}, 03:57:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:57:24 INFO - 'tests': ['tp5o']}, 03:57:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:57:24 INFO - 'talos_options': ['--disable-e10s', 03:57:24 INFO - '--xperf_path', 03:57:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:57:24 INFO - 'tests': ['tp5n']}, 03:57:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:57:24 INFO - 'talos_options': ['--xperf_path', 03:57:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:57:24 INFO - 'tests': ['tp5n']}}, 03:57:24 INFO - 'talos.zip': {'path': '', 03:57:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:57:24 INFO - Running post-action listener: _resource_record_post_action 03:57:24 INFO - [mozharness: 2016-10-10 10:57:24.959489Z] Finished populate-webroot step (success) 03:57:24 INFO - [mozharness: 2016-10-10 10:57:24.959602Z] Running create-virtualenv step. 03:57:24 INFO - Running pre-action listener: _resource_record_pre_action 03:57:24 INFO - Running main action method: create_virtualenv 03:57:24 INFO - Creating virtualenv /builds/slave/test/build/venv 03:57: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 03:57:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:57:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:57:25 INFO - Using real prefix '/tools/python27' 03:57:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:57:25 INFO - Installing distribute.............................................................................................................................................................................................done. 03:57:28 INFO - Installing pip.................done. 03:57:28 INFO - Return code: 0 03:57:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:57:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:57:28 INFO - Reading from file tmpfile_stdout 03:57:28 INFO - Output received: 03:57: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) 03:57:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:57: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')]} 03:57:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57: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')]} 03:57:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57: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 0x10577e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056f4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf39d6dfe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105592d30>, '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 0x1053b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10558ab20>, '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.SZIZxdpgRo/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.mnljo94mzn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 03:57: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 03:57: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 03:57:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SZIZxdpgRo/Render', 03:57:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:28 INFO - 'HOME': '/Users/cltbld', 03:57:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:28 INFO - 'LOGNAME': 'cltbld', 03:57:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:28 INFO - 'MOZ_NO_REMOTE': '1', 03:57:28 INFO - 'NO_EM_RESTART': '1', 03:57:28 INFO - 'PAGER': '/bin/cat', 03:57:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:28 INFO - 'PWD': '/builds/slave/test', 03:57:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:28 INFO - 'SHELL': '/bin/bash', 03:57:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mnljo94mzn/Listeners', 03:57:28 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 03:57:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:28 INFO - 'USER': 'cltbld', 03:57:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:28 INFO - 'XPC_FLAGS': '0x0', 03:57:28 INFO - 'XPC_SERVICE_NAME': '0', 03:57:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:29 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 03:57:29 INFO - Cleaning up... 03:57:29 INFO - Return code: 0 03:57:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:57: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')]} 03:57:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57: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')]} 03:57:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:29 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 0x10577e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056f4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf39d6dfe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105592d30>, '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 0x1053b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10558ab20>, '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.SZIZxdpgRo/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.mnljo94mzn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 03:57:29 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 03:57:29 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 03:57:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SZIZxdpgRo/Render', 03:57:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:29 INFO - 'HOME': '/Users/cltbld', 03:57:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:29 INFO - 'LOGNAME': 'cltbld', 03:57:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:29 INFO - 'MOZ_NO_REMOTE': '1', 03:57:29 INFO - 'NO_EM_RESTART': '1', 03:57:29 INFO - 'PAGER': '/bin/cat', 03:57:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:29 INFO - 'PWD': '/builds/slave/test', 03:57:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:29 INFO - 'SHELL': '/bin/bash', 03:57:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mnljo94mzn/Listeners', 03:57:29 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 03:57:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:29 INFO - 'USER': 'cltbld', 03:57:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:29 INFO - 'XPC_FLAGS': '0x0', 03:57:29 INFO - 'XPC_SERVICE_NAME': '0', 03:57:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:29 INFO - Downloading/unpacking psutil>=3.1.1 03:57: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 03:57: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 03:57: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 03:57: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 03:57: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 03:57: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 03:57:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:57:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:57:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:57:33 INFO - Installing collected packages: psutil 03:57:33 INFO - Running setup.py install for psutil 03:57:33 INFO - building 'psutil._psutil_osx' extension 03:57: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 03:57: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 03:57: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 03:57:34 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 03:57:34 INFO - building 'psutil._psutil_posix' extension 03:57:34 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 03:57:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:57:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:57:34 INFO - ^ 03:57:34 INFO - 1 warning generated. 03:57: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 03:57:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:57:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:57:34 INFO - Successfully installed psutil 03:57:34 INFO - Cleaning up... 03:57:34 INFO - Return code: 0 03:57:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:57: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')]} 03:57:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57: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')]} 03:57:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57: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 0x10577e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056f4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf39d6dfe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105592d30>, '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 0x1053b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10558ab20>, '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.SZIZxdpgRo/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.mnljo94mzn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 03:57: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 03:57: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 03:57:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SZIZxdpgRo/Render', 03:57:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:34 INFO - 'HOME': '/Users/cltbld', 03:57:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:34 INFO - 'LOGNAME': 'cltbld', 03:57:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:34 INFO - 'MOZ_NO_REMOTE': '1', 03:57:34 INFO - 'NO_EM_RESTART': '1', 03:57:34 INFO - 'PAGER': '/bin/cat', 03:57:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:34 INFO - 'PWD': '/builds/slave/test', 03:57:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:34 INFO - 'SHELL': '/bin/bash', 03:57:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mnljo94mzn/Listeners', 03:57:34 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 03:57:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:34 INFO - 'USER': 'cltbld', 03:57:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:34 INFO - 'XPC_FLAGS': '0x0', 03:57:34 INFO - 'XPC_SERVICE_NAME': '0', 03:57:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:57: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 03:57: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 03:57: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 03:57: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 03:57: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 03:57: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 03:57:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:57:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:57:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:57:37 INFO - Installing collected packages: mozsystemmonitor 03:57:37 INFO - Running setup.py install for mozsystemmonitor 03:57:37 INFO - Successfully installed mozsystemmonitor 03:57:37 INFO - Cleaning up... 03:57:37 INFO - Return code: 0 03:57:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:57: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')]} 03:57:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57: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')]} 03:57:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57: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 0x10577e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056f4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf39d6dfe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105592d30>, '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 0x1053b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10558ab20>, '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.SZIZxdpgRo/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.mnljo94mzn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 03:57: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 03:57: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 03:57:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SZIZxdpgRo/Render', 03:57:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:37 INFO - 'HOME': '/Users/cltbld', 03:57:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:37 INFO - 'LOGNAME': 'cltbld', 03:57:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:37 INFO - 'MOZ_NO_REMOTE': '1', 03:57:37 INFO - 'NO_EM_RESTART': '1', 03:57:37 INFO - 'PAGER': '/bin/cat', 03:57:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:37 INFO - 'PWD': '/builds/slave/test', 03:57:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:37 INFO - 'SHELL': '/bin/bash', 03:57:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mnljo94mzn/Listeners', 03:57:37 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 03:57:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:37 INFO - 'USER': 'cltbld', 03:57:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:37 INFO - 'XPC_FLAGS': '0x0', 03:57:37 INFO - 'XPC_SERVICE_NAME': '0', 03:57:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:37 INFO - Downloading/unpacking jsonschema==2.5.1 03:57: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 03:57: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 03:57: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 03:57: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 03:57: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 03:57: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 03:57:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:57:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:57: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 03:57: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 03:57: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 03:57: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 03:57: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 03:57: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 03:57:40 INFO - Downloading functools32-3.2.3-2.tar.gz 03:57:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:57:40 INFO - warning: no files found matching '*.txt' 03:57:40 INFO - no previously-included directories found matching 'build' 03:57:40 INFO - no previously-included directories found matching 'dist' 03:57:40 INFO - no previously-included directories found matching '.git*' 03:57:40 INFO - Installing collected packages: jsonschema, functools32 03:57:40 INFO - Running setup.py install for functools32 03:57:40 INFO - warning: no files found matching '*.txt' 03:57:40 INFO - no previously-included directories found matching 'build' 03:57:40 INFO - no previously-included directories found matching 'dist' 03:57:40 INFO - no previously-included directories found matching '.git*' 03:57:40 INFO - Successfully installed jsonschema functools32 03:57:40 INFO - Cleaning up... 03:57:41 INFO - Return code: 0 03:57:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:57: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')]} 03:57:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57: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')]} 03:57:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57: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', '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 0x10577e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056f4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf39d6dfe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105592d30>, '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 0x1053b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10558ab20>, '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.SZIZxdpgRo/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.mnljo94mzn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 03:57: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:57: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 functools32==3.2.3-2 03:57:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SZIZxdpgRo/Render', 03:57:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:41 INFO - 'HOME': '/Users/cltbld', 03:57:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:41 INFO - 'LOGNAME': 'cltbld', 03:57:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:41 INFO - 'MOZ_NO_REMOTE': '1', 03:57:41 INFO - 'NO_EM_RESTART': '1', 03:57:41 INFO - 'PAGER': '/bin/cat', 03:57:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:41 INFO - 'PWD': '/builds/slave/test', 03:57:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:41 INFO - 'SHELL': '/bin/bash', 03:57:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mnljo94mzn/Listeners', 03:57:41 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 03:57:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:41 INFO - 'USER': 'cltbld', 03:57:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:41 INFO - 'XPC_FLAGS': '0x0', 03:57:41 INFO - 'XPC_SERVICE_NAME': '0', 03:57:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:57:41 INFO - Cleaning up... 03:57:41 INFO - Return code: 0 03:57:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:57: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')]} 03:57:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57: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')]} 03:57:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57: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 0x10577e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056f4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf39d6dfe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105592d30>, '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 0x1053b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10558ab20>, '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.SZIZxdpgRo/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.mnljo94mzn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 03:57: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 03:57: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 03:57:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SZIZxdpgRo/Render', 03:57:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:41 INFO - 'HOME': '/Users/cltbld', 03:57:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:41 INFO - 'LOGNAME': 'cltbld', 03:57:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:41 INFO - 'MOZ_NO_REMOTE': '1', 03:57:41 INFO - 'NO_EM_RESTART': '1', 03:57:41 INFO - 'PAGER': '/bin/cat', 03:57:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:41 INFO - 'PWD': '/builds/slave/test', 03:57:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:41 INFO - 'SHELL': '/bin/bash', 03:57:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mnljo94mzn/Listeners', 03:57:41 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 03:57:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:41 INFO - 'USER': 'cltbld', 03:57:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:41 INFO - 'XPC_FLAGS': '0x0', 03:57:41 INFO - 'XPC_SERVICE_NAME': '0', 03:57:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:41 INFO - Downloading/unpacking blobuploader==1.2.4 03:57: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 03:57: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 03:57: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 03:57: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 03:57: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 03:57: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 03:57:43 INFO - Downloading blobuploader-1.2.4.tar.gz 03:57:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:57:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:57: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 03:57: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 03:57: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 03:57: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 03:57: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 03:57: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 03:57:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:57:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:57: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 03:57: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 03:57: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 03:57: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 03:57: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 03:57: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 03:57:45 INFO - Downloading docopt-0.6.1.tar.gz 03:57:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:57:45 INFO - Installing collected packages: blobuploader, requests, docopt 03:57:45 INFO - Running setup.py install for blobuploader 03:57:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:57:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:57:45 INFO - Running setup.py install for requests 03:57:46 INFO - Running setup.py install for docopt 03:57:46 INFO - Successfully installed blobuploader requests docopt 03:57:46 INFO - Cleaning up... 03:57:46 INFO - Return code: 0 03:57:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:57: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')]} 03:57:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57: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')]} 03:57:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57: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 0x10577e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056f4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf39d6dfe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105592d30>, '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 0x1053b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10558ab20>, '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.SZIZxdpgRo/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.mnljo94mzn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 03:57: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 03:57: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 03:57:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SZIZxdpgRo/Render', 03:57:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:46 INFO - 'HOME': '/Users/cltbld', 03:57:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:46 INFO - 'LOGNAME': 'cltbld', 03:57:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:46 INFO - 'MOZ_NO_REMOTE': '1', 03:57:46 INFO - 'NO_EM_RESTART': '1', 03:57:46 INFO - 'PAGER': '/bin/cat', 03:57:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:46 INFO - 'PWD': '/builds/slave/test', 03:57:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:46 INFO - 'SHELL': '/bin/bash', 03:57:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mnljo94mzn/Listeners', 03:57:46 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 03:57:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:46 INFO - 'USER': 'cltbld', 03:57:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:46 INFO - 'XPC_FLAGS': '0x0', 03:57:46 INFO - 'XPC_SERVICE_NAME': '0', 03:57:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:57:46 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-suKH0t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:57:46 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-J2wy1G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:57:46 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-q3XaXm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:57:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:57:47 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-df8Pa3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:57:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:57:47 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-dImk9u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:57:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:57:47 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-WVFqhM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:57:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:57:47 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-BYQVyU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:57:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:57:47 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-1JmBs3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:57:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:57:47 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-DVffTo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:57:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:57:47 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-Q2zAWN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:57:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:57:47 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-51xE71-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:57:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:57:47 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-mI0PIP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:57:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:57:47 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-3uUTy0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:57:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:57:48 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-1xCDhI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:57:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:57:48 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-3X3lNu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:57:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:57:48 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-Ccj_yQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:57:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:57:48 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-302wvw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:57:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:57:48 INFO - Running setup.py install for manifestparser 03:57:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:57:48 INFO - Running setup.py install for mozcrash 03:57:48 INFO - Running setup.py install for mozdebug 03:57:48 INFO - Running setup.py install for mozdevice 03:57:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:57:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:57:49 INFO - Running setup.py install for mozfile 03:57:49 INFO - Running setup.py install for mozhttpd 03:57:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:57:49 INFO - Running setup.py install for mozinfo 03:57:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:57:49 INFO - Running setup.py install for mozInstall 03:57:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:57:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:57:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:57:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:57:49 INFO - Running setup.py install for mozleak 03:57:49 INFO - Running setup.py install for mozlog 03:57:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:57:49 INFO - Running setup.py install for moznetwork 03:57:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:57:50 INFO - Running setup.py install for mozprocess 03:57:50 INFO - Running setup.py install for mozprofile 03:57:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:57:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:57:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:57:50 INFO - Running setup.py install for mozrunner 03:57:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:57:50 INFO - Running setup.py install for mozscreenshot 03:57:50 INFO - Running setup.py install for moztest 03:57:50 INFO - Running setup.py install for mozversion 03:57:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:57:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:57:51 INFO - Cleaning up... 03:57:51 INFO - Return code: 0 03:57:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:57:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:51 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 0x10577e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056f4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf39d6dfe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105592d30>, '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 0x1053b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10558ab20>, '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.SZIZxdpgRo/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.mnljo94mzn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 03:57:51 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 03:57:51 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 03:57:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SZIZxdpgRo/Render', 03:57:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:51 INFO - 'HOME': '/Users/cltbld', 03:57:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:51 INFO - 'LOGNAME': 'cltbld', 03:57:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:51 INFO - 'MOZ_NO_REMOTE': '1', 03:57:51 INFO - 'NO_EM_RESTART': '1', 03:57:51 INFO - 'PAGER': '/bin/cat', 03:57:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:51 INFO - 'PWD': '/builds/slave/test', 03:57:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:51 INFO - 'SHELL': '/bin/bash', 03:57:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mnljo94mzn/Listeners', 03:57:51 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 03:57:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:51 INFO - 'USER': 'cltbld', 03:57:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:51 INFO - 'XPC_FLAGS': '0x0', 03:57:51 INFO - 'XPC_SERVICE_NAME': '0', 03:57:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:57:51 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-mjNrud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:57: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)) 03:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:57:51 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-CA4VKi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:57:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:57:51 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-qCx5Nm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:57: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)) 03:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:57:51 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-wrFgun-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:57:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:57:51 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-Zbmo2v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:57: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)) 03:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:57:51 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-Cm3rnV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:57: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)) 03:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:57:51 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-i8Y0Hi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:57:52 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)) 03:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:57:52 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-N7bvqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:57:52 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)) 03:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:57:52 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-opVxms-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:57: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)) 03:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:57:52 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-UglYLm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:57: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)) 03:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:57:52 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-bTFLIm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:57: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)) 03:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:57:52 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-FztMIv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:57: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)) 03:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:57:52 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-UavDx1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:57: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)) 03:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:57:52 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-v_JRKg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:57:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:57:52 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-yvTjon-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:57: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)) 03:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:57:52 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-QWsz5_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:57:53 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)) 03:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:57:53 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-KccLf3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:57:53 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)) 03:57:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:57:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:57:53 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)) 03:57:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:57:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:57:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:57:53 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 03:57:53 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 03:57:53 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 03:57:53 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 03:57:53 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 03:57:53 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 03:57:55 INFO - Downloading blessings-1.6.tar.gz 03:57:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:57:55 INFO - Installing collected packages: blessings 03:57:55 INFO - Running setup.py install for blessings 03:57:55 INFO - Successfully installed blessings 03:57:55 INFO - Cleaning up... 03:57:55 INFO - Return code: 0 03:57:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:57:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:57:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:57:56 INFO - Reading from file tmpfile_stdout 03:57:56 INFO - Current package versions: 03:57:56 INFO - blessings == 1.6 03:57:56 INFO - blobuploader == 1.2.4 03:57:56 INFO - docopt == 0.6.1 03:57:56 INFO - functools32 == 3.2.3-2 03:57:56 INFO - jsonschema == 2.5.1 03:57:56 INFO - manifestparser == 1.1 03:57:56 INFO - mozInstall == 1.12 03:57:56 INFO - mozcrash == 0.17 03:57:56 INFO - mozdebug == 0.1 03:57:56 INFO - mozdevice == 0.48 03:57:56 INFO - mozfile == 1.2 03:57:56 INFO - mozhttpd == 0.7 03:57:56 INFO - mozinfo == 0.9 03:57:56 INFO - mozleak == 0.1 03:57:56 INFO - mozlog == 3.4 03:57:56 INFO - moznetwork == 0.27 03:57:56 INFO - mozprocess == 0.23 03:57:56 INFO - mozprofile == 0.28 03:57:56 INFO - mozrunner == 6.12 03:57:56 INFO - mozscreenshot == 0.1 03:57:56 INFO - mozsystemmonitor == 0.3 03:57:56 INFO - moztest == 0.7 03:57:56 INFO - mozversion == 1.4 03:57:56 INFO - psutil == 3.1.1 03:57:56 INFO - requests == 1.2.3 03:57:56 INFO - wsgiref == 0.1.2 03:57:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:57: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')]} 03:57:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57: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')]} 03:57:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57: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 0x10577e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056f4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf39d6dfe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105592d30>, '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 0x1053b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10558ab20>, '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.SZIZxdpgRo/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.mnljo94mzn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 03:57: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 03:57: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 03:57:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SZIZxdpgRo/Render', 03:57:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:56 INFO - 'HOME': '/Users/cltbld', 03:57:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:56 INFO - 'LOGNAME': 'cltbld', 03:57:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:56 INFO - 'MOZ_NO_REMOTE': '1', 03:57:56 INFO - 'NO_EM_RESTART': '1', 03:57:56 INFO - 'PAGER': '/bin/cat', 03:57:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:56 INFO - 'PWD': '/builds/slave/test', 03:57:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:56 INFO - 'SHELL': '/bin/bash', 03:57:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mnljo94mzn/Listeners', 03:57:56 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 03:57:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:56 INFO - 'USER': 'cltbld', 03:57:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:56 INFO - 'XPC_FLAGS': '0x0', 03:57:56 INFO - 'XPC_SERVICE_NAME': '0', 03:57:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57: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)) 03:57: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)) 03:57: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)) 03:57: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)) 03:57: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)) 03:57: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)) 03:57: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)) 03:57: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)) 03:57: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)) 03:57: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)) 03:57: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)) 03:57:56 INFO - Cleaning up... 03:57:56 INFO - Return code: 0 03:57:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:57: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')]} 03:57:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57: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')]} 03:57:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57: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 0x10577e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056f4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf39d6dfe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105592d30>, '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 0x1053b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10558ab20>, '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.SZIZxdpgRo/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.mnljo94mzn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 03:57: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 03:57: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 03:57:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SZIZxdpgRo/Render', 03:57:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:56 INFO - 'HOME': '/Users/cltbld', 03:57:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:56 INFO - 'LOGNAME': 'cltbld', 03:57:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:56 INFO - 'MOZ_NO_REMOTE': '1', 03:57:56 INFO - 'NO_EM_RESTART': '1', 03:57:56 INFO - 'PAGER': '/bin/cat', 03:57:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:56 INFO - 'PWD': '/builds/slave/test', 03:57:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:56 INFO - 'SHELL': '/bin/bash', 03:57:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mnljo94mzn/Listeners', 03:57:56 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 03:57:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:56 INFO - 'USER': 'cltbld', 03:57:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:56 INFO - 'XPC_FLAGS': '0x0', 03:57:56 INFO - 'XPC_SERVICE_NAME': '0', 03:57:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:57:56 INFO - Cleaning up... 03:57:56 INFO - Return code: 0 03:57:56 INFO - Running post-action listener: _resource_record_post_action 03:57:56 INFO - Running post-action listener: _start_resource_monitoring 03:57:56 INFO - Starting resource monitoring. 03:57:56 INFO - [mozharness: 2016-10-10 10:57:56.774144Z] Finished create-virtualenv step (success) 03:57:56 INFO - [mozharness: 2016-10-10 10:57:56.775146Z] Running install step. 03:57:56 INFO - Running pre-action listener: _resource_record_pre_action 03:57:56 INFO - Running main action method: install 03:57:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:57:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:57:57 INFO - Reading from file tmpfile_stdout 03:57:57 INFO - Detecting whether we're running mozinstall >=1.0... 03:57:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:57:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:57:57 INFO - Reading from file tmpfile_stdout 03:57:57 INFO - Output received: 03:57:57 INFO - Usage: mozinstall [options] installer 03:57:57 INFO - Options: 03:57:57 INFO - -h, --help show this help message and exit 03:57:57 INFO - -d DEST, --destination=DEST 03:57:57 INFO - Directory to install application into. [default: 03:57:57 INFO - "/builds/slave/test"] 03:57:57 INFO - --app=APP Application being installed. [default: firefox] 03:57:57 INFO - mkdir: /builds/slave/test/build/application 03:57:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:57:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 03:58:31 INFO - Reading from file tmpfile_stdout 03:58:31 INFO - Output received: 03:58:31 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:58:31 INFO - Running post-action listener: _resource_record_post_action 03:58:31 INFO - [mozharness: 2016-10-10 10:58:31.205413Z] Finished install step (success) 03:58:31 INFO - [mozharness: 2016-10-10 10:58:31.205550Z] Running run-tests step. 03:58:31 INFO - Running pre-action listener: _resource_record_pre_action 03:58:31 INFO - Running main action method: run_tests 03:58:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:58:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:58:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:58:31 INFO - 2016-10-10 03:58:31.220 screenresolution[1648:7629] starting screenresolution argv=screenresolution get 03:58:31 INFO - 2016-10-10 03:58:31.222 screenresolution[1648:7629] Display 0: 1600x1200x32@60 03:58:31 INFO - 2016-10-10 03:58:31.228 screenresolution[1649:7631] starting screenresolution argv=screenresolution list 03:58:31 INFO - Available Modes on Display 0 03:58:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:58:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:58:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:58:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:58:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:58:31 INFO - Intel Iris: 03:58:31 INFO - Chipset Model: Intel Iris 03:58:31 INFO - Type: GPU 03:58:31 INFO - Bus: Built-In 03:58:31 INFO - VRAM (Dynamic, Max): 1536 MB 03:58:31 INFO - Vendor: Intel (0x8086) 03:58:31 INFO - Device ID: 0x0a2e 03:58:31 INFO - Revision ID: 0x0009 03:58:31 INFO - Displays: 03:58:31 INFO - Display: 03:58:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:58:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:58:31 INFO - Display Serial Number: 1600x1200 60 03:58:31 INFO - Main Display: Yes 03:58:31 INFO - Mirror: Off 03:58:31 INFO - Online: Yes 03:58:31 INFO - Rotation: Supported 03:58:31 INFO - Return code: 0 03:58:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:58:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:58:31 INFO - Python 2.7.3 03:58:31 INFO - Return code: 0 03:58:31 INFO - grabbing minidump binary from tooltool 03:58:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:58:31 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 0x105592d30>, '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 0x1053b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10558ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:58:31 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 03:58:31 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 03:58:31 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 03:58:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:58:31 INFO - Return code: 0 03:58:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:58:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:58:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:58:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:58:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:58:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TjKfoploQnejlkXZvpUb0g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0388', '--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 03:58:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TjKfoploQnejlkXZvpUb0g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0388 --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 03:58:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SZIZxdpgRo/Render', 03:58:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:58:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:58:31 INFO - 'HOME': '/Users/cltbld', 03:58:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:58:31 INFO - 'LOGNAME': 'cltbld', 03:58:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:58:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:58:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:58:31 INFO - 'MOZ_NO_REMOTE': '1', 03:58:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:58:31 INFO - 'NO_EM_RESTART': '1', 03:58:31 INFO - 'PAGER': '/bin/cat', 03:58:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:58:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:58:31 INFO - 'PWD': '/builds/slave/test', 03:58:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:58:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:58:31 INFO - 'SHELL': '/bin/bash', 03:58:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mnljo94mzn/Listeners', 03:58:31 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 03:58:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:58:31 INFO - 'USER': 'cltbld', 03:58:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:58:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:58:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:58:31 INFO - 'XPC_FLAGS': '0x0', 03:58:31 INFO - 'XPC_SERVICE_NAME': '0', 03:58:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:58:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TjKfoploQnejlkXZvpUb0g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0388', '--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 03:58:31 INFO - mozversion application_buildid: 20161010025716 03:58:31 INFO - mozversion application_changeset: a2c1d4e7e5e01c580877d5e4d7b2680047529bde 03:58:31 INFO - mozversion application_display_name: Nightly 03:58:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:58:31 INFO - mozversion application_name: Firefox 03:58:31 INFO - mozversion application_remotingname: firefox 03:58:31 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 03:58:31 INFO - mozversion application_vendor: Mozilla 03:58:31 INFO - mozversion application_version: 52.0a1 03:58:31 INFO - mozversion platform_buildid: 20161010025716 03:58:31 INFO - mozversion platform_changeset: a2c1d4e7e5e01c580877d5e4d7b2680047529bde 03:58:31 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 03:58:31 INFO - mozversion platform_version: 52.0a1 03:58:31 INFO - using testdate: 1476097111 03:58:31 INFO - actual date: 1476097111 03:58:31 INFO - starting webserver on 'localhost:49274' 03:58:31 INFO - SUITE-START | Running 2 tests 03:58:31 INFO - TEST-START | dromaeo_css 03:58:31 INFO - operating with platform_type : mac_ 03:58:31 INFO - Initialising browser for dromaeo_css test... 03:58:31 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/tmpBeBmqN/profile http://localhost:49274/getInfo.html) 03:58:34 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 03:58:34 INFO - PROCESS | 1659 | colorDepth:24 03:58:34 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 03:58:34 INFO - PROCESS | 1659 | __metrics 03:58:34 INFO - PROCESS | 1659 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:58:34 INFO - PROCESS | 1659 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:58:35 INFO - TEST-INFO | 1659: exit 0 03:58:35 INFO - Browser initialized. 03:58:35 INFO - Running cycle 1/1 for dromaeo_css test... 03:58:35 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/tmpBeBmqN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:58:47 INFO - PROCESS | 1661 | RSS: Main: 234643456 03:58:47 INFO - PROCESS | 1661 | 03:59:38 INFO - PROCESS | 1661 | 1476097178926 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 04:01:50 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 04:01:52 INFO - PROCESS | 1661 | RSS: Main: 435900416 04:01:52 INFO - PROCESS | 1661 | 04:04:55 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 04:04:56 INFO - PROCESS | 1661 | RSS: Main: 375414784 04:04:56 INFO - PROCESS | 1661 | 04:07:59 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 04:07:59 INFO - PROCESS | 1661 | RSS: Main: 346566656 04:07:59 INFO - PROCESS | 1661 | 04:11:02 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 04:11:02 INFO - PROCESS | 1661 | RSS: Main: 342675456 04:11:02 INFO - PROCESS | 1661 | 04:14:06 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 04:14:06 INFO - PROCESS | 1661 | RSS: Main: 331255808 04:14:06 INFO - PROCESS | 1661 | 04:17:09 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 04:17:09 INFO - PROCESS | 1661 | RSS: Main: 629956608 04:17:09 INFO - PROCESS | 1661 | 04:17:09 INFO - PROCESS | 1661 | __start_tp_report 04:17:09 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 04:17:09 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 04:17:09 INFO - PROCESS | 1661 | |i|pagename|runs| 04:17:09 INFO - PROCESS | 1661 | |0;dojo.html;2784.5127102757015;3315.37007968486;3334.7661365294844;3363.4282172030785;3387.7120444762195;5541.667499950006;5670.659760414378;5676.971615141929;5721.971390143049;5779.393163717807;4616.28447590624;5625.887482250363;5647.265855438797;5764.596478246524;5782.739776710047;5129.769160387791;5130.6152038597065;5187.429382767911;5211.42674305826;5222.477752224785;5221.86945326368;5306.92039619406;5316.38861530925;5360.865978350534;5380.435054319299;5093.159628661267;5276.182191760283;5287.629865909388;5298.92051619226;5367.597430192732;5125.282460455539;5155.9484405155845;5172.250023746573;5202.947970520284;5222.190560463135;4989.902221511262;5090.287359769598;5147.716875571842;5228.163493840967;5281.049411106;4932.506749325074;5706.543476521868;5752.827415177551;5753.827385178452;5771.249737534135;6032.245969253843;6112.541559383064;6253.74985000604;6278.843028924268;6295.905561416583;6147.323794382614;6152.138700581922;6229.221347331583;6231.844203894893;6261.186045814061;5781.797637082724;5789;5797.130430435384;5799.130130480462;5819.359870414251;6132.263729246456;6194;6232.0963460298;6258.311585725566;6267.9373206268265;5623.0721930880945;5904.852378690567;6061.848453788691;6106.97642067191;6144.539159563051;6183.876322473615;6211.409916058057;6234.532410069172;6241.594296370721;6261.78083767066;6072.36240194782;6184.6598437085495;6203.2556093269095;6204.627722336674;6226.377362263737;6103.298046209379;6105.906617299833;6185.041318595625;6218.253809542884;6236.812895613139;4912.361393018885;4924.261360795909;4927.137750893579;4927.581155601814;4964.429090654548;4423.911521769546;4436.134953684001;4455.1535208310315;4457.77711114443;4518.525554816762;5625.46558076982;5645.096784514458;5707.429257074259;5710.057840456278;5809.128630705428;6182.474489668339;6240.31356550779;6264.467520260738;6270.122182894398;6272.090546870678;5795.304563452412;5803.216565763652;5810.941890581124;5823.56323275756;5825.679587622722;5248.816291429782;5266.447023062523;5346.492083252163;5349.438308977579;5354.170103633943;5125.282460455464;5139.408967968731;6215.06773983906;6259.280182779039;6271.059341098871;6208.379162083756;6208.534359923115;6224.875502490016;6229.065640154013;6242.625442473466;6189.288231853395;6218.782342617987;6228.501719862309;6237.937620623736;6248.281447633399;6044.365341639241;6058.878822423615;6135.509159267337;6137.447629713347;6143.017117261216;6113.962834745988;6137.631742095488;6144.003198960445;6145.324014358506;6156.846078847885;6020.638761674313;6103.2677270967215;6125.050617154348;6135.355787642234;6147.385261473817;5943.405659434022;6011.248593925759;6043.697815109139;6047.425494578008;6123.908141377794;5956.702164891652;6010.759569617167;6035.155078289131;6038.4867286279405;6039.516838652809;5979.402059793986;6062.151298818081;6115.480184184395;6115.990861507801;6122.051082082285;5951.1073338999495;5959.791407300723;5988.041913293852;5989.2812862456785;5999.010163323002 04:17:09 INFO - PROCESS | 1661 | |1;ext.html;462.585985542939;527.3592585009213;528.363322196753;528.7144941731464;553.363631823403;32818.835905820495;40415.39376909345;41773.79113104434;41998.79000604993;43881.780591097;41618.58381416185;42423.93940151498;42666.786666066626;42701.57298427023;42739;8843.125984055176;9816.754581135458;10016.348937319088;10025.248106392013;10039.096481316716;8890.822183556325;9009.459432434074;9021.594028268712;9025.548722563879;12071;12064.819027714591;12429.875701243007;12434.378281085954;12447.190932589398;12456.252624842538;12399.19405238661;12480;12490.875091249152;12499.312537810416;12505.624831255076;8575.485470871768;8811.823763524693;10264.538095785632;11799.351035693033;12019.21875078117;40212.79893600543;42459.1508169838;42475.57524424747;42652.57347426548;42657.146857062995;3390.5761779777526;3621.6559426854406;3695.558732094485;3961.9405708914387;4070.14526949341;31123.105749971503;31909.680903190903;32301.192470188198;32340.353192936243;32352.676473235202;3179.952300715491;3297.7526685498683;3316.1709572606846;3377.2738861144726;3380.678835510623;492546;492889;494543;495195;495206.9999999964;61231;62106.37893621096;62116.689416553534;62139;62256;65006;65162.67418662876;65169.34830651633;65180;65321;16014.519564413085;16266.75599866003;16304.67390652204;16315.265813038443;16334.101624410772;12628.747425051632;12719.236845789283;12725.61823145307;12729.10896237273;12733.426995785212;15605.765913511306;15971.44099956496;15987.120708361152;17835.73246401305;18279.451616451526;5348.438413966555;5384.488473595002;6375.330590287953;6412.3267056959285;6447.033654735351;730.6565914020401;970.1850445625709;1025.5795123999274;1094.8686157661132;1099.3623698254994;24186.153484627954;28469.715302846707;30328.090157295315;30973.69026309753;30999.915002974787;1043.155044414027;1073.7208325835184;1073.8442925775719;1120.4341807387218;1152.03229287399;15769.05385676863;15786.605334866721;15890.841091589167;16160.838391615933;16199.43301984425;642082.789586049;646833;647650.7617461883;647685;647782;41466.963325917095;45066.54933450689;51784;57733.42266577364;59075.704621476616;1227.533537255837;1320.735852829438;1348.7707089794758;1351.2297990145523;1359.0350850895777;30750.231244219074;31804.204894877814;31934.520982184822;32615.80299743041;32636.347273054882;51195;51574.74212628913;51794.22308665298;51845.70385740387;51851.74074129756;799504;813647;814070;815040;816693;25615.103471378414;25779.355516112246;25864;25879.353016174748;25896.741032589434;45415.54584454245;45847.541524585664;46541;47152;47258.52741472541;29535.704642954155;37594.624053759115;38020.04949876275;38994.80502597469;39156.80421597988;37047.44428833516;38156;38358.42462363011;39243.80378098205;39819.40270895881;37456.2508749818;37479.063023424635;38388.61611383851;38389.80805096068;38397.008388909264;30069.54895676523;31904.521432178073;33495.665043349254;34126.488102677984;34366.48450273198;24427.877860610584;24776.876115619307;25169.993200271783;25361;25760.742392576583 04:17:09 INFO - PROCESS | 1661 | |2;jquery.html;303.69782066843493;369.07730673316706;370.3944051475836;379.965803077723;400.37341560457884;15259.168899732034;15356;18154.27382904684;19644.607107857835;19845;15565.455209067684;19448.513787155327;19665.803341966577;19720.40838774839;19745.605087898235;5804.686214991697;5881.911771323434;6036.3661815509395;6115.205023346981;6119.602225855305;4137.81379837908;4375.365571992059;5966.254218222723;6082.787102451415;6090.086487026959;4404.031113155117;6738.494612904052;6745.1905771307265;6751.898721519181;6752.358525940028;6454.903176452357;6687.465002799764;6757.898631520531;6759.560628559152;6763.966180169067;5822.33043200029;5826.359100498942;5830.125481177814;5830.825075247707;5837.620554663933;22282;24768.752312476823;24858.875705621354;24861.378465538324;25024.624630630555;2198.4503874031493;2251.0995601759264;2251.7072780538588;2396.8681722505257;2405.1341517053847;14638.560843174722;15202.391904323813;15388.769168462482;15539;15919.965202261816;1825.4067428085925;1831.8168183181708;1842.0973722875694;1885.3212843376373;1949.9567731263849;91542;123229.38385308016;123946;124265.75734242632;125681.37159314145;10172.84740728875;10769.83845242322;10936.453177341102;11151.776964460827;11562.710932226762;13150.539731109366;13655.975801814904;13663.931680341733;13728.931355343359;14387.208703521406;7021.22766495685;8181;8292.419530632798;8422.326213902874;8475.194856488624;6794.592324460449;8086;8191;8345.707900223462;8462.703805366782;4402.603765661106;4460.620847228022;4479.3057076153245;4540.228161212602;4594.1730488512385;1565.506865337415;1931.8357939575294;1933.8936358500137;1945.8735182213395;1955.162147581269;834.2616784146074;856.6616186606255;885.7829831691276;907.7563737679421;971.8639390485338;19482.733622314503;19509.51226219356;20520.589588208153;20803.271885483937;20841.062152203187;861.6208868098142;953.3279038277998;962.153305091515;974.4056125763278;992.8411454167299;10309.278350515391;10438.739031524121;10945.398003109745;10983.340999540053;11018.228723989243;599391;603546;612556;625148;627061;134329.65670343366;135444;135712;140059;141529;1008.2589296866797;1021.4841505039907;1028.7428142964259;1040.7970445763003;1041.614602597044;17009.574760631083;17935.385815276688;18051.277948881896;18626.534336641693;18687.719684204476;55193;55286;55314.06400775954;56396;56492;58791.70604146952;75027.49945001179;78090;78898;80314;7223;7680.231976802275;7877.527348359117;7932.4447288691545;8180.713675021346;9157;11469.08247340194;11914.40427978615;12364.814527781911;12706.30115343665;10674.57301707923;10755.623553175601;11041.558337666404;11159.163062770162;11237.38194399316;9163.808704868325;10399.48002599882;10779.67660970172;11053.723656908642;11244.66266012021;10845.837312440162;11002.579432525152;11315.717107072389;11364.659060228207;11386.886131138914;7999.840003199787;8074.39442041861;8126.024877014796;8177.877331839908;8223.917760822314;9728.902710973083;10736.409497477714;10998;11069.889301106887;11186.608468703556 04:17:09 INFO - PROCESS | 1661 | |3;mootools.html;1344.7646661834176;1377.1943413103334;1551.58107311026;1703.5400441880693;1882.9905850470755;7825.41309401795;8284.917150828498;8729.127087291276;8965.32760042996;8983.460992340464;8709.390342676015;8806.823863522726;8815.559222038904;8816.382853200263;8838.86741698875;2306.3772781349066;2335.1476711000446;2337.637666161748;2340.33300509354;2340.707411573553;2297.7242730872317;2332.4518738096535;2345.9648105278434;2347.436615212354;2348.5420343033115;2294.942626434344;2318.7217533895955;2318.8144948404088;2325.1164557468137;2332.183735692511;2283.5204607032542;2304.953900921989;2308.088305119485;2313.7339205991357;2315.6063469210276;1794.6590147871862;2294.9196778112855;2298.195631528968;2303.573838839803;2305.0318866076286;8748.387612867102;8749.081346458594;8856.778580535474;8865.911340886572;8868.201861832466;4350.608445239944;5164.14791559396;5328.17413300939;5331.2003199519995;5371.919421208685;3518.736094792901;3600.063983364318;3651.8174091295327;3681.9447708284397;3690.538682664667;1022.4734261855151;1056.799208150449;1057.2916146182076;1076.5532304093792;1079.034264333425;11547.595834145848;11608.535658573646;11657.300561966224;11688;11693.649190524298;4355.716878402894;4379.4525684289465;4560.042391097841;4560.977195114003;4598.126355992351;9101.954490227507;9115.134062264075;9115.99724030356;9118.908810911806;9121.954390228138;5230.137027390438;5234;5234.581233501311;5244;5252.816151434681;4335.501417337046;4388.934165987513;4561.885952851205;4562.292844609091;4889.755512224375;3713.405855063177;3716.0524066362905;3755.6244375562223;3764.134249122717;3767.51022367096;1120.1542835159403;1123.0398009701587;1123.4102096399456;1133.5919069135105;1138.0781566930812;768.8385439057748;875.9956200218958;900.1339247930156;921.3780698028804;930.4045410937006;5777.24895763548;5803.245578074823;6027.276726792813;6115.205023347025;6245.906311405332;806.2421323955463;944.7354740672623;945.6170251048198;955.5843208204453;999.14073896449;3902.297586434412;3944.2111577684004;3946.309395855771;4054.3107671695884;4065.5527891931865;10354.637587684394;10728;10837;10876.18428617841;10890.183236257313;10825;10833.370820774971;10849.186311026704;10864.804871464132;10865.348079115278;8166.265036146775;8304.501729896225;8308.919840420827;8335.916640833635;8357.623906924204;5606.971965140148;5804.506616937439;5886.52907767369;5985.162077309094;6007.369226231358;10004.899951000689;10180.949095254477;10229.48852557384;10264.743381415525;10305.948470257901;9268.026857180168;9704;10181.541830617492;10202.38785672862;10319.122874555447;7053.823654408681;7221.566705997657;7345.6694448751;7497.850042999218;7580.241975802375;8249.876251856107;8269.710560130367;8278.503289802631;8355.331573473986;8356.832863342577;7192.244814294424;7393.260673932564;7453.850922981402;7501.399888009056;7519.248075192437;6199.504039676905;7417.740379086922;7463.888041679271;7507.249275072449;7540.9622951884885;6917.27368626287;6961.79114626562;7035.648217588998;7036.683349249193;7039.753608623879;4431.534688857752;4781.784819683053;4998.500149985118;5017.899642007213;5058.64589478733;4402.647788176874;4988.152014157529;5096.490350964874;5115.87210319745;5225.1378522543355 04:17:09 INFO - PROCESS | 1661 | |4;prototype.html;269.0851106238788;289.8029340048767;297.8064258232149;310.21051424125596;313.3372916282061;4438.778061096944;5319.840404787853;5335.386430560487;5337.786488540463;5343.706096164709;4111.71218014739;4692.178868697973;5125.436202017784;5143.81996630118;5271.815486457966;4012.638862502374;4045.9797701011453;4052.8378864845517;4056.91069099414;4059.8579049733266;3510.8063258492107;4024.350016493224;4037.6567991720594;4046.5751096134923;4067.8169482373364;3223.339215460837;4000.919981600366;4004.599540046001;4034.3055916514986;4044.5550989391145;3169.1284896653374;3481.8781342652887;4002.319605667029;4006.2388146252265;4060.959390406117;3025.1680787783316;3396.6603339666085;3459.97930610468;3476.418229705478;3515.4726790981304;5092.490750924915;5100.183970564692;5126.461721519224;5134.383873935151;5168.475299786534;4348.5216626171095;4568.931466028012;4643.489216186251;4650.163185541726;4660.856006237135;5562.7774889004395;5564.165375193713;5581.469760372799;5588.479258067432;5595.272614560122;3839.212961342918;3858.013123241533;3874.5156855393075;3882.2429626222897;3889.863854765098;5030.547250747451;5132.666376685504;5568.303962004749;5669.918375472833;5750.166225897222;3264.689854463822;3351.815650139217;3879.670228030649;3918.431827385013;3928.4303775952326;3965.0483883867664;3984.4820173377857;3996.5429903134163;3996.7402118862183;4016.477857878457;4169.770662613585;4535.682502224879;4604.493505714368;4624.329472226508;4643.8374656886845;4593.989322349078;4647.535246475325;5128.410232823198;5444.8094316698725;5601.719914004283;1175.8824117588172;1176.817593273044;1311.4754098360731;1338.772408690525;1341.1885809085559;793.2622660925394;823.8269963307653;852.8336974289954;857.4651374040523;864.9610767515477;3998.860324807416;4632.791524381343;5231.424543300234;5258.553022993012;5304.814331498456;5642.4357564244065;5647.802326918538;5697.914531282034;5702.889930725658;5706.403586368193;4172.7913604319665;4966.157995953916;5211.140161873249;5233.712145832016;5243.239730239094;2258.808001319906;2808.9016884408948;2964.52567589189;2985.014965048262;2997.880084796627;5583.817224182178;5876.9118463223085;5877.970610147007;5879.647221166658;5886.8822623547285;4608.5621865922685;5446.264754258124;5751.964646363611;5772.422757724278;5809.564282678816;5264.473302742238;5764.213093940892;5820.24336836217;5834.154047663065;5855.954903450182;5226.425093239664;5386.542143917811;5522.696251706195;5639.392773059658;5794.710264486843;4806.423229212517;4990.900181996412;5589.077802162597;5778.653280803165;5815.2149459822385;5979.461848433488;5997.220361352996;6006.399360063959;6008.279006519246;6027.879442411215;3340.4154273002614;3563.465480177994;3836.5204349456317;3900.1614652849776;3906.5312162540677;5759.51044161251;5780.017397042597;5784.710764461676;5789.507891829242;5801.361850196559;4832.901289139851;5352.9732351338;5565.777368905198;5586.832395028156;5617.865211219711;4552.453705555355;5294.338207724035;5595.580331475075;5604.747786349705;5632.915506267491;5266.473352664703;5510.752016159202;5514.200440936122;5522.2544956430365;5529.4470552944385;5500;5540.445955404427;5546.195801608664;5548.69482178484;5588.624834445426;4590.862274131781;5507.421720719427;5508.889822203613;5520.227168196536;5580.21876937221 04:17:09 INFO - PROCESS | 1661 | |5;yui.html;696.2271878215182;714.1180641907243;762.3710438887919;798.1220657276999;809.8663720486119;15656.686866262668;16056.357745690173;16057.197140142975;16065.357385704587;16110.758338624915;14233.288335583231;14363.64090897728;14379.137251764876;14380.065295755769;14392.208428536427;661.6856992928344;688.8622275544886;692.2247083266723;815.7552734179752;824.2293455619;844.945078569894;852.5524099847598;852.6674596907211;854.4531499840107;854.6154230596226;886.4238245140646;904.2178515584005;905.2079430498314;905.3707673167152;906.0893801729253;899.9865002024975;900.3832374823266;901.612306708112;901.8421776189206;903.8734577159203;514.1927174336294;535.7776522743058;635.1140159477549;667.4693618573198;668.3750693101965;14686.412543498247;15217.467388641455;15217.6195595111;15220.162891040989;15233.847661523354;13350.399232034582;13693.383797729026;13762.064905840385;13785.793213101811;14038.578842634737;11967.581134660331;12221.93889030558;12341.5063397464;12718.364081795873;12805.807912881315;4451.443569553806;4479.61923236522;4546.568076032805;4558.247889098295;4709.081729062834;115880.42059789647;120135;121815.3909230457;123106;127484;10564.601479700048;10689.30220946199;10830.644399476876;10968.890311096946;11027.724306892393;21188.894055529625;21222.575548488945;21306.04122814477;21332.573348532944;21348.573028539344;7577.393808495307;7581.336571102885;7608.8478230435085;7674.654640541189;7840.098388685375;4116.773577453209;4176.352665336878;4212.326027835532;4237.237297286456;4306.547812479707;3308.801471911693;3419.26485805553;3420.110771199506;3423.5891692997;3425.7088147507243;571.3914680864866;586.8092869817326;593.0570393074945;593.6794131169206;595.5116804220584;575.2062154227224;586.9794557190478;588.2617315269395;600.5585894367637;613.0650757594703;7931.365490760725;8393;8698.91301086981;8737;8999;523.359650562911;598.9820305390846;599.8410421238375;615.187951903483;626.7179769103831;4046.089629833264;4127.4634297541725;4157.8960525987095;4211.284081706117;4235.597618226264;162011.27932324097;162797;163509;169324.3067569333;171583;109437.45281273543;139232;139733.30133349472;143299;144011.5598844019;104132.95867041383;104626;104854;105331;108090.37864432197;8352.33623966028;8353.498790072616;8501.489910605384;8816.51509167002;9227.123423274777;42814.57185428106;51121;55719.72140139274;57165.42834571767;58081.70959145177;75591.11022224488;80375;81858;82862;84294.57852710698;13142.080054396101;13315.001374897116;13431.932840336127;13470.797938031134;13726.656833578842;14708.926455367655;14831.777523337374;14931.85068149348;15264.465743699362;15526;13468;13486.460541578619;13913.930430348188;13943.721125577635;14290.499832505813;13484.190948543119;13670.726585468434;13794.517191898236;13985.370658320604;14263.786043209568;13628.727425451238;13864.168149911038;13922.72154556883;13923.860761392256;14079.436822526985;10988.615398461016;11283.492242848928;11390.7087552115;11394.03150732197;12090.274583525017;8556.903558627928;10473.581056757948;10807;10981.890181098086;11366.715832104264 04:17:09 INFO - PROCESS | 1661 | __end_tp_report 04:17:09 INFO - PROCESS | 1661 | __start_cc_report 04:17:09 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,2519 04:17:09 INFO - PROCESS | 1661 | __end_cc_report 04:17:09 INFO - PROCESS | 1661 | __startTimestamp1476098229955__endTimestamp 04:17:09 INFO - PROCESS | 1661 | 04:17:09 INFO - PROCESS | 1661 | ------- Summary: start ------- 04:17:09 INFO - PROCESS | 1661 | Number of tests: 6 04:17:09 INFO - PROCESS | 1661 | 04:17:09 INFO - PROCESS | 1661 | [#0] dojo.html Cycles:165 Average:5702.69 Median:5959.79 stddev:647.62 (10.9%) stddev-sans-first:607.80 04:17:09 INFO - PROCESS | 1661 | Values: 2784.5 3315.4 3334.8 3363.4 3387.7 5541.7 5670.7 5677.0 5722.0 5779.4 4616.3 5625.9 5647.3 5764.6 5782.7 5129.8 5130.6 5187.4 5211.4 5222.5 5221.9 5306.9 5316.4 5360.9 5380.4 5093.2 5276.2 5287.6 5298.9 5367.6 5125.3 5155.9 5172.3 5202.9 5222.2 4989.9 5090.3 5147.7 5228.2 5281.0 4932.5 5706.5 5752.8 5753.8 5771.2 6032.2 6112.5 6253.7 6278.8 6295.9 6147.3 6152.1 6229.2 6231.8 6261.2 5781.8 5789.0 5797.1 5799.1 5819.4 6132.3 6194.0 6232.1 6258.3 6267.9 5623.1 5904.9 6061.8 6107.0 6144.5 6183.9 6211.4 6234.5 6241.6 6261.8 6072.4 6184.7 6203.3 6204.6 6226.4 6103.3 6105.9 6185.0 6218.3 6236.8 4912.4 4924.3 4927.1 4927.6 4964.4 4423.9 4436.1 4455.2 4457.8 4518.5 5625.5 5645.1 5707.4 5710.1 5809.1 6182.5 6240.3 6264.5 6270.1 6272.1 5795.3 5803.2 5810.9 5823.6 5825.7 5248.8 5266.4 5346.5 5349.4 5354.2 5125.3 5139.4 6215.1 6259.3 6271.1 6208.4 6208.5 6224.9 6229.1 6242.6 6189.3 6218.8 6228.5 6237.9 6248.3 6044.4 6058.9 6135.5 6137.4 6143.0 6114.0 6137.6 6144.0 6145.3 6156.8 6020.6 6103.3 6125.1 6135.4 6147.4 5943.4 6011.2 6043.7 6047.4 6123.9 5956.7 6010.8 6035.2 6038.5 6039.5 5979.4 6062.2 6115.5 6116.0 6122.1 5951.1 5959.8 5988.0 5989.3 5999.0 04:17:09 INFO - PROCESS | 1661 | 04:17:09 INFO - PROCESS | 1661 | [#1] ext.html Cycles:180 Average:77116.43 Median:37251.85 stddev:178358.86 (478.8%) stddev-sans-first:178766.35 04:17:09 INFO - PROCESS | 1661 | Values: 462.6 527.4 528.4 528.7 553.4 32818.8 40415.4 41773.8 41998.8 43881.8 41618.6 42423.9 42666.8 42701.6 42739.0 8843.1 9816.8 10016.3 10025.2 10039.1 8890.8 9009.5 9021.6 9025.5 12071.0 12064.8 12429.9 12434.4 12447.2 12456.3 12399.2 12480.0 12490.9 12499.3 12505.6 8575.5 8811.8 10264.5 11799.4 12019.2 40212.8 42459.2 42475.6 42652.6 42657.1 3390.6 3621.7 3695.6 3961.9 4070.1 31123.1 31909.7 32301.2 32340.4 32352.7 3180.0 3297.8 3316.2 3377.3 3380.7 492546.0 492889.0 494543.0 495195.0 495207.0 61231.0 62106.4 62116.7 62139.0 62256.0 65006.0 65162.7 65169.3 65180.0 65321.0 16014.5 16266.8 16304.7 16315.3 16334.1 12628.7 12719.2 12725.6 12729.1 12733.4 15605.8 15971.4 15987.1 17835.7 18279.5 5348.4 5384.5 6375.3 6412.3 6447.0 730.7 970.2 1025.6 1094.9 1099.4 24186.2 28469.7 30328.1 30973.7 30999.9 1043.2 1073.7 1073.8 1120.4 1152.0 15769.1 15786.6 15890.8 16160.8 16199.4 642082.8 646833.0 647650.8 647685.0 647782.0 41467.0 45066.5 51784.0 57733.4 59075.7 1227.5 1320.7 1348.8 1351.2 1359.0 30750.2 31804.2 31934.5 32615.8 32636.3 51195.0 51574.7 51794.2 51845.7 51851.7 799504.0 813647.0 814070.0 815040.0 816693.0 25615.1 25779.4 25864.0 25879.4 25896.7 45415.5 45847.5 46541.0 47152.0 47258.5 29535.7 37594.6 38020.0 38994.8 39156.8 37047.4 38156.0 38358.4 39243.8 39819.4 37456.3 37479.1 38388.6 38389.8 38397.0 30069.5 31904.5 33495.7 34126.5 34366.5 24427.9 24776.9 25170.0 25361.0 25760.7 04:17:09 INFO - PROCESS | 1661 | 04:17:09 INFO - PROCESS | 1661 | [#2] jquery.html Cycles:180 Average:35535.47 Median:2401.00 stddev:102543.52 (4270.9%) stddev-sans-first:102797.06 04:17:09 INFO - PROCESS | 1661 | Values: 303.7 369.1 370.4 380.0 400.4 15259.2 15356.0 18154.3 19644.6 19845.0 15565.5 19448.5 19665.8 19720.4 19745.6 5804.7 5881.9 6036.4 6115.2 6119.6 4137.8 4375.4 5966.3 6082.8 6090.1 4404.0 6738.5 6745.2 6751.9 6752.4 6454.9 6687.5 6757.9 6759.6 6764.0 5822.3 5826.4 5830.1 5830.8 5837.6 22282.0 24768.8 24858.9 24861.4 25024.6 2198.5 2251.1 2251.7 2396.9 2405.1 14638.6 15202.4 15388.8 15539.0 15920.0 1825.4 1831.8 1842.1 1885.3 1950.0 91542.0 123229.4 123946.0 124265.8 125681.4 10172.8 10769.8 10936.5 11151.8 11562.7 13150.5 13656.0 13663.9 13728.9 14387.2 7021.2 8181.0 8292.4 8422.3 8475.2 6794.6 8086.0 8191.0 8345.7 8462.7 4402.6 4460.6 4479.3 4540.2 4594.2 1565.5 1931.8 1933.9 1945.9 1955.2 834.3 856.7 885.8 907.8 971.9 19482.7 19509.5 20520.6 20803.3 20841.1 861.6 953.3 962.2 974.4 992.8 10309.3 10438.7 10945.4 10983.3 11018.2 599391.0 603546.0 612556.0 625148.0 627061.0 134329.7 135444.0 135712.0 140059.0 141529.0 1008.3 1021.5 1028.7 1040.8 1041.6 17009.6 17935.4 18051.3 18626.5 18687.7 55193.0 55286.0 55314.1 56396.0 56492.0 58791.7 75027.5 78090.0 78898.0 80314.0 7223.0 7680.2 7877.5 7932.4 8180.7 9157.0 11469.1 11914.4 12364.8 12706.3 10674.6 10755.6 11041.6 11159.2 11237.4 9163.8 10399.5 10779.7 11053.7 11244.7 10845.8 11002.6 11315.7 11364.7 11386.9 7999.8 8074.4 8126.0 8177.9 8223.9 9728.9 10736.4 10998.0 11069.9 11186.6 04:17:09 INFO - PROCESS | 1661 | 04:17:09 INFO - PROCESS | 1661 | [#3] mootools.html Cycles:180 Average:5658.04 Median:4835.77 stddev:3205.66 (66.3%) stddev-sans-first:3198.27 04:17:09 INFO - PROCESS | 1661 | Values: 1344.8 1377.2 1551.6 1703.5 1883.0 7825.4 8284.9 8729.1 8965.3 8983.5 8709.4 8806.8 8815.6 8816.4 8838.9 2306.4 2335.1 2337.6 2340.3 2340.7 2297.7 2332.5 2346.0 2347.4 2348.5 2294.9 2318.7 2318.8 2325.1 2332.2 2283.5 2305.0 2308.1 2313.7 2315.6 1794.7 2294.9 2298.2 2303.6 2305.0 8748.4 8749.1 8856.8 8865.9 8868.2 4350.6 5164.1 5328.2 5331.2 5371.9 3518.7 3600.1 3651.8 3681.9 3690.5 1022.5 1056.8 1057.3 1076.6 1079.0 11547.6 11608.5 11657.3 11688.0 11693.6 4355.7 4379.5 4560.0 4561.0 4598.1 9102.0 9115.1 9116.0 9118.9 9122.0 5230.1 5234.0 5234.6 5244.0 5252.8 4335.5 4388.9 4561.9 4562.3 4889.8 3713.4 3716.1 3755.6 3764.1 3767.5 1120.2 1123.0 1123.4 1133.6 1138.1 768.8 876.0 900.1 921.4 930.4 5777.2 5803.2 6027.3 6115.2 6245.9 806.2 944.7 945.6 955.6 999.1 3902.3 3944.2 3946.3 4054.3 4065.6 10354.6 10728.0 10837.0 10876.2 10890.2 10825.0 10833.4 10849.2 10864.8 10865.3 8166.3 8304.5 8308.9 8335.9 8357.6 5607.0 5804.5 5886.5 5985.2 6007.4 10004.9 10180.9 10229.5 10264.7 10305.9 9268.0 9704.0 10181.5 10202.4 10319.1 7053.8 7221.6 7345.7 7497.9 7580.2 8249.9 8269.7 8278.5 8355.3 8356.8 7192.2 7393.3 7453.9 7501.4 7519.2 6199.5 7417.7 7463.9 7507.2 7541.0 6917.3 6961.8 7035.6 7036.7 7039.8 4431.5 4781.8 4998.5 5017.9 5058.6 4402.6 4988.2 5096.5 5115.9 5225.1 04:17:09 INFO - PROCESS | 1661 | 04:17:09 INFO - PROCESS | 1661 | [#4] prototype.html Cycles:180 Average:4501.53 Median:5130.54 stddev:1406.41 (27.4%) stddev-sans-first:1374.01 04:17:09 INFO - PROCESS | 1661 | Values: 269.1 289.8 297.8 310.2 313.3 4438.8 5319.8 5335.4 5337.8 5343.7 4111.7 4692.2 5125.4 5143.8 5271.8 4012.6 4046.0 4052.8 4056.9 4059.9 3510.8 4024.4 4037.7 4046.6 4067.8 3223.3 4000.9 4004.6 4034.3 4044.6 3169.1 3481.9 4002.3 4006.2 4061.0 3025.2 3396.7 3460.0 3476.4 3515.5 5092.5 5100.2 5126.5 5134.4 5168.5 4348.5 4568.9 4643.5 4650.2 4660.9 5562.8 5564.2 5581.5 5588.5 5595.3 3839.2 3858.0 3874.5 3882.2 3889.9 5030.5 5132.7 5568.3 5669.9 5750.2 3264.7 3351.8 3879.7 3918.4 3928.4 3965.0 3984.5 3996.5 3996.7 4016.5 4169.8 4535.7 4604.5 4624.3 4643.8 4594.0 4647.5 5128.4 5444.8 5601.7 1175.9 1176.8 1311.5 1338.8 1341.2 793.3 823.8 852.8 857.5 865.0 3998.9 4632.8 5231.4 5258.6 5304.8 5642.4 5647.8 5697.9 5702.9 5706.4 4172.8 4966.2 5211.1 5233.7 5243.2 2258.8 2808.9 2964.5 2985.0 2997.9 5583.8 5876.9 5878.0 5879.6 5886.9 4608.6 5446.3 5752.0 5772.4 5809.6 5264.5 5764.2 5820.2 5834.2 5856.0 5226.4 5386.5 5522.7 5639.4 5794.7 4806.4 4990.9 5589.1 5778.7 5815.2 5979.5 5997.2 6006.4 6008.3 6027.9 3340.4 3563.5 3836.5 3900.2 3906.5 5759.5 5780.0 5784.7 5789.5 5801.4 4832.9 5353.0 5565.8 5586.8 5617.9 4552.5 5294.3 5595.6 5604.7 5632.9 5266.5 5510.8 5514.2 5522.3 5529.4 5500.0 5540.4 5546.2 5548.7 5588.6 4590.9 5507.4 5508.9 5520.2 5580.2 04:17:09 INFO - PROCESS | 1661 | 04:17:09 INFO - PROCESS | 1661 | [#5] yui.html Cycles:180 Average:25147.80 Median:3419.69 stddev:41491.43 (1213.3%) stddev-sans-first:41567.20 04:17:09 INFO - PROCESS | 1661 | Values: 696.2 714.1 762.4 798.1 809.9 15656.7 16056.4 16057.2 16065.4 16110.8 14233.3 14363.6 14379.1 14380.1 14392.2 661.7 688.9 692.2 815.8 824.2 844.9 852.6 852.7 854.5 854.6 886.4 904.2 905.2 905.4 906.1 900.0 900.4 901.6 901.8 903.9 514.2 535.8 635.1 667.5 668.4 14686.4 15217.5 15217.6 15220.2 15233.8 13350.4 13693.4 13762.1 13785.8 14038.6 11967.6 12221.9 12341.5 12718.4 12805.8 4451.4 4479.6 4546.6 4558.2 4709.1 115880.4 120135.0 121815.4 123106.0 127484.0 10564.6 10689.3 10830.6 10968.9 11027.7 21188.9 21222.6 21306.0 21332.6 21348.6 7577.4 7581.3 7608.8 7674.7 7840.1 4116.8 4176.4 4212.3 4237.2 4306.5 3308.8 3419.3 3420.1 3423.6 3425.7 571.4 586.8 593.1 593.7 595.5 575.2 587.0 588.3 600.6 613.1 7931.4 8393.0 8698.9 8737.0 8999.0 523.4 599.0 599.8 615.2 626.7 4046.1 4127.5 4157.9 4211.3 4235.6 162011.3 162797.0 163509.0 169324.3 171583.0 109437.5 139232.0 139733.3 143299.0 144011.6 104133.0 104626.0 104854.0 105331.0 108090.4 8352.3 8353.5 8501.5 8816.5 9227.1 42814.6 51121.0 55719.7 57165.4 58081.7 75591.1 80375.0 81858.0 82862.0 84294.6 13142.1 13315.0 13431.9 13470.8 13726.7 14708.9 14831.8 14931.9 15264.5 15526.0 13468.0 13486.5 13913.9 13943.7 14290.5 13484.2 13670.7 13794.5 13985.4 14263.8 13628.7 13864.2 13922.7 13923.9 14079.4 10988.6 11283.5 11390.7 11394.0 12090.3 8556.9 10473.6 10807.0 10981.9 11366.7 04:17:09 INFO - PROCESS | 1661 | -------- Summary: end -------- 04:17:09 INFO - PROCESS | 1661 | 04:17:10 INFO - TEST-INFO | 1661: exit 0 04:17:10 INFO - TEST-OK | dromaeo_css | took 1118771ms 04:17:10 INFO - TEST-START | kraken 04:17:10 INFO - operating with platform_type : mac_ 04:17:10 INFO - Initialising browser for kraken test... 04:17:10 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/tmpiZ6W79/profile http://localhost:49274/getInfo.html) 04:17:12 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 04:17:12 INFO - PROCESS | 1667 | colorDepth:24 04:17:12 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 04:17:12 INFO - PROCESS | 1667 | __metrics 04:17:12 INFO - PROCESS | 1667 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:17:12 INFO - PROCESS | 1667 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:17:13 INFO - TEST-INFO | 1667: exit 0 04:17:13 INFO - Browser initialized. 04:17:13 INFO - Running cycle 1/1 for kraken test... 04:17:13 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/tmpiZ6W79/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:17:25 INFO - PROCESS | 1668 | RSS: Main: 234721280 04:17:25 INFO - PROCESS | 1668 | 04:17:28 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection) 04:17:29 INFO - PROCESS | 1668 | RSS: Main: 293195776 04:17:29 INFO - PROCESS | 1668 | 04:17:31 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-dft) 04:17:32 INFO - PROCESS | 1668 | RSS: Main: 293244928 04:17:32 INFO - PROCESS | 1668 | 04:17:35 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-fft) 04:17:36 INFO - PROCESS | 1668 | RSS: Main: 383299584 04:17:36 INFO - PROCESS | 1668 | 04:17:38 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator) 04:17:39 INFO - PROCESS | 1668 | RSS: Main: 284860416 04:17:39 INFO - PROCESS | 1668 | 04:17:41 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:17:41 INFO - PROCESS | 1668 | RSS: Main: 288927744 04:17:41 INFO - PROCESS | 1668 | 04:17:44 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom) 04:17:45 INFO - PROCESS | 1668 | RSS: Main: 326430720 04:17:45 INFO - PROCESS | 1668 | 04:17:48 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate) 04:17:48 INFO - PROCESS | 1668 | RSS: Main: 326361088 04:17:48 INFO - PROCESS | 1668 | 04:17:51 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial) 04:17:52 INFO - PROCESS | 1668 | RSS: Main: 324472832 04:17:52 INFO - PROCESS | 1668 | 04:17:53 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:17:54 INFO - PROCESS | 1668 | RSS: Main: 300437504 04:17:54 INFO - PROCESS | 1668 | 04:17:56 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:17:56 INFO - PROCESS | 1668 | RSS: Main: 305991680 04:17:56 INFO - PROCESS | 1668 | 04:17:58 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:17:59 INFO - PROCESS | 1668 | RSS: Main: 308240384 04:17:59 INFO - PROCESS | 1668 | 04:18:02 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:18:03 INFO - PROCESS | 1668 | RSS: Main: 315027456 04:18:03 INFO - PROCESS | 1668 | 04:18:05 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:18:06 INFO - PROCESS | 1668 | RSS: Main: 314204160 04:18:06 INFO - PROCESS | 1668 | 04:18:08 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49274/tests/kraken/driver.html?testName=ai-astar) 04:18:08 INFO - PROCESS | 1668 | RSS: Main: 358621184 04:18:08 INFO - PROCESS | 1668 | 04:18:08 INFO - PROCESS | 1668 | __start_tp_report 04:18:08 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 04:18:08 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 04:18:08 INFO - PROCESS | 1668 | |i|pagename|runs| 04:18:08 INFO - PROCESS | 1668 | |0;ai-astar;112;111;113;112;113;113;112;113;112;112 04:18:08 INFO - PROCESS | 1668 | |1;audio-beat-detection;149;115;113;161;140;138;112;113;143;114 04:18:08 INFO - PROCESS | 1668 | |2;audio-dft;181;183;249;237;185;184;182;180;183;184 04:18:08 INFO - PROCESS | 1668 | |3;audio-fft;79;58;59;87;61;59;59;58;82;59 04:18:08 INFO - PROCESS | 1668 | |4;audio-oscillator;87;71;90;70;68;69;92;69;69;89 04:18:08 INFO - PROCESS | 1668 | |5;imaging-gaussian-blur;87;86;88;86;134;129;94;86;88;85 04:18:08 INFO - PROCESS | 1668 | |6;imaging-darkroom;95;95;95;95;94;95;95;95;95;94 04:18:08 INFO - PROCESS | 1668 | |7;imaging-desaturate;85;87;83;83;84;83;84;81;85;69 04:18:08 INFO - PROCESS | 1668 | |8;json-parse-financial;56;51;53;52;57;53;53;54;54;55 04:18:08 INFO - PROCESS | 1668 | |9;json-stringify-tinderbox;72;68;75;74;72;70;70;70;75;72 04:18:08 INFO - PROCESS | 1668 | |10;stanford-crypto-aes;88;88;84;83;86;85;86;82;89;85 04:18:08 INFO - PROCESS | 1668 | |11;stanford-crypto-ccm;147;145;148;145;141;145;145;141;201;146 04:18:08 INFO - PROCESS | 1668 | |12;stanford-crypto-pbkdf2;173;171;169;172;172;169;171;169;171;169 04:18:08 INFO - PROCESS | 1668 | |13;stanford-crypto-sha256-iterative;68;69;69;68;68;69;68;68;69;68 04:18:08 INFO - PROCESS | 1668 | __end_tp_report 04:18:08 INFO - PROCESS | 1668 | __start_cc_report 04:18:08 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,1957 04:18:08 INFO - PROCESS | 1668 | __end_cc_report 04:18:08 INFO - PROCESS | 1668 | __startTimestamp1476098288495__endTimestamp 04:18:08 INFO - PROCESS | 1668 | 04:18:08 INFO - PROCESS | 1668 | ------- Summary: start ------- 04:18:08 INFO - PROCESS | 1668 | Number of tests: 14 04:18:08 INFO - PROCESS | 1668 | 04:18:08 INFO - PROCESS | 1668 | [#0] ai-astar Cycles:10 Average:112.30 Median:112.50 stddev:0.67 (0.6%) stddev-sans-first:0.71 04:18:08 INFO - PROCESS | 1668 | Values: 112.0 111.0 113.0 112.0 113.0 113.0 112.0 113.0 112.0 112.0 04:18:08 INFO - PROCESS | 1668 | 04:18:08 INFO - PROCESS | 1668 | [#1] audio-beat-detection Cycles:10 Average:129.80 Median:139.00 stddev:18.37 (13.2%) stddev-sans-first:18.12 04:18:08 INFO - PROCESS | 1668 | Values: 149.0 115.0 113.0 161.0 140.0 138.0 112.0 113.0 143.0 114.0 04:18:08 INFO - PROCESS | 1668 | 04:18:08 INFO - PROCESS | 1668 | [#2] audio-dft Cycles:10 Average:194.80 Median:184.00 stddev:25.60 (13.9%) stddev-sans-first:26.66 04:18:08 INFO - PROCESS | 1668 | Values: 181.0 183.0 249.0 237.0 185.0 184.0 182.0 180.0 183.0 184.0 04:18:08 INFO - PROCESS | 1668 | 04:18:08 INFO - PROCESS | 1668 | [#3] audio-fft Cycles:10 Average:66.10 Median:60.00 stddev:11.62 (19.4%) stddev-sans-first:11.35 04:18:08 INFO - PROCESS | 1668 | Values: 79.0 58.0 59.0 87.0 61.0 59.0 59.0 58.0 82.0 59.0 04:18:08 INFO - PROCESS | 1668 | 04:18:08 INFO - PROCESS | 1668 | [#4] audio-oscillator Cycles:10 Average:77.40 Median:79.00 stddev:10.51 (13.3%) stddev-sans-first:10.56 04:18:08 INFO - PROCESS | 1668 | Values: 87.0 71.0 90.0 70.0 68.0 69.0 92.0 69.0 69.0 89.0 04:18:08 INFO - PROCESS | 1668 | 04:18:08 INFO - PROCESS | 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:96.30 Median:86.50 stddev:18.76 (21.7%) stddev-sans-first:19.59 04:18:08 INFO - PROCESS | 1668 | Values: 87.0 86.0 88.0 86.0 134.0 129.0 94.0 86.0 88.0 85.0 04:18:08 INFO - PROCESS | 1668 | 04:18:08 INFO - PROCESS | 1668 | [#6] imaging-darkroom Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 04:18:08 INFO - PROCESS | 1668 | Values: 95.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 95.0 94.0 04:18:08 INFO - PROCESS | 1668 | 04:18:08 INFO - PROCESS | 1668 | [#7] imaging-desaturate Cycles:10 Average:82.40 Median:84.00 stddev:4.97 (5.9%) stddev-sans-first:5.18 04:18:08 INFO - PROCESS | 1668 | Values: 85.0 87.0 83.0 83.0 84.0 83.0 84.0 81.0 85.0 69.0 04:18:08 INFO - PROCESS | 1668 | 04:18:08 INFO - PROCESS | 1668 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:54.00 stddev:1.81 (3.4%) stddev-sans-first:1.74 04:18:08 INFO - PROCESS | 1668 | Values: 56.0 51.0 53.0 52.0 57.0 53.0 53.0 54.0 54.0 55.0 04:18:08 INFO - PROCESS | 1668 | 04:18:08 INFO - PROCESS | 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:71.80 Median:72.00 stddev:2.35 (3.3%) stddev-sans-first:2.49 04:18:08 INFO - PROCESS | 1668 | Values: 72.0 68.0 75.0 74.0 72.0 70.0 70.0 70.0 75.0 72.0 04:18:08 INFO - PROCESS | 1668 | 04:18:08 INFO - PROCESS | 1668 | [#10] stanford-crypto-aes Cycles:10 Average:85.60 Median:86.00 stddev:2.27 (2.6%) stddev-sans-first:2.24 04:18:08 INFO - PROCESS | 1668 | Values: 88.0 88.0 84.0 83.0 86.0 85.0 86.0 82.0 89.0 85.0 04:18:08 INFO - PROCESS | 1668 | 04:18:08 INFO - PROCESS | 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:150.40 Median:145.50 stddev:17.92 (12.3%) stddev-sans-first:18.97 04:18:08 INFO - PROCESS | 1668 | Values: 147.0 145.0 148.0 145.0 141.0 145.0 145.0 141.0 201.0 146.0 04:18:08 INFO - PROCESS | 1668 | 04:18:08 INFO - PROCESS | 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.60 Median:171.00 stddev:1.51 (0.9%) stddev-sans-first:1.32 04:18:08 INFO - PROCESS | 1668 | Values: 173.0 171.0 169.0 172.0 172.0 169.0 171.0 169.0 171.0 169.0 04:18:08 INFO - PROCESS | 1668 | 04:18:08 INFO - PROCESS | 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.40 Median:68.50 stddev:0.52 (0.8%) stddev-sans-first:0.53 04:18:08 INFO - PROCESS | 1668 | Values: 68.0 69.0 69.0 68.0 68.0 69.0 68.0 68.0 69.0 68.0 04:18:08 INFO - PROCESS | 1668 | -------- Summary: end -------- 04:18:08 INFO - PROCESS | 1668 | 04:18:09 INFO - TEST-INFO | 1668: exit 0 04:18:09 INFO - TEST-OK | kraken | took 58901ms 04:18:09 INFO - SUITE-END | took 1177s 04:18:09 INFO - Completed test suite (00:19:37) 04:18:09 INFO - javascript benchmark 04:18:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2784.5127102757015, 3315.37007968486, 3334.7661365294844, 3363.4282172030785, 3387.7120444762195, 5541.667499950006, 5670.659760414378, 5676.971615141929, 5721.971390143049, 5779.393163717807, 4616.28447590624, 5625.887482250363, 5647.265855438797, 5764.596478246524, 5782.739776710047, 5129.769160387791, 5130.6152038597065, 5187.429382767911, 5211.42674305826, 5222.477752224785, 5221.86945326368, 5306.92039619406, 5316.38861530925, 5360.865978350534, 5380.435054319299, 5093.159628661267, 5276.182191760283, 5287.629865909388, 5298.92051619226, 5367.597430192732, 5125.282460455539, 5155.9484405155845, 5172.250023746573, 5202.947970520284, 5222.190560463135, 4989.902221511262, 5090.287359769598, 5147.716875571842, 5228.163493840967, 5281.049411106, 4932.506749325074, 5706.543476521868, 5752.827415177551, 5753.827385178452, 5771.249737534135, 6032.245969253843, 6112.541559383064, 6253.74985000604, 6278.843028924268, 6295.905561416583, 6147.323794382614, 6152.138700581922, 6229.221347331583, 6231.844203894893, 6261.186045814061, 5781.797637082724, 5789.0, 5797.130430435384, 5799.130130480462, 5819.359870414251, 6132.263729246456, 6194.0, 6232.0963460298, 6258.311585725566, 6267.9373206268265, 5623.0721930880945, 5904.852378690567, 6061.848453788691, 6106.97642067191, 6144.539159563051, 6183.876322473615, 6211.409916058057, 6234.532410069172, 6241.594296370721, 6261.78083767066, 6072.36240194782, 6184.6598437085495, 6203.2556093269095, 6204.627722336674, 6226.377362263737, 6103.298046209379, 6105.906617299833, 6185.041318595625, 6218.253809542884, 6236.812895613139, 4912.361393018885, 4924.261360795909, 4927.137750893579, 4927.581155601814, 4964.429090654548, 4423.911521769546, 4436.134953684001, 4455.1535208310315, 4457.77711114443, 4518.525554816762, 5625.46558076982, 5645.096784514458, 5707.429257074259, 5710.057840456278, 5809.128630705428, 6182.474489668339, 6240.31356550779, 6264.467520260738, 6270.122182894398, 6272.090546870678, 5795.304563452412, 5803.216565763652, 5810.941890581124, 5823.56323275756, 5825.679587622722, 5248.816291429782, 5266.447023062523, 5346.492083252163, 5349.438308977579, 5354.170103633943, 5125.282460455464, 5139.408967968731, 6215.06773983906, 6259.280182779039, 6271.059341098871, 6208.379162083756, 6208.534359923115, 6224.875502490016, 6229.065640154013, 6242.625442473466, 6189.288231853395, 6218.782342617987, 6228.501719862309, 6237.937620623736, 6248.281447633399, 6044.365341639241, 6058.878822423615, 6135.509159267337, 6137.447629713347, 6143.017117261216, 6113.962834745988, 6137.631742095488, 6144.003198960445, 6145.324014358506, 6156.846078847885, 6020.638761674313, 6103.2677270967215, 6125.050617154348, 6135.355787642234, 6147.385261473817, 5943.405659434022, 6011.248593925759, 6043.697815109139, 6047.425494578008, 6123.908141377794, 5956.702164891652, 6010.759569617167, 6035.155078289131, 6038.4867286279405, 6039.516838652809, 5979.402059793986, 6062.151298818081, 6115.480184184395, 6115.990861507801, 6122.051082082285, 5951.1073338999495, 5959.791407300723, 5988.041913293852, 5989.2812862456785, 5999.010163323002], "value": 5660.6975930218905, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [462.585985542939, 527.3592585009213, 528.363322196753, 528.7144941731464, 553.363631823403, 32818.835905820495, 40415.39376909345, 41773.79113104434, 41998.79000604993, 43881.780591097, 41618.58381416185, 42423.93940151498, 42666.786666066626, 42701.57298427023, 42739.0, 8843.125984055176, 9816.754581135458, 10016.348937319088, 10025.248106392013, 10039.096481316716, 8890.822183556325, 9009.459432434074, 9021.594028268712, 9025.548722563879, 12071.0, 12064.819027714591, 12429.875701243007, 12434.378281085954, 12447.190932589398, 12456.252624842538, 12399.19405238661, 12480.0, 12490.875091249152, 12499.312537810416, 12505.624831255076, 8575.485470871768, 8811.823763524693, 10264.538095785632, 11799.351035693033, 12019.21875078117, 40212.79893600543, 42459.1508169838, 42475.57524424747, 42652.57347426548, 42657.146857062995, 3390.5761779777526, 3621.6559426854406, 3695.558732094485, 3961.9405708914387, 4070.14526949341, 31123.105749971503, 31909.680903190903, 32301.192470188198, 32340.353192936243, 32352.676473235202, 3179.952300715491, 3297.7526685498683, 3316.1709572606846, 3377.2738861144726, 3380.678835510623, 492546.0, 492889.0, 494543.0, 495195.0, 495206.9999999964, 61231.0, 62106.37893621096, 62116.689416553534, 62139.0, 62256.0, 65006.0, 65162.67418662876, 65169.34830651633, 65180.0, 65321.0, 16014.519564413085, 16266.75599866003, 16304.67390652204, 16315.265813038443, 16334.101624410772, 12628.747425051632, 12719.236845789283, 12725.61823145307, 12729.10896237273, 12733.426995785212, 15605.765913511306, 15971.44099956496, 15987.120708361152, 17835.73246401305, 18279.451616451526, 5348.438413966555, 5384.488473595002, 6375.330590287953, 6412.3267056959285, 6447.033654735351, 730.6565914020401, 970.1850445625709, 1025.5795123999274, 1094.8686157661132, 1099.3623698254994, 24186.153484627954, 28469.715302846707, 30328.090157295315, 30973.69026309753, 30999.915002974787, 1043.155044414027, 1073.7208325835184, 1073.8442925775719, 1120.4341807387218, 1152.03229287399, 15769.05385676863, 15786.605334866721, 15890.841091589167, 16160.838391615933, 16199.43301984425, 642082.789586049, 646833.0, 647650.7617461883, 647685.0, 647782.0, 41466.963325917095, 45066.54933450689, 51784.0, 57733.42266577364, 59075.704621476616, 1227.533537255837, 1320.735852829438, 1348.7707089794758, 1351.2297990145523, 1359.0350850895777, 30750.231244219074, 31804.204894877814, 31934.520982184822, 32615.80299743041, 32636.347273054882, 51195.0, 51574.74212628913, 51794.22308665298, 51845.70385740387, 51851.74074129756, 799504.0, 813647.0, 814070.0, 815040.0, 816693.0, 25615.103471378414, 25779.355516112246, 25864.0, 25879.353016174748, 25896.741032589434, 45415.54584454245, 45847.541524585664, 46541.0, 47152.0, 47258.52741472541, 29535.704642954155, 37594.624053759115, 38020.04949876275, 38994.80502597469, 39156.80421597988, 37047.44428833516, 38156.0, 38358.42462363011, 39243.80378098205, 39819.40270895881, 37456.2508749818, 37479.063023424635, 38388.61611383851, 38389.80805096068, 38397.008388909264, 30069.54895676523, 31904.521432178073, 33495.665043349254, 34126.488102677984, 34366.48450273198, 24427.877860610584, 24776.876115619307, 25169.993200271783, 25361.0, 25760.742392576583], "value": 20402.4269171864, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.69782066843493, 369.07730673316706, 370.3944051475836, 379.965803077723, 400.37341560457884, 15259.168899732034, 15356.0, 18154.27382904684, 19644.607107857835, 19845.0, 15565.455209067684, 19448.513787155327, 19665.803341966577, 19720.40838774839, 19745.605087898235, 5804.686214991697, 5881.911771323434, 6036.3661815509395, 6115.205023346981, 6119.602225855305, 4137.81379837908, 4375.365571992059, 5966.254218222723, 6082.787102451415, 6090.086487026959, 4404.031113155117, 6738.494612904052, 6745.1905771307265, 6751.898721519181, 6752.358525940028, 6454.903176452357, 6687.465002799764, 6757.898631520531, 6759.560628559152, 6763.966180169067, 5822.33043200029, 5826.359100498942, 5830.125481177814, 5830.825075247707, 5837.620554663933, 22282.0, 24768.752312476823, 24858.875705621354, 24861.378465538324, 25024.624630630555, 2198.4503874031493, 2251.0995601759264, 2251.7072780538588, 2396.8681722505257, 2405.1341517053847, 14638.560843174722, 15202.391904323813, 15388.769168462482, 15539.0, 15919.965202261816, 1825.4067428085925, 1831.8168183181708, 1842.0973722875694, 1885.3212843376373, 1949.9567731263849, 91542.0, 123229.38385308016, 123946.0, 124265.75734242632, 125681.37159314145, 10172.84740728875, 10769.83845242322, 10936.453177341102, 11151.776964460827, 11562.710932226762, 13150.539731109366, 13655.975801814904, 13663.931680341733, 13728.931355343359, 14387.208703521406, 7021.22766495685, 8181.0, 8292.419530632798, 8422.326213902874, 8475.194856488624, 6794.592324460449, 8086.0, 8191.0, 8345.707900223462, 8462.703805366782, 4402.603765661106, 4460.620847228022, 4479.3057076153245, 4540.228161212602, 4594.1730488512385, 1565.506865337415, 1931.8357939575294, 1933.8936358500137, 1945.8735182213395, 1955.162147581269, 834.2616784146074, 856.6616186606255, 885.7829831691276, 907.7563737679421, 971.8639390485338, 19482.733622314503, 19509.51226219356, 20520.589588208153, 20803.271885483937, 20841.062152203187, 861.6208868098142, 953.3279038277998, 962.153305091515, 974.4056125763278, 992.8411454167299, 10309.278350515391, 10438.739031524121, 10945.398003109745, 10983.340999540053, 11018.228723989243, 599391.0, 603546.0, 612556.0, 625148.0, 627061.0, 134329.65670343366, 135444.0, 135712.0, 140059.0, 141529.0, 1008.2589296866797, 1021.4841505039907, 1028.7428142964259, 1040.7970445763003, 1041.614602597044, 17009.574760631083, 17935.385815276688, 18051.277948881896, 18626.534336641693, 18687.719684204476, 55193.0, 55286.0, 55314.06400775954, 56396.0, 56492.0, 58791.70604146952, 75027.49945001179, 78090.0, 78898.0, 80314.0, 7223.0, 7680.231976802275, 7877.527348359117, 7932.4447288691545, 8180.713675021346, 9157.0, 11469.08247340194, 11914.40427978615, 12364.814527781911, 12706.30115343665, 10674.57301707923, 10755.623553175601, 11041.558337666404, 11159.163062770162, 11237.38194399316, 9163.808704868325, 10399.48002599882, 10779.67660970172, 11053.723656908642, 11244.66266012021, 10845.837312440162, 11002.579432525152, 11315.717107072389, 11364.659060228207, 11386.886131138914, 7999.840003199787, 8074.39442041861, 8126.024877014796, 8177.877331839908, 8223.917760822314, 9728.902710973083, 10736.409497477714, 10998.0, 11069.889301106887, 11186.608468703556], "value": 9527.865651505286, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1344.7646661834176, 1377.1943413103334, 1551.58107311026, 1703.5400441880693, 1882.9905850470755, 7825.41309401795, 8284.917150828498, 8729.127087291276, 8965.32760042996, 8983.460992340464, 8709.390342676015, 8806.823863522726, 8815.559222038904, 8816.382853200263, 8838.86741698875, 2306.3772781349066, 2335.1476711000446, 2337.637666161748, 2340.33300509354, 2340.707411573553, 2297.7242730872317, 2332.4518738096535, 2345.9648105278434, 2347.436615212354, 2348.5420343033115, 2294.942626434344, 2318.7217533895955, 2318.8144948404088, 2325.1164557468137, 2332.183735692511, 2283.5204607032542, 2304.953900921989, 2308.088305119485, 2313.7339205991357, 2315.6063469210276, 1794.6590147871862, 2294.9196778112855, 2298.195631528968, 2303.573838839803, 2305.0318866076286, 8748.387612867102, 8749.081346458594, 8856.778580535474, 8865.911340886572, 8868.201861832466, 4350.608445239944, 5164.14791559396, 5328.17413300939, 5331.2003199519995, 5371.919421208685, 3518.736094792901, 3600.063983364318, 3651.8174091295327, 3681.9447708284397, 3690.538682664667, 1022.4734261855151, 1056.799208150449, 1057.2916146182076, 1076.5532304093792, 1079.034264333425, 11547.595834145848, 11608.535658573646, 11657.300561966224, 11688.0, 11693.649190524298, 4355.716878402894, 4379.4525684289465, 4560.042391097841, 4560.977195114003, 4598.126355992351, 9101.954490227507, 9115.134062264075, 9115.99724030356, 9118.908810911806, 9121.954390228138, 5230.137027390438, 5234.0, 5234.581233501311, 5244.0, 5252.816151434681, 4335.501417337046, 4388.934165987513, 4561.885952851205, 4562.292844609091, 4889.755512224375, 3713.405855063177, 3716.0524066362905, 3755.6244375562223, 3764.134249122717, 3767.51022367096, 1120.1542835159403, 1123.0398009701587, 1123.4102096399456, 1133.5919069135105, 1138.0781566930812, 768.8385439057748, 875.9956200218958, 900.1339247930156, 921.3780698028804, 930.4045410937006, 5777.24895763548, 5803.245578074823, 6027.276726792813, 6115.205023347025, 6245.906311405332, 806.2421323955463, 944.7354740672623, 945.6170251048198, 955.5843208204453, 999.14073896449, 3902.297586434412, 3944.2111577684004, 3946.309395855771, 4054.3107671695884, 4065.5527891931865, 10354.637587684394, 10728.0, 10837.0, 10876.18428617841, 10890.183236257313, 10825.0, 10833.370820774971, 10849.186311026704, 10864.804871464132, 10865.348079115278, 8166.265036146775, 8304.501729896225, 8308.919840420827, 8335.916640833635, 8357.623906924204, 5606.971965140148, 5804.506616937439, 5886.52907767369, 5985.162077309094, 6007.369226231358, 10004.899951000689, 10180.949095254477, 10229.48852557384, 10264.743381415525, 10305.948470257901, 9268.026857180168, 9704.0, 10181.541830617492, 10202.38785672862, 10319.122874555447, 7053.823654408681, 7221.566705997657, 7345.6694448751, 7497.850042999218, 7580.241975802375, 8249.876251856107, 8269.710560130367, 8278.503289802631, 8355.331573473986, 8356.832863342577, 7192.244814294424, 7393.260673932564, 7453.850922981402, 7501.399888009056, 7519.248075192437, 6199.504039676905, 7417.740379086922, 7463.888041679271, 7507.249275072449, 7540.9622951884885, 6917.27368626287, 6961.79114626562, 7035.648217588998, 7036.683349249193, 7039.753608623879, 4431.534688857752, 4781.784819683053, 4998.500149985118, 5017.899642007213, 5058.64589478733, 4402.647788176874, 4988.152014157529, 5096.490350964874, 5115.87210319745, 5225.1378522543355], "value": 4521.218301362154, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [269.0851106238788, 289.8029340048767, 297.8064258232149, 310.21051424125596, 313.3372916282061, 4438.778061096944, 5319.840404787853, 5335.386430560487, 5337.786488540463, 5343.706096164709, 4111.71218014739, 4692.178868697973, 5125.436202017784, 5143.81996630118, 5271.815486457966, 4012.638862502374, 4045.9797701011453, 4052.8378864845517, 4056.91069099414, 4059.8579049733266, 3510.8063258492107, 4024.350016493224, 4037.6567991720594, 4046.5751096134923, 4067.8169482373364, 3223.339215460837, 4000.919981600366, 4004.599540046001, 4034.3055916514986, 4044.5550989391145, 3169.1284896653374, 3481.8781342652887, 4002.319605667029, 4006.2388146252265, 4060.959390406117, 3025.1680787783316, 3396.6603339666085, 3459.97930610468, 3476.418229705478, 3515.4726790981304, 5092.490750924915, 5100.183970564692, 5126.461721519224, 5134.383873935151, 5168.475299786534, 4348.5216626171095, 4568.931466028012, 4643.489216186251, 4650.163185541726, 4660.856006237135, 5562.7774889004395, 5564.165375193713, 5581.469760372799, 5588.479258067432, 5595.272614560122, 3839.212961342918, 3858.013123241533, 3874.5156855393075, 3882.2429626222897, 3889.863854765098, 5030.547250747451, 5132.666376685504, 5568.303962004749, 5669.918375472833, 5750.166225897222, 3264.689854463822, 3351.815650139217, 3879.670228030649, 3918.431827385013, 3928.4303775952326, 3965.0483883867664, 3984.4820173377857, 3996.5429903134163, 3996.7402118862183, 4016.477857878457, 4169.770662613585, 4535.682502224879, 4604.493505714368, 4624.329472226508, 4643.8374656886845, 4593.989322349078, 4647.535246475325, 5128.410232823198, 5444.8094316698725, 5601.719914004283, 1175.8824117588172, 1176.817593273044, 1311.4754098360731, 1338.772408690525, 1341.1885809085559, 793.2622660925394, 823.8269963307653, 852.8336974289954, 857.4651374040523, 864.9610767515477, 3998.860324807416, 4632.791524381343, 5231.424543300234, 5258.553022993012, 5304.814331498456, 5642.4357564244065, 5647.802326918538, 5697.914531282034, 5702.889930725658, 5706.403586368193, 4172.7913604319665, 4966.157995953916, 5211.140161873249, 5233.712145832016, 5243.239730239094, 2258.808001319906, 2808.9016884408948, 2964.52567589189, 2985.014965048262, 2997.880084796627, 5583.817224182178, 5876.9118463223085, 5877.970610147007, 5879.647221166658, 5886.8822623547285, 4608.5621865922685, 5446.264754258124, 5751.964646363611, 5772.422757724278, 5809.564282678816, 5264.473302742238, 5764.213093940892, 5820.24336836217, 5834.154047663065, 5855.954903450182, 5226.425093239664, 5386.542143917811, 5522.696251706195, 5639.392773059658, 5794.710264486843, 4806.423229212517, 4990.900181996412, 5589.077802162597, 5778.653280803165, 5815.2149459822385, 5979.461848433488, 5997.220361352996, 6006.399360063959, 6008.279006519246, 6027.879442411215, 3340.4154273002614, 3563.465480177994, 3836.5204349456317, 3900.1614652849776, 3906.5312162540677, 5759.51044161251, 5780.017397042597, 5784.710764461676, 5789.507891829242, 5801.361850196559, 4832.901289139851, 5352.9732351338, 5565.777368905198, 5586.832395028156, 5617.865211219711, 4552.453705555355, 5294.338207724035, 5595.580331475075, 5604.747786349705, 5632.915506267491, 5266.473352664703, 5510.752016159202, 5514.200440936122, 5522.2544956430365, 5529.4470552944385, 5500.0, 5540.445955404427, 5546.195801608664, 5548.69482178484, 5588.624834445426, 4590.862274131781, 5507.421720719427, 5508.889822203613, 5520.227168196536, 5580.21876937221], "value": 4046.2910764823364, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [696.2271878215182, 714.1180641907243, 762.3710438887919, 798.1220657276999, 809.8663720486119, 15656.686866262668, 16056.357745690173, 16057.197140142975, 16065.357385704587, 16110.758338624915, 14233.288335583231, 14363.64090897728, 14379.137251764876, 14380.065295755769, 14392.208428536427, 661.6856992928344, 688.8622275544886, 692.2247083266723, 815.7552734179752, 824.2293455619, 844.945078569894, 852.5524099847598, 852.6674596907211, 854.4531499840107, 854.6154230596226, 886.4238245140646, 904.2178515584005, 905.2079430498314, 905.3707673167152, 906.0893801729253, 899.9865002024975, 900.3832374823266, 901.612306708112, 901.8421776189206, 903.8734577159203, 514.1927174336294, 535.7776522743058, 635.1140159477549, 667.4693618573198, 668.3750693101965, 14686.412543498247, 15217.467388641455, 15217.6195595111, 15220.162891040989, 15233.847661523354, 13350.399232034582, 13693.383797729026, 13762.064905840385, 13785.793213101811, 14038.578842634737, 11967.581134660331, 12221.93889030558, 12341.5063397464, 12718.364081795873, 12805.807912881315, 4451.443569553806, 4479.61923236522, 4546.568076032805, 4558.247889098295, 4709.081729062834, 115880.42059789647, 120135.0, 121815.3909230457, 123106.0, 127484.0, 10564.601479700048, 10689.30220946199, 10830.644399476876, 10968.890311096946, 11027.724306892393, 21188.894055529625, 21222.575548488945, 21306.04122814477, 21332.573348532944, 21348.573028539344, 7577.393808495307, 7581.336571102885, 7608.8478230435085, 7674.654640541189, 7840.098388685375, 4116.773577453209, 4176.352665336878, 4212.326027835532, 4237.237297286456, 4306.547812479707, 3308.801471911693, 3419.26485805553, 3420.110771199506, 3423.5891692997, 3425.7088147507243, 571.3914680864866, 586.8092869817326, 593.0570393074945, 593.6794131169206, 595.5116804220584, 575.2062154227224, 586.9794557190478, 588.2617315269395, 600.5585894367637, 613.0650757594703, 7931.365490760725, 8393.0, 8698.91301086981, 8737.0, 8999.0, 523.359650562911, 598.9820305390846, 599.8410421238375, 615.187951903483, 626.7179769103831, 4046.089629833264, 4127.4634297541725, 4157.8960525987095, 4211.284081706117, 4235.597618226264, 162011.27932324097, 162797.0, 163509.0, 169324.3067569333, 171583.0, 109437.45281273543, 139232.0, 139733.30133349472, 143299.0, 144011.5598844019, 104132.95867041383, 104626.0, 104854.0, 105331.0, 108090.37864432197, 8352.33623966028, 8353.498790072616, 8501.489910605384, 8816.51509167002, 9227.123423274777, 42814.57185428106, 51121.0, 55719.72140139274, 57165.42834571767, 58081.70959145177, 75591.11022224488, 80375.0, 81858.0, 82862.0, 84294.57852710698, 13142.080054396101, 13315.001374897116, 13431.932840336127, 13470.797938031134, 13726.656833578842, 14708.926455367655, 14831.777523337374, 14931.85068149348, 15264.465743699362, 15526.0, 13468.0, 13486.460541578619, 13913.930430348188, 13943.721125577635, 14290.499832505813, 13484.190948543119, 13670.726585468434, 13794.517191898236, 13985.370658320604, 14263.786043209568, 13628.727425451238, 13864.168149911038, 13922.72154556883, 13923.860761392256, 14079.436822526985, 10988.615398461016, 11283.492242848928, 11390.7087552115, 11394.03150732197, 12090.274583525017, 8556.903558627928, 10473.581056757948, 10807.0, 10981.890181098086, 11366.715832104264], "value": 7694.843648283766, "unit": "score"}], "name": "dromaeo_css", "value": 7328.548078854154, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 111.0, 113.0, 112.0, 113.0, 113.0, 112.0, 113.0, 112.0, 112.0], "value": 112.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 115.0, 113.0, 161.0, 140.0, 138.0, 112.0, 113.0, 143.0, 114.0], "value": 129.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 183.0, 249.0, 237.0, 185.0, 184.0, 182.0, 180.0, 183.0, 184.0], "value": 194.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 59.0, 87.0, 61.0, 59.0, 59.0, 58.0, 82.0, 59.0], "value": 66.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 90.0, 70.0, 68.0, 69.0, 92.0, 69.0, 69.0, 89.0], "value": 77.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 88.0, 86.0, 134.0, 129.0, 94.0, 86.0, 88.0, 85.0], "value": 96.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 83.0, 83.0, 84.0, 83.0, 84.0, 81.0, 85.0, 69.0], "value": 82.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 51.0, 53.0, 52.0, 57.0, 53.0, 53.0, 54.0, 54.0, 55.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 68.0, 75.0, 74.0, 72.0, 70.0, 70.0, 70.0, 75.0, 72.0], "value": 71.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 84.0, 83.0, 86.0, 85.0, 86.0, 82.0, 89.0, 85.0], "value": 85.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 145.0, 148.0, 145.0, 141.0, 145.0, 145.0, 141.0, 201.0, 146.0], "value": 150.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 171.0, 169.0, 172.0, 172.0, 169.0, 171.0, 169.0, 171.0, 169.0], "value": 170.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 69.0, 68.0, 68.0, 69.0, 68.0, 68.0, 69.0, 68.0], "value": 68.4, "unit": "score"}], "name": "kraken", "value": 1454.4999999999998, "alertThreshold": 2.0}]} 04:18:09 INFO - Return code: 0 04:18:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:18:09 INFO - # TBPL SUCCESS # 04:18:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:18:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:18:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:18:09 INFO - 2016-10-10 04:18:09.823 screenresolution[1672:9844] starting screenresolution argv=screenresolution get 04:18:09 INFO - 2016-10-10 04:18:09.826 screenresolution[1672:9844] Display 0: 1600x1200x32@60 04:18:09 INFO - 2016-10-10 04:18:09.832 screenresolution[1673:9847] starting screenresolution argv=screenresolution list 04:18:09 INFO - Available Modes on Display 0 04:18:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:18:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:18:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:18:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:18:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:18:10 INFO - Intel Iris: 04:18:10 INFO - Chipset Model: Intel Iris 04:18:10 INFO - Type: GPU 04:18:10 INFO - Bus: Built-In 04:18:10 INFO - VRAM (Dynamic, Max): 1536 MB 04:18:10 INFO - Vendor: Intel (0x8086) 04:18:10 INFO - Device ID: 0x0a2e 04:18:10 INFO - Revision ID: 0x0009 04:18:10 INFO - Displays: 04:18:10 INFO - Display: 04:18:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:18:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:18:10 INFO - Display Serial Number: 1600x1200 60 04:18:10 INFO - Main Display: Yes 04:18:10 INFO - Mirror: Off 04:18:10 INFO - Online: Yes 04:18:10 INFO - Rotation: Supported 04:18:10 INFO - Return code: 0 04:18:10 INFO - Running post-action listener: _resource_record_post_action 04:18:10 INFO - [mozharness: 2016-10-10 11:18:10.072244Z] Finished run-tests step (success) 04:18:10 INFO - Running post-run listener: _resource_record_post_run 04:18:10 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 32029696; Write bytes: 704458752; Read time: 202; Write time: 1947 04:18:10 INFO - TinderboxPrint: CPU usage
53.4% 04:18:10 INFO - TinderboxPrint: I/O read bytes / time
32,029,696 / 202 04:18:10 INFO - TinderboxPrint: I/O write bytes / time
704,458,752 / 1,947 04:18:10 INFO - TinderboxPrint: CPU idle
2,262.2 (46.6%) 04:18:10 INFO - TinderboxPrint: CPU system
973.7 (20.1%) 04:18:10 INFO - TinderboxPrint: CPU user
1,613.8 (33.3%) 04:18:10 INFO - TinderboxPrint: Swap in / out
435,609,600 / 0 04:18:10 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 402182656; Write bytes: 394915840; Read time: 29767; Write time: 805 04:18:10 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 30006272; Write bytes: 292532224; Read time: 184; Write time: 1100 04:18:10 INFO - Running post-run listener: _upload_blobber_files 04:18:10 INFO - Blob upload gear active. 04:18:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:18:10 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 04:18:10 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:18:10 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:18:10 INFO - (blobuploader) - INFO - Open directory for files ... 04:18:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:18:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:18:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:18:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:18:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:18:12 INFO - (blobuploader) - INFO - Done attempting. 04:18:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:18:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:18:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:18:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:18:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:18:13 INFO - (blobuploader) - INFO - Done attempting. 04:18:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:18:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:18:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:18:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:18:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:18:16 INFO - (blobuploader) - INFO - Done attempting. 04:18:16 INFO - (blobuploader) - INFO - Iteration through files over. 04:18:16 INFO - Return code: 0 04:18:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:18:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:18:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/87929d069c6c9075b2a194b6eba5bd957bb9385f474e94f78fab81346d0f76ae4717d77af1e14b888a8b5584344cddf5f5d1934c3a4ea6f4616c2025f2710bf3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/562e9058b2520fd5ccb495c0ba379f95f0f642d0d213df2f3fad8c92db9391be90cad78f36c550c4c3b6ff1d892d80037911ea70d1be592b71cf579a056fa524", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2b841d3e9d2028688b2d20da0ef0fbd22c4c303e6fa28ed252a7e44ef44e030fa4c81891d22cffc6f4c245bd344dfb328571cd0b0de38f364cf1b6957450ac72"} 04:18:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:18:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:18:16 INFO - Contents: 04:18:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/87929d069c6c9075b2a194b6eba5bd957bb9385f474e94f78fab81346d0f76ae4717d77af1e14b888a8b5584344cddf5f5d1934c3a4ea6f4616c2025f2710bf3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/562e9058b2520fd5ccb495c0ba379f95f0f642d0d213df2f3fad8c92db9391be90cad78f36c550c4c3b6ff1d892d80037911ea70d1be592b71cf579a056fa524", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2b841d3e9d2028688b2d20da0ef0fbd22c4c303e6fa28ed252a7e44ef44e030fa4c81891d22cffc6f4c245bd344dfb328571cd0b0de38f364cf1b6957450ac72"} 04:18:16 INFO - Running post-run listener: copy_logs_to_upload_dir 04:18:16 INFO - Copying logs to upload dir... 04:18:16 INFO - mkdir: /builds/slave/test/build/upload/logs 04:18:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1348.103355 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 28 secs) (at 2016-10-10 04:18:16.447530) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-10 04:18:16.450968) ========= 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.SZIZxdpgRo/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.mnljo94mzn/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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/Autoland/sha512/87929d069c6c9075b2a194b6eba5bd957bb9385f474e94f78fab81346d0f76ae4717d77af1e14b888a8b5584344cddf5f5d1934c3a4ea6f4616c2025f2710bf3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/562e9058b2520fd5ccb495c0ba379f95f0f642d0d213df2f3fad8c92db9391be90cad78f36c550c4c3b6ff1d892d80037911ea70d1be592b71cf579a056fa524", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2b841d3e9d2028688b2d20da0ef0fbd22c4c303e6fa28ed252a7e44ef44e030fa4c81891d22cffc6f4c245bd344dfb328571cd0b0de38f364cf1b6957450ac72"} build_url:https://queue.taskcluster.net/v1/task/TjKfoploQnejlkXZvpUb0g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010970 build_url: 'https://queue.taskcluster.net/v1/task/TjKfoploQnejlkXZvpUb0g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/87929d069c6c9075b2a194b6eba5bd957bb9385f474e94f78fab81346d0f76ae4717d77af1e14b888a8b5584344cddf5f5d1934c3a4ea6f4616c2025f2710bf3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/562e9058b2520fd5ccb495c0ba379f95f0f642d0d213df2f3fad8c92db9391be90cad78f36c550c4c3b6ff1d892d80037911ea70d1be592b71cf579a056fa524", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2b841d3e9d2028688b2d20da0ef0fbd22c4c303e6fa28ed252a7e44ef44e030fa4c81891d22cffc6f4c245bd344dfb328571cd0b0de38f364cf1b6957450ac72"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-10 04:18:16.482810) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 04:18:16.483134) ========= 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.SZIZxdpgRo/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.mnljo94mzn/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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.005163 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 04:18:16.531552) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-10 04:18:16.531869) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-10 04:18:16.532200) ========= ========= Total master_lag: 0.17 =========