builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0047 starttime: 1475104166.79 results: success (0) buildid: 20160928140620 builduid: 471f35a04b3d41289a0151dd4735d881 revision: d2435513f1356962445a7704b8d8b0d911b9aac3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-28 16:09:26.789376) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-28 16:09:26.789804) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-28 16:09:26.790097) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-28 16:09:26.817667) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 16:09:26.817999) ========= 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.JCZsHzRkHE/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.3GDYsRq0kk/Listeners TMPDIR=/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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-09-28 16:09:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.5M=0.001s 2016-09-28 16:09:26 (14.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.124525 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 16:09:26.964759) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 16:09:26.967130) ========= 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.JCZsHzRkHE/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.3GDYsRq0kk/Listeners TMPDIR=/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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.028677 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 16:09:27.015721) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-28 16:09:27.018092) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev d2435513f1356962445a7704b8d8b0d911b9aac3 --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 d2435513f1356962445a7704b8d8b0d911b9aac3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.JCZsHzRkHE/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.3GDYsRq0kk/Listeners TMPDIR=/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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-09-28 16:09:27,094 truncating revision to first 12 chars 2016-09-28 16:09:27,094 Setting DEBUG logging. 2016-09-28 16:09:27,094 attempt 1/10 2016-09-28 16:09:27,094 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/d2435513f135?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-28 16:09:27,864 unpacking tar archive at: autoland-d2435513f135/testing/mozharness/ program finished with exit code 0 elapsedTime=1.036708 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-28 16:09:28.078985) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-28 16:09:28.079307) ========= script_repo_revision: d2435513f1356962445a7704b8d8b0d911b9aac3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-28 16:09:28.079694) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-28 16:09:28.079969) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-28 16:09:28.097661) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-09-28 16:09:28.097938) ========= /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.JCZsHzRkHE/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.3GDYsRq0kk/Listeners TMPDIR=/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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 16:09:28 INFO - MultiFileLogger online at 20160928 16:09:28 in /builds/slave/test 16:09:28 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 16:09:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:09:28 INFO - {'append_to_log': False, 16:09:28 INFO - 'base_work_dir': '/builds/slave/test', 16:09:28 INFO - 'blob_upload_branch': 'Autoland', 16:09:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:09:28 INFO - 'branch': 'Autoland', 16:09:28 INFO - 'buildbot_json_path': 'buildprops.json', 16:09:28 INFO - 'config_files': ('talos/mac_config.py',), 16:09:28 INFO - 'default_actions': ('clobber', 16:09:28 INFO - 'read-buildbot-config', 16:09:28 INFO - 'download-and-extract', 16:09:28 INFO - 'populate-webroot', 16:09:28 INFO - 'create-virtualenv', 16:09:28 INFO - 'install', 16:09:28 INFO - 'run-tests'), 16:09:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:09:28 INFO - 'download_minidump_stackwalk': True, 16:09:28 INFO - 'download_symbols': 'ondemand', 16:09:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:09:28 INFO - 'tooltool.py': '/tools/tooltool.py', 16:09:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:09:28 INFO - '/tools/misc-python/virtualenv.py')}, 16:09:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:09:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:09:28 INFO - 'installer_path': 'installer.exe', 16:09:28 INFO - 'log_level': 'info', 16:09:28 INFO - 'log_name': 'talos', 16:09:28 INFO - 'log_to_console': True, 16:09:28 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:09:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:09:28 INFO - 'opt_config_files': (), 16:09:28 INFO - 'pip_index': False, 16:09:28 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:09:28 INFO - 'cmd': ('bash', 16:09:28 INFO - '-c', 16:09:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:09:28 INFO - 'enabled': True, 16:09:28 INFO - 'halt_on_failure': False, 16:09:28 INFO - 'name': 'check_screen_resolution'},), 16:09:28 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:09:28 INFO - 'cmd': ('bash', 16:09:28 INFO - '-c', 16:09:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:09:28 INFO - 'enabled': True, 16:09:28 INFO - 'halt_on_failure': False, 16:09:28 INFO - 'name': 'check_screen_resolution'},), 16:09:28 INFO - 'run_cmd_checks_enabled': True, 16:09:28 INFO - 'sps_profile': False, 16:09:28 INFO - 'sps_profile_interval': 0, 16:09:28 INFO - 'suite': 'dromaeojs', 16:09:28 INFO - 'system_bits': '32', 16:09:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:09:28 INFO - 'title': 't-yosemite-r7-0047', 16:09:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:09:28 INFO - 'use_talos_json': True, 16:09:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:09:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:09:28 INFO - 'work_dir': 'build'} 16:09:28 INFO - [mozharness: 2016-09-28 23:09:28.298774Z] Running clobber step. 16:09:28 INFO - Running pre-action listener: _resource_record_pre_action 16:09:28 INFO - Running main action method: clobber 16:09:28 INFO - rmtree: /builds/slave/test/build 16:09:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:09:30 INFO - Running post-action listener: _resource_record_post_action 16:09:30 INFO - [mozharness: 2016-09-28 23:09:30.423571Z] Finished clobber step (success) 16:09:30 INFO - [mozharness: 2016-09-28 23:09:30.423686Z] Running read-buildbot-config step. 16:09:30 INFO - Running pre-action listener: _resource_record_pre_action 16:09:30 INFO - Running main action method: read_buildbot_config 16:09:30 INFO - Using buildbot properties: 16:09:30 INFO - { 16:09:30 INFO - "project": "", 16:09:30 INFO - "product": "firefox", 16:09:30 INFO - "script_repo_revision": "production", 16:09:30 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 16:09:30 INFO - "repository": "", 16:09:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 16:09:30 INFO - "buildid": "20160928140620", 16:09:30 INFO - "pgo_build": "False", 16:09:30 INFO - "basedir": "/builds/slave/test", 16:09:30 INFO - "buildnumber": 309, 16:09:30 INFO - "slavename": "t-yosemite-r7-0047", 16:09:30 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 16:09:30 INFO - "platform": "yosemite_r7", 16:09:30 INFO - "scheduler": "tests-autoland-macosx64-talos", 16:09:30 INFO - "branch": "autoland", 16:09:30 INFO - "repo_path": "integration/autoland", 16:09:30 INFO - "revision": "d2435513f1356962445a7704b8d8b0d911b9aac3", 16:09:30 INFO - "stage_platform": "macosx64", 16:09:30 INFO - "builduid": "471f35a04b3d41289a0151dd4735d881", 16:09:30 INFO - "slavebuilddir": "test" 16:09:30 INFO - } 16:09:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/S9SZ-svfRRWEyQFFy_pX8Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 16:09:30 INFO - Running post-action listener: _resource_record_post_action 16:09:30 INFO - [mozharness: 2016-09-28 23:09:30.426915Z] Finished read-buildbot-config step (success) 16:09:30 INFO - [mozharness: 2016-09-28 23:09:30.427037Z] Running download-and-extract step. 16:09:30 INFO - Running pre-action listener: _resource_record_pre_action 16:09:30 INFO - Running main action method: download_and_extract 16:09:30 INFO - mkdir: /builds/slave/test/build/tests 16:09:30 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')]} 16:09:30 INFO - trying https://queue.taskcluster.net/v1/task/S9SZ-svfRRWEyQFFy_pX8Q/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 16:09:30 INFO - Downloading https://queue.taskcluster.net/v1/task/S9SZ-svfRRWEyQFFy_pX8Q/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 16:09:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S9SZ-svfRRWEyQFFy_pX8Q/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 16:09:31 INFO - Downloaded 1199 bytes. 16:09:31 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 16:09:31 INFO - Using the following test package requirements: 16:09:31 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 16:09:31 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:09:31 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 16:09:31 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:09:31 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 16:09:31 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:09:31 INFO - u'jsshell-mac.zip'], 16:09:31 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:09:31 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 16:09:31 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 16:09:31 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:09:31 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 16:09:31 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:09:31 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 16:09:31 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:09:31 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 16:09:31 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:09:31 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 16:09:31 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:09:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/S9SZ-svfRRWEyQFFy_pX8Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 16:09:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S9SZ-svfRRWEyQFFy_pX8Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:09:31 INFO - Fetch https://queue.taskcluster.net/v1/task/S9SZ-svfRRWEyQFFy_pX8Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 16:09:32 INFO - Http code: 200 16:09:32 INFO - Content-Encoding: None 16:09:32 INFO - Content-Type: application/zip 16:09:32 INFO - via: 1.1 e30ae5b3d9f6779a9b8bc992faad0b09.cloudfront.net (CloudFront) 16:09:32 INFO - x-amz-cf-id: XNQtYKVlTOrHv1CgfrHaEXVH9G0rDJWrB9Qw2MyWgZWyk2j6TMV1QA== 16:09:32 INFO - x-amz-version-id: HGEL9SCOb_t28MhhMPCbn8e7xe3RHjty 16:09:32 INFO - x-cache: Hit from cloudfront 16:09:32 INFO - Expected file size: 16472846 16:09:32 INFO - Obtained file size: 16472846 16:09:39 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 16:09:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/S9SZ-svfRRWEyQFFy_pX8Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 16:09:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S9SZ-svfRRWEyQFFy_pX8Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:09:39 INFO - Fetch https://queue.taskcluster.net/v1/task/S9SZ-svfRRWEyQFFy_pX8Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 16:09:40 INFO - Http code: 200 16:09:40 INFO - Content-Encoding: None 16:09:40 INFO - Content-Type: application/zip 16:09:40 INFO - via: 1.1 37af14ee951b12568cf1cc160d08abe6.cloudfront.net (CloudFront) 16:09:40 INFO - x-amz-cf-id: RRx6knS-HkT7OnnHEz2x-JhG51VMzYXEDAYj6nhqkidfBPXp7l6dFw== 16:09:40 INFO - x-amz-version-id: HGEL9SCOb_t28MhhMPCbn8e7xe3RHjty 16:09:40 INFO - x-cache: Hit from cloudfront 16:09:40 INFO - Expected file size: 16472846 16:09:40 INFO - Obtained file size: 16472846 16:09:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/S9SZ-svfRRWEyQFFy_pX8Q/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 16:09:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S9SZ-svfRRWEyQFFy_pX8Q/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:09:46 INFO - Fetch https://queue.taskcluster.net/v1/task/S9SZ-svfRRWEyQFFy_pX8Q/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 16:09:47 INFO - Http code: 200 16:09:47 INFO - Content-Encoding: None 16:09:47 INFO - Content-Type: application/zip 16:09:47 INFO - via: 1.1 41b2ee7cbe95749816a7586d9ab4629d.cloudfront.net (CloudFront) 16:09:47 INFO - x-amz-cf-id: AP4SU_1P5bttwDCpVNTwsv8fh70UIBAsb6eoP2wkukl8rxoER2sWdQ== 16:09:47 INFO - x-amz-version-id: ZXl4osTMwPBLo.pDl.VHTvUf_O65l8jg 16:09:47 INFO - x-cache: Hit from cloudfront 16:09:47 INFO - Expected file size: 14414067 16:09:47 INFO - Obtained file size: 14414067 16:09:47 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')]} 16:09:47 INFO - trying https://queue.taskcluster.net/v1/task/S9SZ-svfRRWEyQFFy_pX8Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:09:47 INFO - Downloading https://queue.taskcluster.net/v1/task/S9SZ-svfRRWEyQFFy_pX8Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 16:09:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S9SZ-svfRRWEyQFFy_pX8Q/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 16:09:50 INFO - Downloaded 111805557 bytes. 16:09:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/S9SZ-svfRRWEyQFFy_pX8Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:09:50 INFO - mkdir: /builds/slave/test/properties 16:09:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:09:50 INFO - Writing to file /builds/slave/test/properties/build_url 16:09:50 INFO - Contents: 16:09:50 INFO - build_url:https://queue.taskcluster.net/v1/task/S9SZ-svfRRWEyQFFy_pX8Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:09:52 INFO - Running post-action listener: _resource_record_post_action 16:09:52 INFO - Running post-action listener: set_extra_try_arguments 16:09:52 INFO - [mozharness: 2016-09-28 23:09:52.005794Z] Finished download-and-extract step (success) 16:09:52 INFO - [mozharness: 2016-09-28 23:09:52.005920Z] Running populate-webroot step. 16:09:52 INFO - Running pre-action listener: _resource_record_pre_action 16:09:52 INFO - Running main action method: populate_webroot 16:09:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:09:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:09:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:09:52 INFO - '--tppagecycles', 16:09:52 INFO - '7'], 16:09:52 INFO - 'tests': ['tsvgm']}}, 16:09:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:09:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:09:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:09:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:09:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:09:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:09:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:52 INFO - 'talos_options': ['--disable-e10s'], 16:09:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:09:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:09:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:52 INFO - 'talos_options': ['--disable-e10s'], 16:09:52 INFO - 'tests': ['damp', 'tps']}, 16:09:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:52 INFO - 'tests': ['damp', 'tps']}, 16:09:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:09:52 INFO - 'tests': ['dromaeo_dom']}, 16:09:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:09:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:09:52 INFO - 'tests': ['basic_compositor_video']}, 16:09:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:09:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:09:52 INFO - 'tests': ['a11yr', 16:09:52 INFO - 'ts_paint', 16:09:52 INFO - 'tpaint', 16:09:52 INFO - 'sessionrestore', 16:09:52 INFO - 'sessionrestore_no_auto_restore', 16:09:52 INFO - 'tabpaint']}, 16:09:52 INFO - 'other-e10s': {'tests': ['a11yr', 16:09:52 INFO - 'ts_paint', 16:09:52 INFO - 'tpaint', 16:09:52 INFO - 'sessionrestore', 16:09:52 INFO - 'sessionrestore_no_auto_restore', 16:09:52 INFO - 'tabpaint']}, 16:09:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:09:52 INFO - 'ts_paint', 16:09:52 INFO - 'tpaint', 16:09:52 INFO - 'sessionrestore', 16:09:52 INFO - 'sessionrestore_no_auto_restore', 16:09:52 INFO - 'tabpaint']}, 16:09:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:09:52 INFO - 'ts_paint', 16:09:52 INFO - 'tpaint', 16:09:52 INFO - 'sessionrestore', 16:09:52 INFO - 'sessionrestore_no_auto_restore', 16:09:52 INFO - 'tabpaint']}, 16:09:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:09:52 INFO - 'tests': ['a11yr', 16:09:52 INFO - 'ts_paint', 16:09:52 INFO - 'tpaint', 16:09:52 INFO - 'sessionrestore', 16:09:52 INFO - 'sessionrestore_no_auto_restore', 16:09:52 INFO - 'tabpaint']}, 16:09:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:09:52 INFO - 'tests': ['a11yr', 16:09:52 INFO - 'ts_paint', 16:09:52 INFO - 'tpaint', 16:09:52 INFO - 'sessionrestore', 16:09:52 INFO - 'sessionrestore_no_auto_restore', 16:09:52 INFO - 'tabpaint']}, 16:09:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:09:52 INFO - 'tests': ['tsvgx', 16:09:52 INFO - 'tsvgr_opacity', 16:09:52 INFO - 'tart', 16:09:52 INFO - 'tscrollx', 16:09:52 INFO - 'cart']}, 16:09:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:09:52 INFO - 'tsvgr_opacity', 16:09:52 INFO - 'tart', 16:09:52 INFO - 'tscrollx', 16:09:52 INFO - 'cart']}, 16:09:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:52 INFO - 'talos_options': ['--disable-e10s'], 16:09:52 INFO - 'tests': ['tp5o']}, 16:09:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:52 INFO - 'tests': ['tp5o']}, 16:09:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:52 INFO - 'talos_options': ['--disable-e10s', 16:09:52 INFO - '--xperf_path', 16:09:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:09:52 INFO - 'tests': ['tp5n']}, 16:09:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:52 INFO - 'talos_options': ['--xperf_path', 16:09:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:09:52 INFO - 'tests': ['tp5n']}}, 16:09:52 INFO - 'talos.zip': {'path': '', 16:09:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:09:52 INFO - Running post-action listener: _resource_record_post_action 16:09:52 INFO - [mozharness: 2016-09-28 23:09:52.025384Z] Finished populate-webroot step (success) 16:09:52 INFO - [mozharness: 2016-09-28 23:09:52.025517Z] Running create-virtualenv step. 16:09:52 INFO - Running pre-action listener: _resource_record_pre_action 16:09:52 INFO - Running main action method: create_virtualenv 16:09:52 INFO - Creating virtualenv /builds/slave/test/build/venv 16:09:52 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 16:09:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:09:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:09:52 INFO - Using real prefix '/tools/python27' 16:09:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:09:52 INFO - Installing distribute.............................................................................................................................................................................................done. 16:09:55 INFO - Installing pip.................done. 16:09:55 INFO - Return code: 0 16:09:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:09:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:09:56 INFO - Reading from file tmpfile_stdout 16:09:56 INFO - Output received: 16:09:56 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) 16:09:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:09: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')]} 16:09:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09: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')]} 16:09:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104cc3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c39570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff84348edb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ad7d30>, '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 0x1048fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104acfb20>, '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.JCZsHzRkHE/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.3GDYsRq0kk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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 16:09: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', 'pip>=1.5'] in /builds/slave/test/build 16:09: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 pip>=1.5 16:09:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JCZsHzRkHE/Render', 16:09:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:09:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:09:56 INFO - 'HOME': '/Users/cltbld', 16:09:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:09:56 INFO - 'LOGNAME': 'cltbld', 16:09:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:56 INFO - 'MOZ_NO_REMOTE': '1', 16:09:56 INFO - 'NO_EM_RESTART': '1', 16:09:56 INFO - 'PAGER': '/bin/cat', 16:09:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:09:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:56 INFO - 'PWD': '/builds/slave/test', 16:09:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:09:56 INFO - 'SHELL': '/bin/bash', 16:09:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3GDYsRq0kk/Listeners', 16:09:56 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 16:09:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:09:56 INFO - 'USER': 'cltbld', 16:09:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:09:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:09:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:56 INFO - 'XPC_FLAGS': '0x0', 16:09:56 INFO - 'XPC_SERVICE_NAME': '0', 16:09:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:09:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:56 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 16:09:56 INFO - Cleaning up... 16:09:56 INFO - Return code: 0 16:09:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:09: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')]} 16:09:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09: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')]} 16:09:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09: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', '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 0x104cc3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c39570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff84348edb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ad7d30>, '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 0x1048fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104acfb20>, '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.JCZsHzRkHE/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.3GDYsRq0kk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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 16:09: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', 'psutil>=3.1.1'] in /builds/slave/test/build 16:09: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 psutil>=3.1.1 16:09:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JCZsHzRkHE/Render', 16:09:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:09:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:09:56 INFO - 'HOME': '/Users/cltbld', 16:09:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:09:56 INFO - 'LOGNAME': 'cltbld', 16:09:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:56 INFO - 'MOZ_NO_REMOTE': '1', 16:09:56 INFO - 'NO_EM_RESTART': '1', 16:09:56 INFO - 'PAGER': '/bin/cat', 16:09:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:09:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:56 INFO - 'PWD': '/builds/slave/test', 16:09:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:09:56 INFO - 'SHELL': '/bin/bash', 16:09:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3GDYsRq0kk/Listeners', 16:09:56 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 16:09:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:09:56 INFO - 'USER': 'cltbld', 16:09:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:09:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:09:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:56 INFO - 'XPC_FLAGS': '0x0', 16:09:56 INFO - 'XPC_SERVICE_NAME': '0', 16:09:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:09:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:56 INFO - Downloading/unpacking psutil>=3.1.1 16:09:56 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 16:09:56 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 16:09:56 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 16:09:56 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 16:09:56 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 16:09:56 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 16:09:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:09:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:09:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:09:59 INFO - Installing collected packages: psutil 16:09:59 INFO - Running setup.py install for psutil 16:10:00 INFO - building 'psutil._psutil_osx' extension 16:10:00 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 16:10:00 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 16:10:00 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 16:10:00 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 16:10:00 INFO - building 'psutil._psutil_posix' extension 16:10:00 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 16:10:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:10:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:10:00 INFO - ^ 16:10:00 INFO - 1 warning generated. 16:10:00 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 16:10:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:10:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:10:01 INFO - Successfully installed psutil 16:10:01 INFO - Cleaning up... 16:10:01 INFO - Return code: 0 16:10:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:10:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:01 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 0x104cc3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c39570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff84348edb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ad7d30>, '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 0x1048fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104acfb20>, '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.JCZsHzRkHE/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.3GDYsRq0kk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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 16:10:01 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 16:10:01 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 16:10:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JCZsHzRkHE/Render', 16:10:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:01 INFO - 'HOME': '/Users/cltbld', 16:10:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:01 INFO - 'LOGNAME': 'cltbld', 16:10:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:01 INFO - 'MOZ_NO_REMOTE': '1', 16:10:01 INFO - 'NO_EM_RESTART': '1', 16:10:01 INFO - 'PAGER': '/bin/cat', 16:10:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:01 INFO - 'PWD': '/builds/slave/test', 16:10:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:01 INFO - 'SHELL': '/bin/bash', 16:10:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3GDYsRq0kk/Listeners', 16:10:01 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 16:10:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:01 INFO - 'USER': 'cltbld', 16:10:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:01 INFO - 'XPC_FLAGS': '0x0', 16:10:01 INFO - 'XPC_SERVICE_NAME': '0', 16:10:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:10:01 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 16:10:01 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 16:10:01 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 16:10:01 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 16:10:01 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 16:10:01 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 16:10:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:10:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:10:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:10:04 INFO - Installing collected packages: mozsystemmonitor 16:10:04 INFO - Running setup.py install for mozsystemmonitor 16:10:04 INFO - Successfully installed mozsystemmonitor 16:10:04 INFO - Cleaning up... 16:10:04 INFO - Return code: 0 16:10:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:10:04 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')]} 16:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:04 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')]} 16:10:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:04 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 0x104cc3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c39570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff84348edb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ad7d30>, '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 0x1048fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104acfb20>, '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.JCZsHzRkHE/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.3GDYsRq0kk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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 16:10:04 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 16:10:04 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 16:10:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JCZsHzRkHE/Render', 16:10:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:04 INFO - 'HOME': '/Users/cltbld', 16:10:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:04 INFO - 'LOGNAME': 'cltbld', 16:10:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:04 INFO - 'MOZ_NO_REMOTE': '1', 16:10:04 INFO - 'NO_EM_RESTART': '1', 16:10:04 INFO - 'PAGER': '/bin/cat', 16:10:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:04 INFO - 'PWD': '/builds/slave/test', 16:10:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:04 INFO - 'SHELL': '/bin/bash', 16:10:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3GDYsRq0kk/Listeners', 16:10:04 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 16:10:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:04 INFO - 'USER': 'cltbld', 16:10:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:04 INFO - 'XPC_FLAGS': '0x0', 16:10:04 INFO - 'XPC_SERVICE_NAME': '0', 16:10:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:04 INFO - Downloading/unpacking jsonschema==2.5.1 16:10:04 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 16:10:04 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 16:10:04 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 16:10:04 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 16:10:04 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 16:10:04 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 16:10:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:10:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:10:06 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 16:10:06 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 16:10:06 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 16:10:06 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 16:10:06 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 16:10:06 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 16:10:07 INFO - Downloading functools32-3.2.3-2.tar.gz 16:10:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:10:07 INFO - warning: no files found matching '*.txt' 16:10:07 INFO - no previously-included directories found matching 'build' 16:10:07 INFO - no previously-included directories found matching 'dist' 16:10:07 INFO - no previously-included directories found matching '.git*' 16:10:07 INFO - Installing collected packages: jsonschema, functools32 16:10:07 INFO - Running setup.py install for functools32 16:10:07 INFO - warning: no files found matching '*.txt' 16:10:07 INFO - no previously-included directories found matching 'build' 16:10:07 INFO - no previously-included directories found matching 'dist' 16:10:07 INFO - no previously-included directories found matching '.git*' 16:10:07 INFO - Successfully installed jsonschema functools32 16:10:07 INFO - Cleaning up... 16:10:07 INFO - Return code: 0 16:10:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:10:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104cc3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c39570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff84348edb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ad7d30>, '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 0x1048fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104acfb20>, '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.JCZsHzRkHE/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.3GDYsRq0kk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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 16:10:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:10:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:10:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JCZsHzRkHE/Render', 16:10:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:07 INFO - 'HOME': '/Users/cltbld', 16:10:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:07 INFO - 'LOGNAME': 'cltbld', 16:10:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:07 INFO - 'MOZ_NO_REMOTE': '1', 16:10:07 INFO - 'NO_EM_RESTART': '1', 16:10:07 INFO - 'PAGER': '/bin/cat', 16:10:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:07 INFO - 'PWD': '/builds/slave/test', 16:10:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:07 INFO - 'SHELL': '/bin/bash', 16:10:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3GDYsRq0kk/Listeners', 16:10:07 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 16:10:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:07 INFO - 'USER': 'cltbld', 16:10:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:07 INFO - 'XPC_FLAGS': '0x0', 16:10:07 INFO - 'XPC_SERVICE_NAME': '0', 16:10:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:10:08 INFO - Cleaning up... 16:10:08 INFO - Return code: 0 16:10:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:10: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')]} 16:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10: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')]} 16:10:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:08 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 0x104cc3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c39570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff84348edb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ad7d30>, '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 0x1048fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104acfb20>, '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.JCZsHzRkHE/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.3GDYsRq0kk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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 16:10:08 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 16:10:08 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 16:10:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JCZsHzRkHE/Render', 16:10:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:08 INFO - 'HOME': '/Users/cltbld', 16:10:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:08 INFO - 'LOGNAME': 'cltbld', 16:10:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:08 INFO - 'MOZ_NO_REMOTE': '1', 16:10:08 INFO - 'NO_EM_RESTART': '1', 16:10:08 INFO - 'PAGER': '/bin/cat', 16:10:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:08 INFO - 'PWD': '/builds/slave/test', 16:10:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:08 INFO - 'SHELL': '/bin/bash', 16:10:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3GDYsRq0kk/Listeners', 16:10:08 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 16:10:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:08 INFO - 'USER': 'cltbld', 16:10:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:08 INFO - 'XPC_FLAGS': '0x0', 16:10:08 INFO - 'XPC_SERVICE_NAME': '0', 16:10:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:08 INFO - Downloading/unpacking blobuploader==1.2.4 16:10:08 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 16:10:08 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 16:10:08 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 16:10:08 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 16:10:08 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 16:10:08 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 16:10:10 INFO - Downloading blobuploader-1.2.4.tar.gz 16:10:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:10:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:10:10 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 16:10:10 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 16:10:10 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 16:10:10 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 16:10:10 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 16:10:10 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 16:10:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:10:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:10:11 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 16:10:11 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 16:10:11 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 16:10:11 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 16:10:11 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 16:10:11 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 16:10:11 INFO - Downloading docopt-0.6.1.tar.gz 16:10:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:10:12 INFO - Installing collected packages: blobuploader, requests, docopt 16:10:12 INFO - Running setup.py install for blobuploader 16:10:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:10:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:10:12 INFO - Running setup.py install for requests 16:10:12 INFO - Running setup.py install for docopt 16:10:12 INFO - Successfully installed blobuploader requests docopt 16:10:12 INFO - Cleaning up... 16:10:12 INFO - Return code: 0 16:10:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:10:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:12 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 0x104cc3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c39570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff84348edb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ad7d30>, '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 0x1048fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104acfb20>, '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.JCZsHzRkHE/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.3GDYsRq0kk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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 16:10:12 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 16:10:12 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 16:10:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JCZsHzRkHE/Render', 16:10:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:12 INFO - 'HOME': '/Users/cltbld', 16:10:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:12 INFO - 'LOGNAME': 'cltbld', 16:10:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:12 INFO - 'MOZ_NO_REMOTE': '1', 16:10:12 INFO - 'NO_EM_RESTART': '1', 16:10:12 INFO - 'PAGER': '/bin/cat', 16:10:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:12 INFO - 'PWD': '/builds/slave/test', 16:10:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:12 INFO - 'SHELL': '/bin/bash', 16:10:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3GDYsRq0kk/Listeners', 16:10:12 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 16:10:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:12 INFO - 'USER': 'cltbld', 16:10:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:12 INFO - 'XPC_FLAGS': '0x0', 16:10:12 INFO - 'XPC_SERVICE_NAME': '0', 16:10:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:10:13 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-p4epou-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:10:13 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-rqDtLL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:10:13 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-UwZpoV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:10:13 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-FSuB3u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:10:13 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-kcCVCq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:10:13 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-oVU7b8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:10:13 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-TMv953-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:10:13 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-G9crNF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:10:14 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-Gnx4n1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:10:14 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-QCPK7V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:10:14 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-2PrTI1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:10:14 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-I4CBM7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:10:14 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-B17u_H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:10:14 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-zkYMw8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:10:14 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-R4y5uu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:10:14 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-UwHw_R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:10:14 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-SRyrwc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:10:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:10:15 INFO - Running setup.py install for manifestparser 16:10:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:10:15 INFO - Running setup.py install for mozcrash 16:10:15 INFO - Running setup.py install for mozdebug 16:10:15 INFO - Running setup.py install for mozdevice 16:10:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:10:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:10:15 INFO - Running setup.py install for mozfile 16:10:15 INFO - Running setup.py install for mozhttpd 16:10:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:10:15 INFO - Running setup.py install for mozinfo 16:10:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:10:16 INFO - Running setup.py install for mozInstall 16:10:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:10:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:10:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:10:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:10:16 INFO - Running setup.py install for mozleak 16:10:16 INFO - Running setup.py install for mozlog 16:10:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:10:16 INFO - Running setup.py install for moznetwork 16:10:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:10:16 INFO - Running setup.py install for mozprocess 16:10:16 INFO - Running setup.py install for mozprofile 16:10:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:10:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:10:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:10:16 INFO - Running setup.py install for mozrunner 16:10:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:10:17 INFO - Running setup.py install for mozscreenshot 16:10:17 INFO - Running setup.py install for moztest 16:10:17 INFO - Running setup.py install for mozversion 16:10:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:10:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:10:17 INFO - Cleaning up... 16:10:17 INFO - Return code: 0 16:10:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:10:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:17 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 0x104cc3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c39570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff84348edb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ad7d30>, '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 0x1048fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104acfb20>, '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.JCZsHzRkHE/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.3GDYsRq0kk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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 16:10:17 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 16:10:17 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 16:10:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JCZsHzRkHE/Render', 16:10:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:17 INFO - 'HOME': '/Users/cltbld', 16:10:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:17 INFO - 'LOGNAME': 'cltbld', 16:10:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:17 INFO - 'MOZ_NO_REMOTE': '1', 16:10:17 INFO - 'NO_EM_RESTART': '1', 16:10:17 INFO - 'PAGER': '/bin/cat', 16:10:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:17 INFO - 'PWD': '/builds/slave/test', 16:10:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:17 INFO - 'SHELL': '/bin/bash', 16:10:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3GDYsRq0kk/Listeners', 16:10:17 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 16:10:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:17 INFO - 'USER': 'cltbld', 16:10:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:17 INFO - 'XPC_FLAGS': '0x0', 16:10:17 INFO - 'XPC_SERVICE_NAME': '0', 16:10:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:10:17 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-8R_XhJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:10:17 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)) 16:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:10:17 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-GmPgNc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:10:18 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)) 16:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:10:18 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-TI2_pu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:10:18 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)) 16:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:10:18 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-gZ9dqH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:10:18 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)) 16:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:10:18 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-e95VeJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:10:18 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)) 16:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:10:18 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-BjI99A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:10:18 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)) 16:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:10:18 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-lGZ6Oo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:10:18 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)) 16:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:10:18 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-lOrbog-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:10:18 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)) 16:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:10:18 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-MT1VeP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:10:18 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)) 16:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:10:18 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-W60Ad9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:10:18 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)) 16:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:10:18 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-lo8h2t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:10:19 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)) 16:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:10:19 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-o_6T_d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:10:19 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)) 16:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:10:19 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-7UEAqk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:10:19 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)) 16:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:10:19 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-5ViAG9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:10:19 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)) 16:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:10:19 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-5b7z3_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:10:19 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)) 16:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:10:19 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-MEop0n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:10:19 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)) 16:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:10:19 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-C6gF1n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:10:19 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)) 16:10:19 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)) 16:10:19 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)) 16:10:19 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)) 16:10:19 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)) 16:10:19 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)) 16:10:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:10:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:22 INFO - Downloading blessings-1.6.tar.gz 16:10:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:10:22 INFO - Installing collected packages: blessings 16:10:22 INFO - Running setup.py install for blessings 16:10:22 INFO - Successfully installed blessings 16:10:22 INFO - Cleaning up... 16:10:22 INFO - Return code: 0 16:10:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:10:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:10:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:10:23 INFO - Reading from file tmpfile_stdout 16:10:23 INFO - Current package versions: 16:10:23 INFO - blessings == 1.6 16:10:23 INFO - blobuploader == 1.2.4 16:10:23 INFO - docopt == 0.6.1 16:10:23 INFO - functools32 == 3.2.3-2 16:10:23 INFO - jsonschema == 2.5.1 16:10:23 INFO - manifestparser == 1.1 16:10:23 INFO - mozInstall == 1.12 16:10:23 INFO - mozcrash == 0.17 16:10:23 INFO - mozdebug == 0.1 16:10:23 INFO - mozdevice == 0.48 16:10:23 INFO - mozfile == 1.2 16:10:23 INFO - mozhttpd == 0.7 16:10:23 INFO - mozinfo == 0.9 16:10:23 INFO - mozleak == 0.1 16:10:23 INFO - mozlog == 3.4 16:10:23 INFO - moznetwork == 0.27 16:10:23 INFO - mozprocess == 0.23 16:10:23 INFO - mozprofile == 0.28 16:10:23 INFO - mozrunner == 6.12 16:10:23 INFO - mozscreenshot == 0.1 16:10:23 INFO - mozsystemmonitor == 0.3 16:10:23 INFO - moztest == 0.7 16:10:23 INFO - mozversion == 1.4 16:10:23 INFO - psutil == 3.1.1 16:10:23 INFO - requests == 1.2.3 16:10:23 INFO - wsgiref == 0.1.2 16:10:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:10:23 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')]} 16:10:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:23 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')]} 16:10:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:23 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 0x104cc3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c39570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff84348edb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ad7d30>, '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 0x1048fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104acfb20>, '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.JCZsHzRkHE/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.3GDYsRq0kk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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 16:10:23 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 16:10:23 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 16:10:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JCZsHzRkHE/Render', 16:10:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:23 INFO - 'HOME': '/Users/cltbld', 16:10:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:23 INFO - 'LOGNAME': 'cltbld', 16:10:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:23 INFO - 'MOZ_NO_REMOTE': '1', 16:10:23 INFO - 'NO_EM_RESTART': '1', 16:10:23 INFO - 'PAGER': '/bin/cat', 16:10:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:23 INFO - 'PWD': '/builds/slave/test', 16:10:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:23 INFO - 'SHELL': '/bin/bash', 16:10:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3GDYsRq0kk/Listeners', 16:10:23 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 16:10:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:23 INFO - 'USER': 'cltbld', 16:10:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:23 INFO - 'XPC_FLAGS': '0x0', 16:10:23 INFO - 'XPC_SERVICE_NAME': '0', 16:10:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:23 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)) 16:10:23 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)) 16:10:23 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)) 16:10:23 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)) 16:10:23 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)) 16:10:23 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)) 16:10:23 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)) 16:10:23 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)) 16:10:23 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)) 16:10:23 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)) 16:10:23 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)) 16:10:23 INFO - Cleaning up... 16:10:23 INFO - Return code: 0 16:10:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:10:23 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')]} 16:10:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:23 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')]} 16:10:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:23 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 0x104cc3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c39570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff84348edb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ad7d30>, '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 0x1048fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104acfb20>, '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.JCZsHzRkHE/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.3GDYsRq0kk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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 16:10:23 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 16:10:23 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 16:10:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JCZsHzRkHE/Render', 16:10:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:23 INFO - 'HOME': '/Users/cltbld', 16:10:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:23 INFO - 'LOGNAME': 'cltbld', 16:10:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:23 INFO - 'MOZ_NO_REMOTE': '1', 16:10:23 INFO - 'NO_EM_RESTART': '1', 16:10:23 INFO - 'PAGER': '/bin/cat', 16:10:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:23 INFO - 'PWD': '/builds/slave/test', 16:10:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:23 INFO - 'SHELL': '/bin/bash', 16:10:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3GDYsRq0kk/Listeners', 16:10:23 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 16:10:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:23 INFO - 'USER': 'cltbld', 16:10:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:23 INFO - 'XPC_FLAGS': '0x0', 16:10:23 INFO - 'XPC_SERVICE_NAME': '0', 16:10:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:10:23 INFO - Cleaning up... 16:10:23 INFO - Return code: 0 16:10:23 INFO - Running post-action listener: _resource_record_post_action 16:10:23 INFO - Running post-action listener: _start_resource_monitoring 16:10:23 INFO - Starting resource monitoring. 16:10:23 INFO - [mozharness: 2016-09-28 23:10:23.792278Z] Finished create-virtualenv step (success) 16:10:23 INFO - [mozharness: 2016-09-28 23:10:23.793243Z] Running install step. 16:10:23 INFO - Running pre-action listener: _resource_record_pre_action 16:10:23 INFO - Running main action method: install 16:10:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:10:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:10:24 INFO - Reading from file tmpfile_stdout 16:10:24 INFO - Detecting whether we're running mozinstall >=1.0... 16:10:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:10:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:10:24 INFO - Reading from file tmpfile_stdout 16:10:24 INFO - Output received: 16:10:24 INFO - Usage: mozinstall [options] installer 16:10:24 INFO - Options: 16:10:24 INFO - -h, --help show this help message and exit 16:10:24 INFO - -d DEST, --destination=DEST 16:10:24 INFO - Directory to install application into. [default: 16:10:24 INFO - "/builds/slave/test"] 16:10:24 INFO - --app=APP Application being installed. [default: firefox] 16:10:24 INFO - mkdir: /builds/slave/test/build/application 16:10:24 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'] 16:10:24 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 16:10:59 INFO - Reading from file tmpfile_stdout 16:10:59 INFO - Output received: 16:10:59 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:10:59 INFO - Running post-action listener: _resource_record_post_action 16:10:59 INFO - [mozharness: 2016-09-28 23:10:59.188657Z] Finished install step (success) 16:10:59 INFO - [mozharness: 2016-09-28 23:10:59.188823Z] Running run-tests step. 16:10:59 INFO - Running pre-action listener: _resource_record_pre_action 16:10:59 INFO - Running main action method: run_tests 16:10:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:10:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:10:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:10:59 INFO - 2016-09-28 16:10:59.203 screenresolution[1641:8230] starting screenresolution argv=screenresolution get 16:10:59 INFO - 2016-09-28 16:10:59.205 screenresolution[1641:8230] Display 0: 1600x1200x32@60 16:10:59 INFO - 2016-09-28 16:10:59.211 screenresolution[1642:8233] starting screenresolution argv=screenresolution list 16:10:59 INFO - Available Modes on Display 0 16:10:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:10:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:10:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:10:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:10:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:10:59 INFO - Intel Iris: 16:10:59 INFO - Chipset Model: Intel Iris 16:10:59 INFO - Type: GPU 16:10:59 INFO - Bus: Built-In 16:10:59 INFO - VRAM (Dynamic, Max): 1536 MB 16:10:59 INFO - Vendor: Intel (0x8086) 16:10:59 INFO - Device ID: 0x0a2e 16:10:59 INFO - Revision ID: 0x0009 16:10:59 INFO - Displays: 16:10:59 INFO - Display: 16:10:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:10:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:10:59 INFO - Display Serial Number: 1600x1200 60 16:10:59 INFO - Main Display: Yes 16:10:59 INFO - Mirror: Off 16:10:59 INFO - Online: Yes 16:10:59 INFO - Rotation: Supported 16:10:59 INFO - Return code: 0 16:10:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:10:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:10:59 INFO - Python 2.7.3 16:10:59 INFO - Return code: 0 16:10:59 INFO - grabbing minidump binary from tooltool 16:10:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:59 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 0x104ad7d30>, '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 0x1048fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104acfb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:10:59 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 16:10:59 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 16:10:59 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 16:10:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:10:59 INFO - Return code: 0 16:10:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:10:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:10:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:10:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:10:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:10:59 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/S9SZ-svfRRWEyQFFy_pX8Q/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0047', '--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 16:10:59 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/S9SZ-svfRRWEyQFFy_pX8Q/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0047 --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 16:10:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JCZsHzRkHE/Render', 16:10:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:59 INFO - 'HOME': '/Users/cltbld', 16:10:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:59 INFO - 'LOGNAME': 'cltbld', 16:10:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:10:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:10:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:59 INFO - 'MOZ_NO_REMOTE': '1', 16:10:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:10:59 INFO - 'NO_EM_RESTART': '1', 16:10:59 INFO - 'PAGER': '/bin/cat', 16:10:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:59 INFO - 'PWD': '/builds/slave/test', 16:10:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:10:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:59 INFO - 'SHELL': '/bin/bash', 16:10:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3GDYsRq0kk/Listeners', 16:10:59 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 16:10:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:59 INFO - 'USER': 'cltbld', 16:10:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:59 INFO - 'XPC_FLAGS': '0x0', 16:10:59 INFO - 'XPC_SERVICE_NAME': '0', 16:10:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:59 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/S9SZ-svfRRWEyQFFy_pX8Q/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0047', '--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 16:10:59 INFO - mozversion application_buildid: 20160928140620 16:10:59 INFO - mozversion application_changeset: d2435513f1356962445a7704b8d8b0d911b9aac3 16:10:59 INFO - mozversion application_display_name: Nightly 16:10:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:10:59 INFO - mozversion application_name: Firefox 16:10:59 INFO - mozversion application_remotingname: firefox 16:10:59 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 16:10:59 INFO - mozversion application_vendor: Mozilla 16:10:59 INFO - mozversion application_version: 52.0a1 16:10:59 INFO - mozversion platform_buildid: 20160928140620 16:10:59 INFO - mozversion platform_changeset: d2435513f1356962445a7704b8d8b0d911b9aac3 16:10:59 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 16:10:59 INFO - mozversion platform_version: 52.0a1 16:10:59 INFO - using testdate: 1475104259 16:10:59 INFO - actual date: 1475104259 16:10:59 INFO - starting webserver on 'localhost:49276' 16:10:59 INFO - SUITE-START | Running 2 tests 16:10:59 INFO - TEST-START | dromaeo_css 16:10:59 INFO - operating with platform_type : mac_ 16:10:59 INFO - Initialising browser for dromaeo_css test... 16:10:59 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/tmp9qwEpl/profile http://localhost:49276/getInfo.html) 16:11:02 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 16:11:02 INFO - PROCESS | 1652 | colorDepth:24 16:11:02 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 16:11:02 INFO - PROCESS | 1652 | __metrics 16:11:02 INFO - PROCESS | 1652 | 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" 16:11:02 INFO - PROCESS | 1652 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:11:03 INFO - TEST-INFO | 1652: exit 0 16:11:03 INFO - Browser initialized. 16:11:03 INFO - Running cycle 1/1 for dromaeo_css test... 16:11:03 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/tmp9qwEpl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:11:15 INFO - PROCESS | 1655 | RSS: Main: 235978752 16:11:15 INFO - PROCESS | 1655 | 16:12:07 INFO - PROCESS | 1655 | 1475104327058 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] 16:14:18 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 16:14:19 INFO - PROCESS | 1655 | RSS: Main: 378892288 16:14:19 INFO - PROCESS | 1655 | 16:17:22 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 16:17:23 INFO - PROCESS | 1655 | RSS: Main: 370679808 16:17:23 INFO - PROCESS | 1655 | 16:20:25 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 16:20:26 INFO - PROCESS | 1655 | RSS: Main: 328884224 16:20:26 INFO - PROCESS | 1655 | 16:23:28 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 16:23:29 INFO - PROCESS | 1655 | RSS: Main: 335380480 16:23:29 INFO - PROCESS | 1655 | 16:26:32 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 16:26:33 INFO - PROCESS | 1655 | RSS: Main: 327938048 16:26:33 INFO - PROCESS | 1655 | 16:29:36 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 16:29:37 INFO - PROCESS | 1655 | RSS: Main: 456040448 16:29:37 INFO - PROCESS | 1655 | 16:29:37 INFO - PROCESS | 1655 | __start_tp_report 16:29:37 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 16:29:37 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 16:29:37 INFO - PROCESS | 1655 | |i|pagename|runs| 16:29:37 INFO - PROCESS | 1655 | |0;dojo.html;3334.099793055875;3346.748993825464;3366.51185578091;3387.1870751019733;3401.35374278887;5590.385057643656;5698.145278208271;5715.399883012281;5834.27071616048;5841.7955371562;5635.5773317001185;5649.23735295736;5798.10129429939;5799.971000144993;5801.767929282823;5100.260462232974;5151.459096794839;5163.974180129094;5219.164933610623;5227.660202086852;5308.283381743473;5321.255024296602;5322.494363035525;5334.119870221409;5378.381486129087;5273.446288139767;5283.815066472655;5297.814576489824;5313.07021271276;5376.059189641816;5116.51393117657;5156.639035267533;5197.714125723083;5208.453112423179;5212.087884620177;5159.1229490986625;5171.663841850305;5180.740962951837;5183.948160518385;5187.507186817246;5683;5702.287214098238;5737.024705800024;5745.798897038625;5761.971190144064;6100.157317757059;6149.2313460817395;6152.2924863640355;6215.595986260879;6269.749210031593;6236.906446403307;6247.062940558907;6249.96875015622;6266.843328916768;6284.748610055638;5796.681182534959;5833.4749872511675;5834.533237341087;5837.241158649434;5880.7353669085;6134.77336474251;6181.629102253879;6278.0269058296035;6283.3402492737605;6283.717232724538;5980.342162362137;6007.429294217042;6019.458248757633;6058.666773327421;6069.575129740964;6120.816375508742;6123.683408067287;6126.601770884968;6136.846578835565;6158.414950579688;6013.6993150342305;6058.242719660043;6071.483923866521;6077.696115194222;6083.969580152071;6158.507319414436;6221.160143380676;6230.688465576703;6276.968615156987;6283.403076707706;4910.803567857246;4915.188993816052;4919.65562410635;4923.113839508923;4936.3829521309835;4431.047324825114;4454.086912182995;4480.865574032784;4498.977505112519;4520.186366454004;5693.544516438675;5695.5443564514735;5786.334571524244;5831.125331200269;5838.4745372915795;5267.894642107136;6262.185915830913;6313.2424109107205;6330.746770129235;6338;5770.480656740913;5786.132080187921;5787;5787.739551720183;5798.188253644493;4545.65907556928;5276.65701729394;5284.841454756363;5318.73406329682;5344.358676958712;6290.748370065146;6298.433141017331;6300.3699630037545;6320.5891617044745;6328.810135695937;6286.27707813589;6286.874262514815;6289.213848268966;6304.148939893055;6309.842253943688;6247.156633854372;6276.184096067482;6295.748170073329;6317.115603815561;6344.746210151542;6113.749968914014;6122.928487514756;6161.784337548164;6220.315765265726;6225.128482012431;4819.855404337875;5167.948320516747;6177.073438984188;6265.718042688181;6272.6236425814595;5891.852703682443;6059.666718330534;6094.786682466092;6125.479334256638;6137.96931015342;5967.7016149191495;6026.819195424145;6051.183090282755;6108.45023947829;6115.816525504242;5867.794627188028;5913.615614985012;5927.733252003756;5963.105534169909;5970.104484327386;6051;6073.210482637229;6075.483583895419;6079.604825686316;6090.573659843769;5770.596058276048;5830.591858569859;5840.620359676607;5866.530677545701;5923.555733319932 16:29:37 INFO - PROCESS | 1655 | |1;ext.html;480.32993973407093;521.2832355511172;526.6076772804262;535.1918602909608;549.3846891481542;30595;40818.79590602047;41895.99999999996;41995.580044199545;43941.12117757643;43016;43486.34770478438;43512.347314790306;43518;43626.34560481595;8687.782805429852;9452.385594936308;9572.994835542273;9702.175315098226;9744.31789774716;9648.614055437774;9767;9769.609215631403;9779.266555008368;9797.26520510961;9758.90241097587;9789.363691360075;9807;9817.95091024544;9826.606935722562;9712.465814380206;9729.221662267002;9747.268954828416;9760.072793084646;9771.560279787427;9584.856227156599;9668.75828104296;9674.661386851496;9686.90313096867;9689.951550242204;40974.79512602447;41455.37816932749;41483.377749333486;41534;41564.58435415668;3224.274538228903;3296.9175770605925;3297.6207736110414;3325.451300535433;3389.3390788796196;30328;30736.077917662507;30750.692493075232;31369;31421;3033.378157477734;3160.1625569224175;3179.952300715491;3208.374366998436;3315.1546355679147;466923;470076.64961675316;470904;471241;472814;61675.38324616786;61882.381176187664;61883;61899;61937.690311548155;34555;35154;35250;65177.674111630084;65562.01656975149;15452.922735386252;15525.534233973;15635.218239088;15671.764923526156;15713.21433928299;11571.01646360052;11953.880461195451;12049.03607711381;12064.457099430547;12066.974307183813;18795.718064229048;18936.242550297833;18977.620447591245;19021.714674279898;19047.80952190488;6533.30134165111;7540.208278130717;7756.263155000266;7795.986521752136;7805.258500442449;853.4239388412797;965.280865755017;977.7115750853516;995.8954309797512;1080.6055789636882;29996.85001574978;29999.850270262385;30092.849535752182;30173.245668858017;30386.696133038386;785.9174786647434;995.4723996281982;1060.7401186709305;1069.3316677076828;1086.9565217391373;12173.452194651261;15218.695626087638;15501.689966200838;15577.922110389602;15685.235543469485;668572;669371;670157;670335.6483217552;670493;57166;57554.712226439435;57682;57737.71131144317;57759.71120144372;1216.9756604867853;1254.1471799176647;1318.518740659633;1327.7543654423962;1362.0669841158838;27580.862095689394;28360;28862.711372886002;28877.422451551272;29490.410191795614;49476;50018.49981500284;50154.24768628547;50212;50217.74891125521;528499.3575032101;531247;532013;536140;536842;24791.174074092214;25033.624495632215;26383.868080659475;27343.86328068347;28235;47188.29217561808;47773;47907.81382792469;48439.5156048435;48627.51372486371;32571.674283256863;38457.23085538329;38815.22369552537;39178;40156.59843401646;38739.83313087088;38780.61219387883;39587;40018;40129.1974160521;36757.26485470329;37483.250334993696;38048.048798780255;38303;38664.6133538661;28053.01814436485;33013.17467063342;34625.65374346325;34747.65252347545;34838;24803;24822.50354992926;25123.371915702253;25655.615165772157;25774.09790657318 16:29:37 INFO - PROCESS | 1655 | |2;jquery.html;303.9574459575661;359.3316431437527;362.673593765611;374.31873989339414;399.0721572344298;15100.395984160634;15129.470468533604;16289.918550407247;20474.795252047476;21044.8947755261;19331.516712082204;19878.900605496987;20215.494612634655;20543.897280513578;20773.792262077375;5880.147378630096;6129.417705317987;6133.2026836511195;6133.846653833668;6155.19982402287;4194.517630472489;4400.581944715263;4582.564656357641;6015.368386319417;6086.726097325609;4435.778211089449;4443.84446544371;6340.809775706736;6699.296573859748;6703;5438.320209973754;6599.70301336441;6641.867162656768;6693.933060669379;6738.629375384352;5774.960507108719;5780;5784.855378615526;5791.507721843648;5795.623284486537;25900.35249118768;26264.8686756565;26363.73636263632;26678.5998210027;26683;2196;2252.8873556322283;2273.57938781326;2905.3172504461436;3079.63044434667;13213.678632136805;13494.797578036338;15211.391544338214;15450.690986180325;15918.920405398014;1821.681205788995;1827.259959716317;1942.2716481319505;1949.6003319319504;1995.4213278149318;91634;127191.36404318012;128379.35810320888;128946;129258;10418.947905260426;10504.894951050544;11451.656450306504;11461.4269286537;11706.941465292619;14509.911756618296;14535.927320363542;14537.636559085897;14546.781798273034;14566.271686415848;6850.38346548803;8048.396370272253;8320.292775114061;8358.45670031446;8476.279516241066;8143.226393492609;8147.511149331059;8167.142450042778;8318.334533237326;8325.583720813935;4385.386045953569;4494.3707880896045;4505.774711264424;4509.098180363941;4587.61005314552;1297.3772589157256;1585.8969167004109;1847.4827048426462;1889.1404410992966;1897.3928342930406;739.1868944161487;782.765170448863;955.7945041816043;991.4844280973997;999.8650182225307;15575.831812614098;20003.499737519745;20846.374608761762;21035.47411314699;21094.47263818417;918.2470374293016;926.559884055082;947.251671179774;952.9523523823781;1016.2682868334934;9470;10248.692539223835;10807.621733239299;10889.618863339745;10919.617813376493;566434;570040;574370;588095;588498;72638.27361726316;73396;105092.01625845532;126233;129166;978.9510524473748;982.9754256143653;1011.4689787861431;1018.4042335233971;1026.9743256418649;18673.346432874783;18949.810501894804;19160.946147961997;19206.039698015324;19299;41812.3728144072;54915.82020315176;54954;55130.44869551413;56565.71717141388;65904;80438.3912321762;84620;85296;85654.57172714097;7086.326798954091;7326.194118647051;7374.963125184339;7941.324987376138;8388.958055209685;10057.69826905194;12399.008079353449;13100.017498687446;13428.865711343153;13640.839543939193;11113.499892504695;11355.300402663408;11506.884931150582;11564.884351156381;11658.417079146178;8276.241161332138;11076.058535024613;11263.7747245053;11388.430578471209;11456.083513318748;8592.097829727789;9261.767693870468;11170.944145279222;11284.51740617063;11850.348230847041;7152.9284707152265;7343.2289609590225;7446.404287657083;7493.063367079115;7640.541567505745;4033.818478168431;4580.312953057068;10657.626983055556;10740.355578664992;11434.771304574027 16:29:37 INFO - PROCESS | 1655 | |3;mootools.html;1317.3676635215093;1413.6607214268574;1473.2486431919724;1811;1837.8805377650444;8293.419460637759;8820.60307286172;8838.51388173651;8842.646294148226;8845.734627961172;7755.457118001742;8001.839963200747;8012.198780121985;8396.790080247996;8617.526036068013;2327.627579587266;2372.78644921957;2374.4538756086113;2375.726791418983;2375.964360534593;2332.825038122148;2338.38032921276;2340.9765902341014;2378.7283144596868;2387.3673476528734;2336.077249486452;2342.074880422232;2353.8469999450067;2362.2558893948435;2373.394784330002;2345.2143531916827;2359.28041947206;2365.6333268343433;2368.289513146049;2368.549975504666;2335.135999680112;2337.742848286687;2341.7189937207645;2342.145117032271;2348.6007378745485;8728.825423491558;8787.956060219722;8801.031886492481;8813.779655508599;8814.338924580683;5135.332406787131;5287.629865909388;5300.390455097675;5300.92048619271;5307.787688492455;3465.220325426759;3516.085817687394;3687.723420743455;3691.50164727761;3702.3705969985167;1058.6559368205287;1059.7721489879714;1080.206048554312;1080.6649938519006;1098.2477003252798;11341.716457088556;11469.254498457656;11475.368854712988;11483.712907177387;11552.364619945984;4507.346434766941;4557.065801510682;4717.9292310615365;4732.858014259577;4732.929006064912;9843.507824608741;9849;9855.162311203629;9862.112409883002;9877.802443951081;5254.92117618236;5258;5261.605379596545;5266.683998960075;5280.630355875129;4320;4326.113146804897;4545.704529205591;4831.202851529459;4832.540908613676;3778.1876896467393;3825.254075455315;3928.135810121769;3932.783696896698;3960.742551734128;1245.1532957588745;1266.303533056815;1267.2966503590412;1279.1301914698095;1279.5777393460137;838.9119142489951;852.9892077887723;877.6050777150308;892.6474042753076;907.5190149220923;5455.727213639302;5577.470140336662;5700.857478563069;5814.651120932757;5823.242978412779;914.5838643417229;988.3328753091778;1018.4907546226887;1032.0969151992008;1059;3804.2391521695217;3807.1053302473897;3811.447340135665;3912.7065470089856;3941.704372172041;10359.119474844572;10653.254272200871;10654.414007229676;10657.094146997591;10677.679669609925;10537.42044187577;10632.734181645366;10639.680809575726;10643.94678026605;10662.093722033715;7200.603966781878;7886.881696774553;8093.514389136553;8225.753227403067;8242.50544967316;5057.216131499624;5569.275994120739;5591.608587398843;5645.9611541125705;5851.444112809277;9958.601655933971;9988.650397236062;10029;10069.194464442973;10084.243681723754;9469;9874.753131171778;9886.50567471638;10013.899861001297;10017.398956062369;6833.829154271183;7045.401140903081;7351.8529629408185;7501.362384197404;7524.924750752422;7946.404019698662;8092.150324215872;8156.8368632625825;8305.294050005818;8312.459690120122;7305.050343455529;7444.516106453063;7495.550266983998;7520.285572870568;7570.5836179010685;7109.608971506616;7254.927450725425;7324.121105467378;7337.816554585965;7429.888551671837;6846.931530684629;6882.69027893736;6955.617441040791;6959.89560156588;6973.755918542826;4881.096997055556;4921.26181072842;4944.752762361795;4963.627727920492;5030.119729047476;4825.445073816556;4851.684640498356;4896.510348965075;4974.17926042199;4988.251762235548 16:29:37 INFO - PROCESS | 1655 | |4;prototype.html;289.3966080721695;295.5124045325214;302.84554877012715;305.87764894042374;306.10311786465644;4260.550183644409;5163.070647283487;5437.782488700447;5488.545535433109;5526.756479792045;4469.553044695529;4635.536446355362;5022.472640372754;5042.596592272618;5232.686038837673;3752.3058234226755;3768.7927164006105;4079.7552146871135;4104.578298803705;4127.855525056623;4070.7761073140964;4111.671066314702;4126.555705503065;4134.979325103371;4186.665066794664;3254.300325430032;3899.824507897137;4072.0430698785763;4125.484314460692;4163.770992595406;3362.7309815214967;4106.527749308808;4116.608922152391;4122.237386083554;4146.523149837777;3558.48764275182;3563.1270338767167;3571.6964058055096;3575.928481430383;3595.568531776177;4395.164918665443;5144.099782538078;5232.686038837682;5281.603879708999;5373.435789242151;4690.530946905316;4707.176244157293;4714.033623107255;4720.12677654635;4735.005648813753;5099.923501147486;5348.956953394089;5702.515286200678;5704.942950570524;5709.514691251248;3919.157381163035;3926.391409331558;3942.526896772377;3958.7426817256787;3967.4247234150885;5659.4340565943485;5859.677717725481;5909.556783241274;5935.703214839241;5998.3401825799565;3329.267561136546;3642.907127861684;3869.6323849234277;3976.483057202603;3981.960180398159;3404.829758512114;4070.470838790938;4072;4075.857344992911;4087.3037689358584;3705.6664900159117;4641.721496710208;4719.197736384823;4740.431148262231;4756.262779269219;4736.052789442126;5227.372715274191;5332.066888294533;5434.755436005389;5763.423657634287;1279.8848103670714;1298.7402519496138;1371.1567386057502;1377.3388773388829;1379.2345248387041;768.3814529303964;775.5114278004821;806.8587997100484;896.5562046786882;968.5060619084318;4844.975775121102;5303.522682958553;5330.307060082165;5341.545968592635;5359.839204823861;5157.355330583677;5846.473817356458;5859.970700146558;5867.442592953663;5899.84061236827;4029.5366032906004;5025.623078269145;5085.118506152673;5122.410922743858;5368.4631536846;2844.857757112136;3041.7414519766066;3055.908322750321;3057.7248047675816;3067.8159310441447;5748.540116790647;6024.57827952048;6052.576319657582;6054.455099041107;6085.939140608537;5458.863528411822;5962.61243019211;6072.848178795565;6074.453299203093;6102.7794441111955;5088.491150884956;5282.128448805904;6099.725512351866;6109.786157484556;6169.321374648785;4939.382577177853;5534.225208470898;5910.940890591039;5925.525957923442;5948.672822994604;4284.892877678083;5663.782286808356;5782.537397008314;5849.327327357409;5867.596793859466;5718.370979192202;5723.284589426322;6124.295705993868;6138.723757430837;6154.076813513545;3897.7661340319673;3960.3663413853646;3967.8691572901585;3996.2407142642805;4006.2988976928336;5545.724483368767;5702.287214098238;6048.395160484092;6089.299730530868;6097.878042439215;5200.6619569727845;5231.913171228993;5317.25558421829;5514.503694667339;5910.024845900551;5113.798257409431;5762.884742305214;5796.391378905323;5807.402964184883;5808.883822323614;4408.515063343094;4676.953230467651;4747.881302967454;5169.870753231199;5380.3589905079325;4585.633149347977;5022.634834660859;5450.427705090909;5721.485066344049;5791.438265466792;4740.502247263988;5692.743826527733;5694.94981806541;5703;5727.914081288701 16:29:37 INFO - PROCESS | 1655 | |5;yui.html;666.313696892201;755.305119290253;764.6253335865424;801.3469022746465;803.5259197073725;15084.547463576097;15672.373105075783;15694.921525392385;15759.684806303867;16015.679686406265;14139.787903181435;14275;14281.78577321341;14305;14306.570802875904;662.9337066293356;666.070831190489;689.1282527602586;825.7398919340403;845.5729856422498;846.1876598465481;848.9575521223915;851.1190917400492;852.0286872964825;856.3662889461784;889.4618755652834;889.9510526921017;890.2833219258495;890.4924193209873;893.7720881175292;700.3626699703296;717.1645033535924;872.650939624149;883.0330787787362;888.506878682331;667.7629441548278;667.7896462614309;667.9198496180489;668.3683918696834;668.4284936379374;14757.704845903128;14784;14804.481843135438;14810.333534990843;14811.333489992976;13574.253416062113;13692.65768355789;13764.449422023106;13881.4447422103;13993.510227142104;12717.74564508714;12743.553975610808;12909.483620655163;12953.612265467125;12966.935165324114;4337.349397590355;4529.932051019237;4577.084583083363;4673.883152921204;4777.235642297216;116811;119376;119539.80460195374;119859.40070299593;120923.79076209213;10742.3017503862;10796.89203107959;10974.231803773659;11034.117270618492;11327.546898124148;21629.459263518536;21651.675224871324;21660.35018949434;21672.89163554172;21704.782952170593;7836.451448398671;7898.802529936797;7939.801504962306;7970.920290797134;8174.591270436455;4090.3455447128317;4379.627731642782;4407.801648925806;4472.8210871565425;4493.820247190141;2844.687084420712;3491.354099491602;3493.5458390409085;3493.6681015303507;3500.6149323574896;567.6991194666833;568.9829305120853;585.2771826793906;596.153462083842;601.0413390641919;467.17543535659587;559.2757379193954;600.5105838741412;605.1890466774544;606.6996836565928;7882;8434.367422443342;9035.864462033074;9109.817803644022;9222.53887305632;581.2008488328548;586.9698678818646;627.2504357293083;629.9842503937439;649.7693318871827;4012.197560487914;4318.265894797892;4424.380586717862;4554.24854898938;4555.248384016666;147427;153785.23107384393;155348;159106;160301.96225471815;121142.06866210322;123547.76452235543;139910;141002;145188;105248;109670;117465.9999999983;119379;119887.41745029534;8633.784155396166;8660.740177794676;8685.759904201128;8763;8850.778730531789;66665.00002500063;67112.06982643281;67726;68784;69549;62418.75162496816;62808.057879132764;65421;91449;103563;11136;11378.31730096197;11441.256318339281;11525.82711259315;12032.518699252281;12044.578439754918;12109.69725756828;12384.318862462651;12689.746205076031;12730.427130778951;10310;12007.099467540143;12106.576269830513;12110.878891210976;12419.875801241871;13902.51341203053;13948.930255348658;14291.857081429052;14550.636234094232;14571.198584077554;13002.674933126747;13064.804027939399;13787.586372408843;14080.788788168391;14351.569452916428;10628.545722640018;11254.83117753218;11329.773404531697;11518.481668324777;11730.530778768754;10372.585096596353;11131.53969284446;11483.655490335303;11731.589394371156;11755.823662645238 16:29:37 INFO - PROCESS | 1655 | __end_tp_report 16:29:37 INFO - PROCESS | 1655 | __start_cc_report 16:29:37 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,1433 16:29:37 INFO - PROCESS | 1655 | __end_cc_report 16:29:37 INFO - PROCESS | 1655 | __startTimestamp1475105377068__endTimestamp 16:29:37 INFO - PROCESS | 1655 | 16:29:37 INFO - PROCESS | 1655 | ------- Summary: start ------- 16:29:37 INFO - PROCESS | 1655 | Number of tests: 6 16:29:37 INFO - PROCESS | 1655 | 16:29:37 INFO - PROCESS | 1655 | [#0] dojo.html Cycles:165 Average:5717.49 Median:5880.74 stddev:633.27 (10.8%) stddev-sans-first:606.98 16:29:37 INFO - PROCESS | 1655 | Values: 3334.1 3346.7 3366.5 3387.2 3401.4 5590.4 5698.1 5715.4 5834.3 5841.8 5635.6 5649.2 5798.1 5800.0 5801.8 5100.3 5151.5 5164.0 5219.2 5227.7 5308.3 5321.3 5322.5 5334.1 5378.4 5273.4 5283.8 5297.8 5313.1 5376.1 5116.5 5156.6 5197.7 5208.5 5212.1 5159.1 5171.7 5180.7 5183.9 5187.5 5683.0 5702.3 5737.0 5745.8 5762.0 6100.2 6149.2 6152.3 6215.6 6269.7 6236.9 6247.1 6250.0 6266.8 6284.7 5796.7 5833.5 5834.5 5837.2 5880.7 6134.8 6181.6 6278.0 6283.3 6283.7 5980.3 6007.4 6019.5 6058.7 6069.6 6120.8 6123.7 6126.6 6136.8 6158.4 6013.7 6058.2 6071.5 6077.7 6084.0 6158.5 6221.2 6230.7 6277.0 6283.4 4910.8 4915.2 4919.7 4923.1 4936.4 4431.0 4454.1 4480.9 4499.0 4520.2 5693.5 5695.5 5786.3 5831.1 5838.5 5267.9 6262.2 6313.2 6330.7 6338.0 5770.5 5786.1 5787.0 5787.7 5798.2 4545.7 5276.7 5284.8 5318.7 5344.4 6290.7 6298.4 6300.4 6320.6 6328.8 6286.3 6286.9 6289.2 6304.1 6309.8 6247.2 6276.2 6295.7 6317.1 6344.7 6113.7 6122.9 6161.8 6220.3 6225.1 4819.9 5167.9 6177.1 6265.7 6272.6 5891.9 6059.7 6094.8 6125.5 6138.0 5967.7 6026.8 6051.2 6108.5 6115.8 5867.8 5913.6 5927.7 5963.1 5970.1 6051.0 6073.2 6075.5 6079.6 6090.6 5770.6 5830.6 5840.6 5866.5 5923.6 16:29:37 INFO - PROCESS | 1655 | 16:29:37 INFO - PROCESS | 1655 | [#1] ext.html Cycles:180 Average:68870.60 Median:41469.38 stddev:150774.48 (363.6%) stddev-sans-first:151110.01 16:29:37 INFO - PROCESS | 1655 | Values: 480.3 521.3 526.6 535.2 549.4 30595.0 40818.8 41896.0 41995.6 43941.1 43016.0 43486.3 43512.3 43518.0 43626.3 8687.8 9452.4 9573.0 9702.2 9744.3 9648.6 9767.0 9769.6 9779.3 9797.3 9758.9 9789.4 9807.0 9818.0 9826.6 9712.5 9729.2 9747.3 9760.1 9771.6 9584.9 9668.8 9674.7 9686.9 9690.0 40974.8 41455.4 41483.4 41534.0 41564.6 3224.3 3296.9 3297.6 3325.5 3389.3 30328.0 30736.1 30750.7 31369.0 31421.0 3033.4 3160.2 3180.0 3208.4 3315.2 466923.0 470076.6 470904.0 471241.0 472814.0 61675.4 61882.4 61883.0 61899.0 61937.7 34555.0 35154.0 35250.0 65177.7 65562.0 15452.9 15525.5 15635.2 15671.8 15713.2 11571.0 11953.9 12049.0 12064.5 12067.0 18795.7 18936.2 18977.6 19021.7 19047.8 6533.3 7540.2 7756.3 7796.0 7805.3 853.4 965.3 977.7 995.9 1080.6 29996.9 29999.9 30092.8 30173.2 30386.7 785.9 995.5 1060.7 1069.3 1087.0 12173.5 15218.7 15501.7 15577.9 15685.2 668572.0 669371.0 670157.0 670335.6 670493.0 57166.0 57554.7 57682.0 57737.7 57759.7 1217.0 1254.1 1318.5 1327.8 1362.1 27580.9 28360.0 28862.7 28877.4 29490.4 49476.0 50018.5 50154.2 50212.0 50217.7 528499.4 531247.0 532013.0 536140.0 536842.0 24791.2 25033.6 26383.9 27343.9 28235.0 47188.3 47773.0 47907.8 48439.5 48627.5 32571.7 38457.2 38815.2 39178.0 40156.6 38739.8 38780.6 39587.0 40018.0 40129.2 36757.3 37483.3 38048.0 38303.0 38664.6 28053.0 33013.2 34625.7 34747.7 34838.0 24803.0 24822.5 25123.4 25655.6 25774.1 16:29:37 INFO - PROCESS | 1655 | 16:29:37 INFO - PROCESS | 1655 | [#2] jquery.html Cycles:180 Average:33727.52 Median:1719.48 stddev:96238.57 (5597.0%) stddev-sans-first:96475.82 16:29:37 INFO - PROCESS | 1655 | Values: 304.0 359.3 362.7 374.3 399.1 15100.4 15129.5 16289.9 20474.8 21044.9 19331.5 19878.9 20215.5 20543.9 20773.8 5880.1 6129.4 6133.2 6133.8 6155.2 4194.5 4400.6 4582.6 6015.4 6086.7 4435.8 4443.8 6340.8 6699.3 6703.0 5438.3 6599.7 6641.9 6693.9 6738.6 5775.0 5780.0 5784.9 5791.5 5795.6 25900.4 26264.9 26363.7 26678.6 26683.0 2196.0 2252.9 2273.6 2905.3 3079.6 13213.7 13494.8 15211.4 15450.7 15918.9 1821.7 1827.3 1942.3 1949.6 1995.4 91634.0 127191.4 128379.4 128946.0 129258.0 10418.9 10504.9 11451.7 11461.4 11706.9 14509.9 14535.9 14537.6 14546.8 14566.3 6850.4 8048.4 8320.3 8358.5 8476.3 8143.2 8147.5 8167.1 8318.3 8325.6 4385.4 4494.4 4505.8 4509.1 4587.6 1297.4 1585.9 1847.5 1889.1 1897.4 739.2 782.8 955.8 991.5 999.9 15575.8 20003.5 20846.4 21035.5 21094.5 918.2 926.6 947.3 953.0 1016.3 9470.0 10248.7 10807.6 10889.6 10919.6 566434.0 570040.0 574370.0 588095.0 588498.0 72638.3 73396.0 105092.0 126233.0 129166.0 979.0 983.0 1011.5 1018.4 1027.0 18673.3 18949.8 19160.9 19206.0 19299.0 41812.4 54915.8 54954.0 55130.4 56565.7 65904.0 80438.4 84620.0 85296.0 85654.6 7086.3 7326.2 7375.0 7941.3 8389.0 10057.7 12399.0 13100.0 13428.9 13640.8 11113.5 11355.3 11506.9 11564.9 11658.4 8276.2 11076.1 11263.8 11388.4 11456.1 8592.1 9261.8 11170.9 11284.5 11850.3 7152.9 7343.2 7446.4 7493.1 7640.5 4033.8 4580.3 10657.6 10740.4 11434.8 16:29:37 INFO - PROCESS | 1655 | 16:29:37 INFO - PROCESS | 1655 | [#3] mootools.html Cycles:180 Average:5635.24 Median:4888.80 stddev:3149.08 (64.4%) stddev-sans-first:3141.19 16:29:37 INFO - PROCESS | 1655 | Values: 1317.4 1413.7 1473.2 1811.0 1837.9 8293.4 8820.6 8838.5 8842.6 8845.7 7755.5 8001.8 8012.2 8396.8 8617.5 2327.6 2372.8 2374.5 2375.7 2376.0 2332.8 2338.4 2341.0 2378.7 2387.4 2336.1 2342.1 2353.8 2362.3 2373.4 2345.2 2359.3 2365.6 2368.3 2368.5 2335.1 2337.7 2341.7 2342.1 2348.6 8728.8 8788.0 8801.0 8813.8 8814.3 5135.3 5287.6 5300.4 5300.9 5307.8 3465.2 3516.1 3687.7 3691.5 3702.4 1058.7 1059.8 1080.2 1080.7 1098.2 11341.7 11469.3 11475.4 11483.7 11552.4 4507.3 4557.1 4717.9 4732.9 4732.9 9843.5 9849.0 9855.2 9862.1 9877.8 5254.9 5258.0 5261.6 5266.7 5280.6 4320.0 4326.1 4545.7 4831.2 4832.5 3778.2 3825.3 3928.1 3932.8 3960.7 1245.2 1266.3 1267.3 1279.1 1279.6 838.9 853.0 877.6 892.6 907.5 5455.7 5577.5 5700.9 5814.7 5823.2 914.6 988.3 1018.5 1032.1 1059.0 3804.2 3807.1 3811.4 3912.7 3941.7 10359.1 10653.3 10654.4 10657.1 10677.7 10537.4 10632.7 10639.7 10643.9 10662.1 7200.6 7886.9 8093.5 8225.8 8242.5 5057.2 5569.3 5591.6 5646.0 5851.4 9958.6 9988.7 10029.0 10069.2 10084.2 9469.0 9874.8 9886.5 10013.9 10017.4 6833.8 7045.4 7351.9 7501.4 7524.9 7946.4 8092.2 8156.8 8305.3 8312.5 7305.1 7444.5 7495.6 7520.3 7570.6 7109.6 7254.9 7324.1 7337.8 7429.9 6846.9 6882.7 6955.6 6959.9 6973.8 4881.1 4921.3 4944.8 4963.6 5030.1 4825.4 4851.7 4896.5 4974.2 4988.3 16:29:37 INFO - PROCESS | 1655 | 16:29:37 INFO - PROCESS | 1655 | [#4] prototype.html Cycles:180 Average:4585.32 Median:5106.86 stddev:1422.27 (27.9%) stddev-sans-first:1389.23 16:29:37 INFO - PROCESS | 1655 | Values: 289.4 295.5 302.8 305.9 306.1 4260.6 5163.1 5437.8 5488.5 5526.8 4469.6 4635.5 5022.5 5042.6 5232.7 3752.3 3768.8 4079.8 4104.6 4127.9 4070.8 4111.7 4126.6 4135.0 4186.7 3254.3 3899.8 4072.0 4125.5 4163.8 3362.7 4106.5 4116.6 4122.2 4146.5 3558.5 3563.1 3571.7 3575.9 3595.6 4395.2 5144.1 5232.7 5281.6 5373.4 4690.5 4707.2 4714.0 4720.1 4735.0 5099.9 5349.0 5702.5 5704.9 5709.5 3919.2 3926.4 3942.5 3958.7 3967.4 5659.4 5859.7 5909.6 5935.7 5998.3 3329.3 3642.9 3869.6 3976.5 3982.0 3404.8 4070.5 4072.0 4075.9 4087.3 3705.7 4641.7 4719.2 4740.4 4756.3 4736.1 5227.4 5332.1 5434.8 5763.4 1279.9 1298.7 1371.2 1377.3 1379.2 768.4 775.5 806.9 896.6 968.5 4845.0 5303.5 5330.3 5341.5 5359.8 5157.4 5846.5 5860.0 5867.4 5899.8 4029.5 5025.6 5085.1 5122.4 5368.5 2844.9 3041.7 3055.9 3057.7 3067.8 5748.5 6024.6 6052.6 6054.5 6085.9 5458.9 5962.6 6072.8 6074.5 6102.8 5088.5 5282.1 6099.7 6109.8 6169.3 4939.4 5534.2 5910.9 5925.5 5948.7 4284.9 5663.8 5782.5 5849.3 5867.6 5718.4 5723.3 6124.3 6138.7 6154.1 3897.8 3960.4 3967.9 3996.2 4006.3 5545.7 5702.3 6048.4 6089.3 6097.9 5200.7 5231.9 5317.3 5514.5 5910.0 5113.8 5762.9 5796.4 5807.4 5808.9 4408.5 4677.0 4747.9 5169.9 5380.4 4585.6 5022.6 5450.4 5721.5 5791.4 4740.5 5692.7 5694.9 5703.0 5727.9 16:29:37 INFO - PROCESS | 1655 | 16:29:37 INFO - PROCESS | 1655 | [#5] yui.html Cycles:180 Average:25189.88 Median:12274.74 stddev:41120.98 (335.0%) stddev-sans-first:41195.11 16:29:37 INFO - PROCESS | 1655 | Values: 666.3 755.3 764.6 801.3 803.5 15084.5 15672.4 15694.9 15759.7 16015.7 14139.8 14275.0 14281.8 14305.0 14306.6 662.9 666.1 689.1 825.7 845.6 846.2 849.0 851.1 852.0 856.4 889.5 890.0 890.3 890.5 893.8 700.4 717.2 872.7 883.0 888.5 667.8 667.8 667.9 668.4 668.4 14757.7 14784.0 14804.5 14810.3 14811.3 13574.3 13692.7 13764.4 13881.4 13993.5 12717.7 12743.6 12909.5 12953.6 12966.9 4337.3 4529.9 4577.1 4673.9 4777.2 116811.0 119376.0 119539.8 119859.4 120923.8 10742.3 10796.9 10974.2 11034.1 11327.5 21629.5 21651.7 21660.4 21672.9 21704.8 7836.5 7898.8 7939.8 7970.9 8174.6 4090.3 4379.6 4407.8 4472.8 4493.8 2844.7 3491.4 3493.5 3493.7 3500.6 567.7 569.0 585.3 596.2 601.0 467.2 559.3 600.5 605.2 606.7 7882.0 8434.4 9035.9 9109.8 9222.5 581.2 587.0 627.3 630.0 649.8 4012.2 4318.3 4424.4 4554.2 4555.2 147427.0 153785.2 155348.0 159106.0 160302.0 121142.1 123547.8 139910.0 141002.0 145188.0 105248.0 109670.0 117466.0 119379.0 119887.4 8633.8 8660.7 8685.8 8763.0 8850.8 66665.0 67112.1 67726.0 68784.0 69549.0 62418.8 62808.1 65421.0 91449.0 103563.0 11136.0 11378.3 11441.3 11525.8 12032.5 12044.6 12109.7 12384.3 12689.7 12730.4 10310.0 12007.1 12106.6 12110.9 12419.9 13902.5 13948.9 14291.9 14550.6 14571.2 13002.7 13064.8 13787.6 14080.8 14351.6 10628.5 11254.8 11329.8 11518.5 11730.5 10372.6 11131.5 11483.7 11731.6 11755.8 16:29:37 INFO - PROCESS | 1655 | -------- Summary: end -------- 16:29:37 INFO - PROCESS | 1655 | 16:29:37 INFO - TEST-INFO | 1655: exit 0 16:29:37 INFO - TEST-OK | dromaeo_css | took 1117868ms 16:29:37 INFO - TEST-START | kraken 16:29:37 INFO - operating with platform_type : mac_ 16:29:37 INFO - Initialising browser for kraken test... 16:29:37 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/tmpSv3B93/profile http://localhost:49276/getInfo.html) 16:29:39 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 16:29:39 INFO - PROCESS | 1661 | colorDepth:24 16:29:39 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 16:29:39 INFO - PROCESS | 1661 | __metrics 16:29:40 INFO - PROCESS | 1661 | 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" 16:29:40 INFO - PROCESS | 1661 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:29:40 INFO - TEST-INFO | 1661: exit 0 16:29:40 INFO - Browser initialized. 16:29:40 INFO - Running cycle 1/1 for kraken test... 16:29:40 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/tmpSv3B93/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:29:52 INFO - PROCESS | 1662 | RSS: Main: 236335104 16:29:52 INFO - PROCESS | 1662 | 16:29:55 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 16:29:56 INFO - PROCESS | 1662 | RSS: Main: 295071744 16:29:56 INFO - PROCESS | 1662 | 16:29:59 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 16:29:59 INFO - PROCESS | 1662 | RSS: Main: 293593088 16:29:59 INFO - PROCESS | 1662 | 16:30:03 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 16:30:03 INFO - PROCESS | 1662 | RSS: Main: 385662976 16:30:03 INFO - PROCESS | 1662 | 16:30:05 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 16:30:06 INFO - PROCESS | 1662 | RSS: Main: 286187520 16:30:06 INFO - PROCESS | 1662 | 16:30:08 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:30:09 INFO - PROCESS | 1662 | RSS: Main: 290406400 16:30:09 INFO - PROCESS | 1662 | 16:30:11 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 16:30:12 INFO - PROCESS | 1662 | RSS: Main: 327639040 16:30:12 INFO - PROCESS | 1662 | 16:30:15 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 16:30:16 INFO - PROCESS | 1662 | RSS: Main: 328814592 16:30:16 INFO - PROCESS | 1662 | 16:30:19 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 16:30:19 INFO - PROCESS | 1662 | RSS: Main: 327569408 16:30:19 INFO - PROCESS | 1662 | 16:30:21 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:30:22 INFO - PROCESS | 1662 | RSS: Main: 301015040 16:30:22 INFO - PROCESS | 1662 | 16:30:23 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:30:24 INFO - PROCESS | 1662 | RSS: Main: 305291264 16:30:24 INFO - PROCESS | 1662 | 16:30:26 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:30:27 INFO - PROCESS | 1662 | RSS: Main: 306798592 16:30:27 INFO - PROCESS | 1662 | 16:30:30 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:30:31 INFO - PROCESS | 1662 | RSS: Main: 321794048 16:30:31 INFO - PROCESS | 1662 | 16:30:34 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:30:34 INFO - PROCESS | 1662 | RSS: Main: 318074880 16:30:34 INFO - PROCESS | 1662 | 16:30:36 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 16:30:36 INFO - PROCESS | 1662 | RSS: Main: 361869312 16:30:36 INFO - PROCESS | 1662 | 16:30:36 INFO - PROCESS | 1662 | __start_tp_report 16:30:36 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 16:30:36 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 16:30:36 INFO - PROCESS | 1662 | |i|pagename|runs| 16:30:36 INFO - PROCESS | 1662 | |0;ai-astar;114;112;112;112;111;113;113;112;112;113 16:30:36 INFO - PROCESS | 1662 | |1;audio-beat-detection;145;111;112;145;109;109;108;107;146;140 16:30:36 INFO - PROCESS | 1662 | |2;audio-dft;186;183;182;183;182;182;180;184;184;186 16:30:36 INFO - PROCESS | 1662 | |3;audio-fft;79;58;60;87;62;70;58;58;83;58 16:30:36 INFO - PROCESS | 1662 | |4;audio-oscillator;85;71;90;70;69;71;91;68;69;90 16:30:36 INFO - PROCESS | 1662 | |5;imaging-gaussian-blur;92;127;92;89;91;89;92;89;92;89 16:30:36 INFO - PROCESS | 1662 | |6;imaging-darkroom;100;121;159;154;100;106;99;113;153;162 16:30:36 INFO - PROCESS | 1662 | |7;imaging-desaturate;84;86;86;84;86;85;84;84;102;143 16:30:36 INFO - PROCESS | 1662 | |8;json-parse-financial;55;53;53;53;75;43;44;53;68;78 16:30:36 INFO - PROCESS | 1662 | |9;json-stringify-tinderbox;63;72;75;74;74;70;71;69;69;70 16:30:36 INFO - PROCESS | 1662 | |10;stanford-crypto-aes;87;88;89;85;88;87;94;105;106;89 16:30:36 INFO - PROCESS | 1662 | |11;stanford-crypto-ccm;143;140;158;153;152;158;142;139;184;156 16:30:36 INFO - PROCESS | 1662 | |12;stanford-crypto-pbkdf2;172;168;169;169;171;168;169;168;167;221 16:30:36 INFO - PROCESS | 1662 | |13;stanford-crypto-sha256-iterative;68;68;67;69;67;67;67;67;67;66 16:30:36 INFO - PROCESS | 1662 | __end_tp_report 16:30:36 INFO - PROCESS | 1662 | __start_cc_report 16:30:36 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,1997 16:30:36 INFO - PROCESS | 1662 | __end_cc_report 16:30:36 INFO - PROCESS | 1662 | __startTimestamp1475105436701__endTimestamp 16:30:36 INFO - PROCESS | 1662 | 16:30:36 INFO - PROCESS | 1662 | ------- Summary: start ------- 16:30:36 INFO - PROCESS | 1662 | Number of tests: 14 16:30:36 INFO - PROCESS | 1662 | 16:30:36 INFO - PROCESS | 1662 | [#0] ai-astar Cycles:10 Average:112.40 Median:112.50 stddev:0.84 (0.7%) stddev-sans-first:0.67 16:30:36 INFO - PROCESS | 1662 | Values: 114.0 112.0 112.0 112.0 111.0 113.0 113.0 112.0 112.0 113.0 16:30:36 INFO - PROCESS | 1662 | 16:30:36 INFO - PROCESS | 1662 | [#1] audio-beat-detection Cycles:10 Average:123.20 Median:126.00 stddev:18.02 (14.3%) stddev-sans-first:17.30 16:30:36 INFO - PROCESS | 1662 | Values: 145.0 111.0 112.0 145.0 109.0 109.0 108.0 107.0 146.0 140.0 16:30:36 INFO - PROCESS | 1662 | 16:30:36 INFO - PROCESS | 1662 | [#2] audio-dft Cycles:10 Average:183.20 Median:183.50 stddev:1.87 (1.0%) stddev-sans-first:1.69 16:30:36 INFO - PROCESS | 1662 | Values: 186.0 183.0 182.0 183.0 182.0 182.0 180.0 184.0 184.0 186.0 16:30:36 INFO - PROCESS | 1662 | 16:30:36 INFO - PROCESS | 1662 | [#3] audio-fft Cycles:10 Average:67.30 Median:66.00 stddev:11.58 (17.5%) stddev-sans-first:11.48 16:30:36 INFO - PROCESS | 1662 | Values: 79.0 58.0 60.0 87.0 62.0 70.0 58.0 58.0 83.0 58.0 16:30:36 INFO - PROCESS | 1662 | 16:30:36 INFO - PROCESS | 1662 | [#4] audio-oscillator Cycles:10 Average:77.40 Median:78.00 stddev:10.15 (13.0%) stddev-sans-first:10.38 16:30:36 INFO - PROCESS | 1662 | Values: 85.0 71.0 90.0 70.0 69.0 71.0 91.0 68.0 69.0 90.0 16:30:36 INFO - PROCESS | 1662 | 16:30:36 INFO - PROCESS | 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:94.20 Median:91.50 stddev:11.61 (12.7%) stddev-sans-first:12.29 16:30:36 INFO - PROCESS | 1662 | Values: 92.0 127.0 92.0 89.0 91.0 89.0 92.0 89.0 92.0 89.0 16:30:36 INFO - PROCESS | 1662 | 16:30:36 INFO - PROCESS | 1662 | [#6] imaging-darkroom Cycles:10 Average:126.70 Median:153.50 stddev:27.01 (17.6%) stddev-sans-first:26.87 16:30:36 INFO - PROCESS | 1662 | Values: 100.0 121.0 159.0 154.0 100.0 106.0 99.0 113.0 153.0 162.0 16:30:36 INFO - PROCESS | 1662 | 16:30:36 INFO - PROCESS | 1662 | [#7] imaging-desaturate Cycles:10 Average:92.40 Median:84.50 stddev:18.60 (22.0%) stddev-sans-first:19.47 16:30:36 INFO - PROCESS | 1662 | Values: 84.0 86.0 86.0 84.0 86.0 85.0 84.0 84.0 102.0 143.0 16:30:36 INFO - PROCESS | 1662 | 16:30:36 INFO - PROCESS | 1662 | [#8] json-parse-financial Cycles:10 Average:57.50 Median:54.00 stddev:12.09 (22.4%) stddev-sans-first:12.79 16:30:36 INFO - PROCESS | 1662 | Values: 55.0 53.0 53.0 53.0 75.0 43.0 44.0 53.0 68.0 78.0 16:30:36 INFO - PROCESS | 1662 | 16:30:36 INFO - PROCESS | 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:70.70 Median:71.50 stddev:3.47 (4.8%) stddev-sans-first:2.30 16:30:36 INFO - PROCESS | 1662 | Values: 63.0 72.0 75.0 74.0 74.0 70.0 71.0 69.0 69.0 70.0 16:30:36 INFO - PROCESS | 1662 | 16:30:36 INFO - PROCESS | 1662 | [#10] stanford-crypto-aes Cycles:10 Average:91.80 Median:88.00 stddev:7.58 (8.6%) stddev-sans-first:7.84 16:30:36 INFO - PROCESS | 1662 | Values: 87.0 88.0 89.0 85.0 88.0 87.0 94.0 105.0 106.0 89.0 16:30:36 INFO - PROCESS | 1662 | 16:30:36 INFO - PROCESS | 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:152.50 Median:154.50 stddev:13.35 (8.6%) stddev-sans-first:13.71 16:30:36 INFO - PROCESS | 1662 | Values: 143.0 140.0 158.0 153.0 152.0 158.0 142.0 139.0 184.0 156.0 16:30:36 INFO - PROCESS | 1662 | 16:30:36 INFO - PROCESS | 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.20 Median:169.00 stddev:16.51 (9.8%) stddev-sans-first:17.49 16:30:36 INFO - PROCESS | 1662 | Values: 172.0 168.0 169.0 169.0 171.0 168.0 169.0 168.0 167.0 221.0 16:30:36 INFO - PROCESS | 1662 | 16:30:36 INFO - PROCESS | 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.00 stddev:0.82 (1.2%) stddev-sans-first:0.83 16:30:36 INFO - PROCESS | 1662 | Values: 68.0 68.0 67.0 69.0 67.0 67.0 67.0 67.0 67.0 66.0 16:30:36 INFO - PROCESS | 1662 | -------- Summary: end -------- 16:30:36 INFO - PROCESS | 1662 | 16:30:37 INFO - TEST-INFO | 1662: exit 0 16:30:37 INFO - TEST-OK | kraken | took 59899ms 16:30:37 INFO - SUITE-END | took 1177s 16:30:37 INFO - Completed test suite (00:19:38) 16:30:37 INFO - javascript benchmark 16:30:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3334.099793055875, 3346.748993825464, 3366.51185578091, 3387.1870751019733, 3401.35374278887, 5590.385057643656, 5698.145278208271, 5715.399883012281, 5834.27071616048, 5841.7955371562, 5635.5773317001185, 5649.23735295736, 5798.10129429939, 5799.971000144993, 5801.767929282823, 5100.260462232974, 5151.459096794839, 5163.974180129094, 5219.164933610623, 5227.660202086852, 5308.283381743473, 5321.255024296602, 5322.494363035525, 5334.119870221409, 5378.381486129087, 5273.446288139767, 5283.815066472655, 5297.814576489824, 5313.07021271276, 5376.059189641816, 5116.51393117657, 5156.639035267533, 5197.714125723083, 5208.453112423179, 5212.087884620177, 5159.1229490986625, 5171.663841850305, 5180.740962951837, 5183.948160518385, 5187.507186817246, 5683.0, 5702.287214098238, 5737.024705800024, 5745.798897038625, 5761.971190144064, 6100.157317757059, 6149.2313460817395, 6152.2924863640355, 6215.595986260879, 6269.749210031593, 6236.906446403307, 6247.062940558907, 6249.96875015622, 6266.843328916768, 6284.748610055638, 5796.681182534959, 5833.4749872511675, 5834.533237341087, 5837.241158649434, 5880.7353669085, 6134.77336474251, 6181.629102253879, 6278.0269058296035, 6283.3402492737605, 6283.717232724538, 5980.342162362137, 6007.429294217042, 6019.458248757633, 6058.666773327421, 6069.575129740964, 6120.816375508742, 6123.683408067287, 6126.601770884968, 6136.846578835565, 6158.414950579688, 6013.6993150342305, 6058.242719660043, 6071.483923866521, 6077.696115194222, 6083.969580152071, 6158.507319414436, 6221.160143380676, 6230.688465576703, 6276.968615156987, 6283.403076707706, 4910.803567857246, 4915.188993816052, 4919.65562410635, 4923.113839508923, 4936.3829521309835, 4431.047324825114, 4454.086912182995, 4480.865574032784, 4498.977505112519, 4520.186366454004, 5693.544516438675, 5695.5443564514735, 5786.334571524244, 5831.125331200269, 5838.4745372915795, 5267.894642107136, 6262.185915830913, 6313.2424109107205, 6330.746770129235, 6338.0, 5770.480656740913, 5786.132080187921, 5787.0, 5787.739551720183, 5798.188253644493, 4545.65907556928, 5276.65701729394, 5284.841454756363, 5318.73406329682, 5344.358676958712, 6290.748370065146, 6298.433141017331, 6300.3699630037545, 6320.5891617044745, 6328.810135695937, 6286.27707813589, 6286.874262514815, 6289.213848268966, 6304.148939893055, 6309.842253943688, 6247.156633854372, 6276.184096067482, 6295.748170073329, 6317.115603815561, 6344.746210151542, 6113.749968914014, 6122.928487514756, 6161.784337548164, 6220.315765265726, 6225.128482012431, 4819.855404337875, 5167.948320516747, 6177.073438984188, 6265.718042688181, 6272.6236425814595, 5891.852703682443, 6059.666718330534, 6094.786682466092, 6125.479334256638, 6137.96931015342, 5967.7016149191495, 6026.819195424145, 6051.183090282755, 6108.45023947829, 6115.816525504242, 5867.794627188028, 5913.615614985012, 5927.733252003756, 5963.105534169909, 5970.104484327386, 6051.0, 6073.210482637229, 6075.483583895419, 6079.604825686316, 6090.573659843769, 5770.596058276048, 5830.591858569859, 5840.620359676607, 5866.530677545701, 5923.555733319932], "value": 5678.093824070635, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [480.32993973407093, 521.2832355511172, 526.6076772804262, 535.1918602909608, 549.3846891481542, 30595.0, 40818.79590602047, 41895.99999999996, 41995.580044199545, 43941.12117757643, 43016.0, 43486.34770478438, 43512.347314790306, 43518.0, 43626.34560481595, 8687.782805429852, 9452.385594936308, 9572.994835542273, 9702.175315098226, 9744.31789774716, 9648.614055437774, 9767.0, 9769.609215631403, 9779.266555008368, 9797.26520510961, 9758.90241097587, 9789.363691360075, 9807.0, 9817.95091024544, 9826.606935722562, 9712.465814380206, 9729.221662267002, 9747.268954828416, 9760.072793084646, 9771.560279787427, 9584.856227156599, 9668.75828104296, 9674.661386851496, 9686.90313096867, 9689.951550242204, 40974.79512602447, 41455.37816932749, 41483.377749333486, 41534.0, 41564.58435415668, 3224.274538228903, 3296.9175770605925, 3297.6207736110414, 3325.451300535433, 3389.3390788796196, 30328.0, 30736.077917662507, 30750.692493075232, 31369.0, 31421.0, 3033.378157477734, 3160.1625569224175, 3179.952300715491, 3208.374366998436, 3315.1546355679147, 466923.0, 470076.64961675316, 470904.0, 471241.0, 472814.0, 61675.38324616786, 61882.381176187664, 61883.0, 61899.0, 61937.690311548155, 34555.0, 35154.0, 35250.0, 65177.674111630084, 65562.01656975149, 15452.922735386252, 15525.534233973, 15635.218239088, 15671.764923526156, 15713.21433928299, 11571.01646360052, 11953.880461195451, 12049.03607711381, 12064.457099430547, 12066.974307183813, 18795.718064229048, 18936.242550297833, 18977.620447591245, 19021.714674279898, 19047.80952190488, 6533.30134165111, 7540.208278130717, 7756.263155000266, 7795.986521752136, 7805.258500442449, 853.4239388412797, 965.280865755017, 977.7115750853516, 995.8954309797512, 1080.6055789636882, 29996.85001574978, 29999.850270262385, 30092.849535752182, 30173.245668858017, 30386.696133038386, 785.9174786647434, 995.4723996281982, 1060.7401186709305, 1069.3316677076828, 1086.9565217391373, 12173.452194651261, 15218.695626087638, 15501.689966200838, 15577.922110389602, 15685.235543469485, 668572.0, 669371.0, 670157.0, 670335.6483217552, 670493.0, 57166.0, 57554.712226439435, 57682.0, 57737.71131144317, 57759.71120144372, 1216.9756604867853, 1254.1471799176647, 1318.518740659633, 1327.7543654423962, 1362.0669841158838, 27580.862095689394, 28360.0, 28862.711372886002, 28877.422451551272, 29490.410191795614, 49476.0, 50018.49981500284, 50154.24768628547, 50212.0, 50217.74891125521, 528499.3575032101, 531247.0, 532013.0, 536140.0, 536842.0, 24791.174074092214, 25033.624495632215, 26383.868080659475, 27343.86328068347, 28235.0, 47188.29217561808, 47773.0, 47907.81382792469, 48439.5156048435, 48627.51372486371, 32571.674283256863, 38457.23085538329, 38815.22369552537, 39178.0, 40156.59843401646, 38739.83313087088, 38780.61219387883, 39587.0, 40018.0, 40129.1974160521, 36757.26485470329, 37483.250334993696, 38048.048798780255, 38303.0, 38664.6133538661, 28053.01814436485, 33013.17467063342, 34625.65374346325, 34747.65252347545, 34838.0, 24803.0, 24822.50354992926, 25123.371915702253, 25655.615165772157, 25774.09790657318], "value": 19662.805419370052, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.9574459575661, 359.3316431437527, 362.673593765611, 374.31873989339414, 399.0721572344298, 15100.395984160634, 15129.470468533604, 16289.918550407247, 20474.795252047476, 21044.8947755261, 19331.516712082204, 19878.900605496987, 20215.494612634655, 20543.897280513578, 20773.792262077375, 5880.147378630096, 6129.417705317987, 6133.2026836511195, 6133.846653833668, 6155.19982402287, 4194.517630472489, 4400.581944715263, 4582.564656357641, 6015.368386319417, 6086.726097325609, 4435.778211089449, 4443.84446544371, 6340.809775706736, 6699.296573859748, 6703.0, 5438.320209973754, 6599.70301336441, 6641.867162656768, 6693.933060669379, 6738.629375384352, 5774.960507108719, 5780.0, 5784.855378615526, 5791.507721843648, 5795.623284486537, 25900.35249118768, 26264.8686756565, 26363.73636263632, 26678.5998210027, 26683.0, 2196.0, 2252.8873556322283, 2273.57938781326, 2905.3172504461436, 3079.63044434667, 13213.678632136805, 13494.797578036338, 15211.391544338214, 15450.690986180325, 15918.920405398014, 1821.681205788995, 1827.259959716317, 1942.2716481319505, 1949.6003319319504, 1995.4213278149318, 91634.0, 127191.36404318012, 128379.35810320888, 128946.0, 129258.0, 10418.947905260426, 10504.894951050544, 11451.656450306504, 11461.4269286537, 11706.941465292619, 14509.911756618296, 14535.927320363542, 14537.636559085897, 14546.781798273034, 14566.271686415848, 6850.38346548803, 8048.396370272253, 8320.292775114061, 8358.45670031446, 8476.279516241066, 8143.226393492609, 8147.511149331059, 8167.142450042778, 8318.334533237326, 8325.583720813935, 4385.386045953569, 4494.3707880896045, 4505.774711264424, 4509.098180363941, 4587.61005314552, 1297.3772589157256, 1585.8969167004109, 1847.4827048426462, 1889.1404410992966, 1897.3928342930406, 739.1868944161487, 782.765170448863, 955.7945041816043, 991.4844280973997, 999.8650182225307, 15575.831812614098, 20003.499737519745, 20846.374608761762, 21035.47411314699, 21094.47263818417, 918.2470374293016, 926.559884055082, 947.251671179774, 952.9523523823781, 1016.2682868334934, 9470.0, 10248.692539223835, 10807.621733239299, 10889.618863339745, 10919.617813376493, 566434.0, 570040.0, 574370.0, 588095.0, 588498.0, 72638.27361726316, 73396.0, 105092.01625845532, 126233.0, 129166.0, 978.9510524473748, 982.9754256143653, 1011.4689787861431, 1018.4042335233971, 1026.9743256418649, 18673.346432874783, 18949.810501894804, 19160.946147961997, 19206.039698015324, 19299.0, 41812.3728144072, 54915.82020315176, 54954.0, 55130.44869551413, 56565.71717141388, 65904.0, 80438.3912321762, 84620.0, 85296.0, 85654.57172714097, 7086.326798954091, 7326.194118647051, 7374.963125184339, 7941.324987376138, 8388.958055209685, 10057.69826905194, 12399.008079353449, 13100.017498687446, 13428.865711343153, 13640.839543939193, 11113.499892504695, 11355.300402663408, 11506.884931150582, 11564.884351156381, 11658.417079146178, 8276.241161332138, 11076.058535024613, 11263.7747245053, 11388.430578471209, 11456.083513318748, 8592.097829727789, 9261.767693870468, 11170.944145279222, 11284.51740617063, 11850.348230847041, 7152.9284707152265, 7343.2289609590225, 7446.404287657083, 7493.063367079115, 7640.541567505745, 4033.818478168431, 4580.312953057068, 10657.626983055556, 10740.355578664992, 11434.771304574027], "value": 9372.182391647344, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1317.3676635215093, 1413.6607214268574, 1473.2486431919724, 1811.0, 1837.8805377650444, 8293.419460637759, 8820.60307286172, 8838.51388173651, 8842.646294148226, 8845.734627961172, 7755.457118001742, 8001.839963200747, 8012.198780121985, 8396.790080247996, 8617.526036068013, 2327.627579587266, 2372.78644921957, 2374.4538756086113, 2375.726791418983, 2375.964360534593, 2332.825038122148, 2338.38032921276, 2340.9765902341014, 2378.7283144596868, 2387.3673476528734, 2336.077249486452, 2342.074880422232, 2353.8469999450067, 2362.2558893948435, 2373.394784330002, 2345.2143531916827, 2359.28041947206, 2365.6333268343433, 2368.289513146049, 2368.549975504666, 2335.135999680112, 2337.742848286687, 2341.7189937207645, 2342.145117032271, 2348.6007378745485, 8728.825423491558, 8787.956060219722, 8801.031886492481, 8813.779655508599, 8814.338924580683, 5135.332406787131, 5287.629865909388, 5300.390455097675, 5300.92048619271, 5307.787688492455, 3465.220325426759, 3516.085817687394, 3687.723420743455, 3691.50164727761, 3702.3705969985167, 1058.6559368205287, 1059.7721489879714, 1080.206048554312, 1080.6649938519006, 1098.2477003252798, 11341.716457088556, 11469.254498457656, 11475.368854712988, 11483.712907177387, 11552.364619945984, 4507.346434766941, 4557.065801510682, 4717.9292310615365, 4732.858014259577, 4732.929006064912, 9843.507824608741, 9849.0, 9855.162311203629, 9862.112409883002, 9877.802443951081, 5254.92117618236, 5258.0, 5261.605379596545, 5266.683998960075, 5280.630355875129, 4320.0, 4326.113146804897, 4545.704529205591, 4831.202851529459, 4832.540908613676, 3778.1876896467393, 3825.254075455315, 3928.135810121769, 3932.783696896698, 3960.742551734128, 1245.1532957588745, 1266.303533056815, 1267.2966503590412, 1279.1301914698095, 1279.5777393460137, 838.9119142489951, 852.9892077887723, 877.6050777150308, 892.6474042753076, 907.5190149220923, 5455.727213639302, 5577.470140336662, 5700.857478563069, 5814.651120932757, 5823.242978412779, 914.5838643417229, 988.3328753091778, 1018.4907546226887, 1032.0969151992008, 1059.0, 3804.2391521695217, 3807.1053302473897, 3811.447340135665, 3912.7065470089856, 3941.704372172041, 10359.119474844572, 10653.254272200871, 10654.414007229676, 10657.094146997591, 10677.679669609925, 10537.42044187577, 10632.734181645366, 10639.680809575726, 10643.94678026605, 10662.093722033715, 7200.603966781878, 7886.881696774553, 8093.514389136553, 8225.753227403067, 8242.50544967316, 5057.216131499624, 5569.275994120739, 5591.608587398843, 5645.9611541125705, 5851.444112809277, 9958.601655933971, 9988.650397236062, 10029.0, 10069.194464442973, 10084.243681723754, 9469.0, 9874.753131171778, 9886.50567471638, 10013.899861001297, 10017.398956062369, 6833.829154271183, 7045.401140903081, 7351.8529629408185, 7501.362384197404, 7524.924750752422, 7946.404019698662, 8092.150324215872, 8156.8368632625825, 8305.294050005818, 8312.459690120122, 7305.050343455529, 7444.516106453063, 7495.550266983998, 7520.285572870568, 7570.5836179010685, 7109.608971506616, 7254.927450725425, 7324.121105467378, 7337.816554585965, 7429.888551671837, 6846.931530684629, 6882.69027893736, 6955.617441040791, 6959.89560156588, 6973.755918542826, 4881.096997055556, 4921.26181072842, 4944.752762361795, 4963.627727920492, 5030.119729047476, 4825.445073816556, 4851.684640498356, 4896.510348965075, 4974.17926042199, 4988.251762235548], "value": 4543.141498253622, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [289.3966080721695, 295.5124045325214, 302.84554877012715, 305.87764894042374, 306.10311786465644, 4260.550183644409, 5163.070647283487, 5437.782488700447, 5488.545535433109, 5526.756479792045, 4469.553044695529, 4635.536446355362, 5022.472640372754, 5042.596592272618, 5232.686038837673, 3752.3058234226755, 3768.7927164006105, 4079.7552146871135, 4104.578298803705, 4127.855525056623, 4070.7761073140964, 4111.671066314702, 4126.555705503065, 4134.979325103371, 4186.665066794664, 3254.300325430032, 3899.824507897137, 4072.0430698785763, 4125.484314460692, 4163.770992595406, 3362.7309815214967, 4106.527749308808, 4116.608922152391, 4122.237386083554, 4146.523149837777, 3558.48764275182, 3563.1270338767167, 3571.6964058055096, 3575.928481430383, 3595.568531776177, 4395.164918665443, 5144.099782538078, 5232.686038837682, 5281.603879708999, 5373.435789242151, 4690.530946905316, 4707.176244157293, 4714.033623107255, 4720.12677654635, 4735.005648813753, 5099.923501147486, 5348.956953394089, 5702.515286200678, 5704.942950570524, 5709.514691251248, 3919.157381163035, 3926.391409331558, 3942.526896772377, 3958.7426817256787, 3967.4247234150885, 5659.4340565943485, 5859.677717725481, 5909.556783241274, 5935.703214839241, 5998.3401825799565, 3329.267561136546, 3642.907127861684, 3869.6323849234277, 3976.483057202603, 3981.960180398159, 3404.829758512114, 4070.470838790938, 4072.0, 4075.857344992911, 4087.3037689358584, 3705.6664900159117, 4641.721496710208, 4719.197736384823, 4740.431148262231, 4756.262779269219, 4736.052789442126, 5227.372715274191, 5332.066888294533, 5434.755436005389, 5763.423657634287, 1279.8848103670714, 1298.7402519496138, 1371.1567386057502, 1377.3388773388829, 1379.2345248387041, 768.3814529303964, 775.5114278004821, 806.8587997100484, 896.5562046786882, 968.5060619084318, 4844.975775121102, 5303.522682958553, 5330.307060082165, 5341.545968592635, 5359.839204823861, 5157.355330583677, 5846.473817356458, 5859.970700146558, 5867.442592953663, 5899.84061236827, 4029.5366032906004, 5025.623078269145, 5085.118506152673, 5122.410922743858, 5368.4631536846, 2844.857757112136, 3041.7414519766066, 3055.908322750321, 3057.7248047675816, 3067.8159310441447, 5748.540116790647, 6024.57827952048, 6052.576319657582, 6054.455099041107, 6085.939140608537, 5458.863528411822, 5962.61243019211, 6072.848178795565, 6074.453299203093, 6102.7794441111955, 5088.491150884956, 5282.128448805904, 6099.725512351866, 6109.786157484556, 6169.321374648785, 4939.382577177853, 5534.225208470898, 5910.940890591039, 5925.525957923442, 5948.672822994604, 4284.892877678083, 5663.782286808356, 5782.537397008314, 5849.327327357409, 5867.596793859466, 5718.370979192202, 5723.284589426322, 6124.295705993868, 6138.723757430837, 6154.076813513545, 3897.7661340319673, 3960.3663413853646, 3967.8691572901585, 3996.2407142642805, 4006.2988976928336, 5545.724483368767, 5702.287214098238, 6048.395160484092, 6089.299730530868, 6097.878042439215, 5200.6619569727845, 5231.913171228993, 5317.25558421829, 5514.503694667339, 5910.024845900551, 5113.798257409431, 5762.884742305214, 5796.391378905323, 5807.402964184883, 5808.883822323614, 4408.515063343094, 4676.953230467651, 4747.881302967454, 5169.870753231199, 5380.3589905079325, 4585.633149347977, 5022.634834660859, 5450.427705090909, 5721.485066344049, 5791.438265466792, 4740.502247263988, 5692.743826527733, 5694.94981806541, 5703.0, 5727.914081288701], "value": 4127.710334016593, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [666.313696892201, 755.305119290253, 764.6253335865424, 801.3469022746465, 803.5259197073725, 15084.547463576097, 15672.373105075783, 15694.921525392385, 15759.684806303867, 16015.679686406265, 14139.787903181435, 14275.0, 14281.78577321341, 14305.0, 14306.570802875904, 662.9337066293356, 666.070831190489, 689.1282527602586, 825.7398919340403, 845.5729856422498, 846.1876598465481, 848.9575521223915, 851.1190917400492, 852.0286872964825, 856.3662889461784, 889.4618755652834, 889.9510526921017, 890.2833219258495, 890.4924193209873, 893.7720881175292, 700.3626699703296, 717.1645033535924, 872.650939624149, 883.0330787787362, 888.506878682331, 667.7629441548278, 667.7896462614309, 667.9198496180489, 668.3683918696834, 668.4284936379374, 14757.704845903128, 14784.0, 14804.481843135438, 14810.333534990843, 14811.333489992976, 13574.253416062113, 13692.65768355789, 13764.449422023106, 13881.4447422103, 13993.510227142104, 12717.74564508714, 12743.553975610808, 12909.483620655163, 12953.612265467125, 12966.935165324114, 4337.349397590355, 4529.932051019237, 4577.084583083363, 4673.883152921204, 4777.235642297216, 116811.0, 119376.0, 119539.80460195374, 119859.40070299593, 120923.79076209213, 10742.3017503862, 10796.89203107959, 10974.231803773659, 11034.117270618492, 11327.546898124148, 21629.459263518536, 21651.675224871324, 21660.35018949434, 21672.89163554172, 21704.782952170593, 7836.451448398671, 7898.802529936797, 7939.801504962306, 7970.920290797134, 8174.591270436455, 4090.3455447128317, 4379.627731642782, 4407.801648925806, 4472.8210871565425, 4493.820247190141, 2844.687084420712, 3491.354099491602, 3493.5458390409085, 3493.6681015303507, 3500.6149323574896, 567.6991194666833, 568.9829305120853, 585.2771826793906, 596.153462083842, 601.0413390641919, 467.17543535659587, 559.2757379193954, 600.5105838741412, 605.1890466774544, 606.6996836565928, 7882.0, 8434.367422443342, 9035.864462033074, 9109.817803644022, 9222.53887305632, 581.2008488328548, 586.9698678818646, 627.2504357293083, 629.9842503937439, 649.7693318871827, 4012.197560487914, 4318.265894797892, 4424.380586717862, 4554.24854898938, 4555.248384016666, 147427.0, 153785.23107384393, 155348.0, 159106.0, 160301.96225471815, 121142.06866210322, 123547.76452235543, 139910.0, 141002.0, 145188.0, 105248.0, 109670.0, 117465.9999999983, 119379.0, 119887.41745029534, 8633.784155396166, 8660.740177794676, 8685.759904201128, 8763.0, 8850.778730531789, 66665.00002500063, 67112.06982643281, 67726.0, 68784.0, 69549.0, 62418.75162496816, 62808.057879132764, 65421.0, 91449.0, 103563.0, 11136.0, 11378.31730096197, 11441.256318339281, 11525.82711259315, 12032.518699252281, 12044.578439754918, 12109.69725756828, 12384.318862462651, 12689.746205076031, 12730.427130778951, 10310.0, 12007.099467540143, 12106.576269830513, 12110.878891210976, 12419.875801241871, 13902.51341203053, 13948.930255348658, 14291.857081429052, 14550.636234094232, 14571.198584077554, 13002.674933126747, 13064.804027939399, 13787.586372408843, 14080.788788168391, 14351.569452916428, 10628.545722640018, 11254.83117753218, 11329.773404531697, 11518.481668324777, 11730.530778768754, 10372.585096596353, 11131.53969284446, 11483.655490335303, 11731.589394371156, 11755.823662645238], "value": 7675.719135701506, "unit": "score"}], "name": "dromaeo_css", "value": 7294.346634320351, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 112.0, 112.0, 112.0, 111.0, 113.0, 113.0, 112.0, 112.0, 113.0], "value": 112.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 111.0, 112.0, 145.0, 109.0, 109.0, 108.0, 107.0, 146.0, 140.0], "value": 123.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 183.0, 182.0, 183.0, 182.0, 182.0, 180.0, 184.0, 184.0, 186.0], "value": 183.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 60.0, 87.0, 62.0, 70.0, 58.0, 58.0, 83.0, 58.0], "value": 67.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 71.0, 90.0, 70.0, 69.0, 71.0, 91.0, 68.0, 69.0, 90.0], "value": 77.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 127.0, 92.0, 89.0, 91.0, 89.0, 92.0, 89.0, 92.0, 89.0], "value": 94.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 121.0, 159.0, 154.0, 100.0, 106.0, 99.0, 113.0, 153.0, 162.0], "value": 126.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 86.0, 84.0, 86.0, 85.0, 84.0, 84.0, 102.0, 143.0], "value": 92.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 53.0, 53.0, 75.0, 43.0, 44.0, 53.0, 68.0, 78.0], "value": 57.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 72.0, 75.0, 74.0, 74.0, 70.0, 71.0, 69.0, 69.0, 70.0], "value": 70.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 89.0, 85.0, 88.0, 87.0, 94.0, 105.0, 106.0, 89.0], "value": 91.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 140.0, 158.0, 153.0, 152.0, 158.0, 142.0, 139.0, 184.0, 156.0], "value": 152.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 168.0, 169.0, 169.0, 171.0, 168.0, 169.0, 168.0, 167.0, 221.0], "value": 174.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 69.0, 67.0, 67.0, 67.0, 67.0, 67.0, 66.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1490.8000000000002, "alertThreshold": 2.0}]} 16:30:37 INFO - Return code: 0 16:30:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:30:38 INFO - # TBPL SUCCESS # 16:30:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:30:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:30:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:30:38 INFO - 2016-09-28 16:30:38.017 screenresolution[1667:10493] starting screenresolution argv=screenresolution get 16:30:38 INFO - 2016-09-28 16:30:38.020 screenresolution[1667:10493] Display 0: 1600x1200x32@60 16:30:38 INFO - 2016-09-28 16:30:38.026 screenresolution[1668:10496] starting screenresolution argv=screenresolution list 16:30:38 INFO - Available Modes on Display 0 16:30:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:30:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:30:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:30:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:30:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:30:38 INFO - Intel Iris: 16:30:38 INFO - Chipset Model: Intel Iris 16:30:38 INFO - Type: GPU 16:30:38 INFO - Bus: Built-In 16:30:38 INFO - VRAM (Dynamic, Max): 1536 MB 16:30:38 INFO - Vendor: Intel (0x8086) 16:30:38 INFO - Device ID: 0x0a2e 16:30:38 INFO - Revision ID: 0x0009 16:30:38 INFO - Displays: 16:30:38 INFO - Display: 16:30:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:30:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:30:38 INFO - Display Serial Number: 1600x1200 60 16:30:38 INFO - Main Display: Yes 16:30:38 INFO - Mirror: Off 16:30:38 INFO - Online: Yes 16:30:38 INFO - Rotation: Supported 16:30:38 INFO - Return code: 0 16:30:38 INFO - Running post-action listener: _resource_record_post_action 16:30:38 INFO - [mozharness: 2016-09-28 23:30:38.260536Z] Finished run-tests step (success) 16:30:38 INFO - Running post-run listener: _resource_record_post_run 16:30:38 INFO - Total resource usage - Wall time: 1214s; CPU: 53.0%; Read bytes: 33278976; Write bytes: 707883520; Read time: 307; Write time: 1915 16:30:38 INFO - TinderboxPrint: CPU usage
53.5% 16:30:38 INFO - TinderboxPrint: I/O read bytes / time
33,278,976 / 307 16:30:38 INFO - TinderboxPrint: I/O write bytes / time
707,883,520 / 1,915 16:30:38 INFO - TinderboxPrint: CPU idle
2,261.2 (46.5%) 16:30:38 INFO - TinderboxPrint: CPU system
973.4 (20.0%) 16:30:38 INFO - TinderboxPrint: CPU user
1,623.0 (33.4%) 16:30:38 INFO - TinderboxPrint: Swap in / out
436,736,000 / 0 16:30:38 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 401998336; Write bytes: 396675072; Read time: 30529; Write time: 690 16:30:38 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 31247360; Write bytes: 296360448; Read time: 286; Write time: 1187 16:30:38 INFO - Running post-run listener: _upload_blobber_files 16:30:38 INFO - Blob upload gear active. 16:30:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:30:38 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 16:30:38 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'] 16:30:38 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 16:30:39 INFO - (blobuploader) - INFO - Open directory for files ... 16:30:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:30:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:30:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:30:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:30:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:30:40 INFO - (blobuploader) - INFO - Done attempting. 16:30:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:30:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:30:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:30:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:30:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:30:41 INFO - (blobuploader) - INFO - Done attempting. 16:30:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:30:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:30:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:30:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:30:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:30:44 INFO - (blobuploader) - INFO - Done attempting. 16:30:44 INFO - (blobuploader) - INFO - Iteration through files over. 16:30:44 INFO - Return code: 0 16:30:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:30:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:30:44 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8bb4ec8d5d0e429352e2475588293ea9284798bde1fef0c16343334d91749225c58ad23e5ccbee307a84e4b7dd500d555295db2b08eb3fd7567b27b8ca908fa6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/09fc84e876faa4c00f31ad64847cf8590ea739ad372a72eb3ff2bc4b2f4fe3ed7bab95c37107de337b1d652c16c5f4eb564841310e3f8bac0b41f0ac1e1f6f5f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/79a37de9d88fdad0f6d8d2d90319731673393d024698d4dd3b41549002c0c8904a499c99bd988335d82f93a304ec93ca426861dd330b190d3b3f491e653d2349"} 16:30:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:30:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:30:44 INFO - Contents: 16:30:44 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8bb4ec8d5d0e429352e2475588293ea9284798bde1fef0c16343334d91749225c58ad23e5ccbee307a84e4b7dd500d555295db2b08eb3fd7567b27b8ca908fa6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/09fc84e876faa4c00f31ad64847cf8590ea739ad372a72eb3ff2bc4b2f4fe3ed7bab95c37107de337b1d652c16c5f4eb564841310e3f8bac0b41f0ac1e1f6f5f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/79a37de9d88fdad0f6d8d2d90319731673393d024698d4dd3b41549002c0c8904a499c99bd988335d82f93a304ec93ca426861dd330b190d3b3f491e653d2349"} 16:30:44 INFO - Running post-run listener: copy_logs_to_upload_dir 16:30:44 INFO - Copying logs to upload dir... 16:30:44 INFO - mkdir: /builds/slave/test/build/upload/logs 16:30:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.164253 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-09-28 16:30:44.315554) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-28 16:30:44.320382) ========= 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.JCZsHzRkHE/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.3GDYsRq0kk/Listeners TMPDIR=/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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/8bb4ec8d5d0e429352e2475588293ea9284798bde1fef0c16343334d91749225c58ad23e5ccbee307a84e4b7dd500d555295db2b08eb3fd7567b27b8ca908fa6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/09fc84e876faa4c00f31ad64847cf8590ea739ad372a72eb3ff2bc4b2f4fe3ed7bab95c37107de337b1d652c16c5f4eb564841310e3f8bac0b41f0ac1e1f6f5f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/79a37de9d88fdad0f6d8d2d90319731673393d024698d4dd3b41549002c0c8904a499c99bd988335d82f93a304ec93ca426861dd330b190d3b3f491e653d2349"} build_url:https://queue.taskcluster.net/v1/task/S9SZ-svfRRWEyQFFy_pX8Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011019 build_url: 'https://queue.taskcluster.net/v1/task/S9SZ-svfRRWEyQFFy_pX8Q/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/8bb4ec8d5d0e429352e2475588293ea9284798bde1fef0c16343334d91749225c58ad23e5ccbee307a84e4b7dd500d555295db2b08eb3fd7567b27b8ca908fa6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/09fc84e876faa4c00f31ad64847cf8590ea739ad372a72eb3ff2bc4b2f4fe3ed7bab95c37107de337b1d652c16c5f4eb564841310e3f8bac0b41f0ac1e1f6f5f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/79a37de9d88fdad0f6d8d2d90319731673393d024698d4dd3b41549002c0c8904a499c99bd988335d82f93a304ec93ca426861dd330b190d3b3f491e653d2349"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-28 16:30:44.355368) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 16:30:44.355661) ========= 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.JCZsHzRkHE/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.3GDYsRq0kk/Listeners TMPDIR=/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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.005067 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 16:30:44.403547) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-28 16:30:44.403872) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-28 16:30:44.407245) ========= ========= Total master_lag: 0.19 =========