builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0124 starttime: 1475674815.51 results: success (0) buildid: 20161005054200 builduid: 12c0a833d4a145efb9441b6d3f7f84b2 revision: 2da81945cf4ede6ff3d02c8a945a3bf93770bc8f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-05 06:40:15.512493) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-05 06:40:15.512988) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-05 06:40:15.513266) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-05 06:40:15.712458) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 06:40:15.712828) ========= 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.4tkeZlLbMT/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.d4UApVJ1f2/Listeners TMPDIR=/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-05 06:40:15-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 129M=0s 2016-10-05 06:40:15 (129 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.144660 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 06:40:15.881573) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 06:40:15.881916) ========= 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.4tkeZlLbMT/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.d4UApVJ1f2/Listeners TMPDIR=/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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.029988 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 06:40:15.935181) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-10-05 06:40:15.935568) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 2da81945cf4ede6ff3d02c8a945a3bf93770bc8f --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 2da81945cf4ede6ff3d02c8a945a3bf93770bc8f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4tkeZlLbMT/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.d4UApVJ1f2/Listeners TMPDIR=/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-05 06:40:16,008 truncating revision to first 12 chars 2016-10-05 06:40:16,008 Setting DEBUG logging. 2016-10-05 06:40:16,008 attempt 1/10 2016-10-05 06:40:16,008 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/2da81945cf4e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-05 06:40:18,035 unpacking tar archive at: autoland-2da81945cf4e/testing/mozharness/ program finished with exit code 0 elapsedTime=2.281772 ========= master_lag: 1.87 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-10-05 06:40:20.085565) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-05 06:40:20.085935) ========= script_repo_revision: 2da81945cf4ede6ff3d02c8a945a3bf93770bc8f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-05 06:40:20.086330) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-05 06:40:20.086661) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-05 06:40:20.120709) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-05 06:40:20.121020) ========= /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.4tkeZlLbMT/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.d4UApVJ1f2/Listeners TMPDIR=/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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 06:40:20 INFO - MultiFileLogger online at 20161005 06:40:20 in /builds/slave/test 06:40:20 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 06:40:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:40:20 INFO - {'append_to_log': False, 06:40:20 INFO - 'base_work_dir': '/builds/slave/test', 06:40:20 INFO - 'blob_upload_branch': 'Autoland', 06:40:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:40:20 INFO - 'branch': 'Autoland', 06:40:20 INFO - 'buildbot_json_path': 'buildprops.json', 06:40:20 INFO - 'config_files': ('talos/mac_config.py',), 06:40:20 INFO - 'default_actions': ('clobber', 06:40:20 INFO - 'read-buildbot-config', 06:40:20 INFO - 'download-and-extract', 06:40:20 INFO - 'populate-webroot', 06:40:20 INFO - 'create-virtualenv', 06:40:20 INFO - 'install', 06:40:20 INFO - 'run-tests'), 06:40:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:40:20 INFO - 'download_minidump_stackwalk': True, 06:40:20 INFO - 'download_symbols': 'ondemand', 06:40:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:40:20 INFO - 'tooltool.py': '/tools/tooltool.py', 06:40:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:40:20 INFO - '/tools/misc-python/virtualenv.py')}, 06:40:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:40:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:40:20 INFO - 'installer_path': 'installer.exe', 06:40:20 INFO - 'log_level': 'info', 06:40:20 INFO - 'log_name': 'talos', 06:40:20 INFO - 'log_to_console': True, 06:40:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:40:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:40:20 INFO - 'opt_config_files': (), 06:40:20 INFO - 'pip_index': False, 06:40:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:40:20 INFO - 'cmd': ('bash', 06:40:20 INFO - '-c', 06:40:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:40:20 INFO - 'enabled': True, 06:40:20 INFO - 'halt_on_failure': False, 06:40:20 INFO - 'name': 'check_screen_resolution'},), 06:40:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:40:20 INFO - 'cmd': ('bash', 06:40:20 INFO - '-c', 06:40:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:40:20 INFO - 'enabled': True, 06:40:20 INFO - 'halt_on_failure': False, 06:40:20 INFO - 'name': 'check_screen_resolution'},), 06:40:20 INFO - 'run_cmd_checks_enabled': True, 06:40:20 INFO - 'sps_profile': False, 06:40:20 INFO - 'sps_profile_interval': 0, 06:40:20 INFO - 'suite': 'dromaeojs', 06:40:20 INFO - 'system_bits': '32', 06:40:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:40:20 INFO - 'title': 't-yosemite-r7-0124', 06:40:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:40:20 INFO - 'use_talos_json': True, 06:40:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:40:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:40:20 INFO - 'work_dir': 'build'} 06:40:20 INFO - [mozharness: 2016-10-05 13:40:20.329426Z] Running clobber step. 06:40:20 INFO - Running pre-action listener: _resource_record_pre_action 06:40:20 INFO - Running main action method: clobber 06:40:20 INFO - rmtree: /builds/slave/test/build 06:40:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:40:22 INFO - Running post-action listener: _resource_record_post_action 06:40:22 INFO - [mozharness: 2016-10-05 13:40:22.432905Z] Finished clobber step (success) 06:40:22 INFO - [mozharness: 2016-10-05 13:40:22.433046Z] Running read-buildbot-config step. 06:40:22 INFO - Running pre-action listener: _resource_record_pre_action 06:40:22 INFO - Running main action method: read_buildbot_config 06:40:22 INFO - Using buildbot properties: 06:40:22 INFO - { 06:40:22 INFO - "project": "", 06:40:22 INFO - "product": "firefox", 06:40:22 INFO - "script_repo_revision": "production", 06:40:22 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 06:40:22 INFO - "repository": "", 06:40:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 06:40:22 INFO - "buildid": "20161005054200", 06:40:22 INFO - "pgo_build": "False", 06:40:22 INFO - "basedir": "/builds/slave/test", 06:40:22 INFO - "buildnumber": 225, 06:40:22 INFO - "slavename": "t-yosemite-r7-0124", 06:40:22 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 06:40:22 INFO - "platform": "yosemite_r7", 06:40:22 INFO - "scheduler": "tests-autoland-macosx64-talos", 06:40:22 INFO - "branch": "autoland", 06:40:22 INFO - "repo_path": "integration/autoland", 06:40:22 INFO - "revision": "2da81945cf4ede6ff3d02c8a945a3bf93770bc8f", 06:40:22 INFO - "stage_platform": "macosx64", 06:40:22 INFO - "builduid": "12c0a833d4a145efb9441b6d3f7f84b2", 06:40:22 INFO - "slavebuilddir": "test" 06:40:22 INFO - } 06:40:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/TKx5qFPFSbmBFShewzG5gQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 06:40:22 INFO - Running post-action listener: _resource_record_post_action 06:40:22 INFO - [mozharness: 2016-10-05 13:40:22.436299Z] Finished read-buildbot-config step (success) 06:40:22 INFO - [mozharness: 2016-10-05 13:40:22.436399Z] Running download-and-extract step. 06:40:22 INFO - Running pre-action listener: _resource_record_pre_action 06:40:22 INFO - Running main action method: download_and_extract 06:40:22 INFO - mkdir: /builds/slave/test/build/tests 06:40:22 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')]} 06:40:22 INFO - trying https://queue.taskcluster.net/v1/task/TKx5qFPFSbmBFShewzG5gQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 06:40:22 INFO - Downloading https://queue.taskcluster.net/v1/task/TKx5qFPFSbmBFShewzG5gQ/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 06:40:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TKx5qFPFSbmBFShewzG5gQ/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 06:40:23 INFO - Downloaded 1199 bytes. 06:40:23 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 06:40:23 INFO - Using the following test package requirements: 06:40:23 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 06:40:23 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:40:23 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 06:40:23 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:40:23 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 06:40:23 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:40:23 INFO - u'jsshell-mac.zip'], 06:40:23 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:40:23 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 06:40:23 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 06:40:23 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:40:23 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 06:40:23 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:40:23 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 06:40:23 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:40:23 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 06:40:23 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:40:23 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 06:40:23 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:40:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TKx5qFPFSbmBFShewzG5gQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 06:40:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TKx5qFPFSbmBFShewzG5gQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:40:23 INFO - Fetch https://queue.taskcluster.net/v1/task/TKx5qFPFSbmBFShewzG5gQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 06:40:24 INFO - Http code: 200 06:40:24 INFO - via: 1.1 ccaa055c9a26ce13da709353563e04a5.cloudfront.net (CloudFront) 06:40:24 INFO - x-amz-cf-id: 8wdz-kZ3VmT1G5LTK-gBVgpQF4Q581fagvxcCuCgTdwX62Elg8rwDA== 06:40:24 INFO - x-amz-version-id: hzbaPtcuqWUCFW1J53OvYfaxr3eNS716 06:40:24 INFO - Expected file size: 16476872 06:40:24 INFO - Obtained file size: 16476872 06:40:31 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 06:40:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TKx5qFPFSbmBFShewzG5gQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 06:40:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TKx5qFPFSbmBFShewzG5gQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:40:31 INFO - Fetch https://queue.taskcluster.net/v1/task/TKx5qFPFSbmBFShewzG5gQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 06:40:31 INFO - Http code: 200 06:40:31 INFO - via: 1.1 66ed69e8b47ad05050331602c798132f.cloudfront.net (CloudFront) 06:40:31 INFO - x-amz-cf-id: GvD0Cx4Myaq_lFGACbW7LIgyAkBAxO63Fowb4QYmj07uUsvypT59Pg== 06:40:31 INFO - x-amz-version-id: hzbaPtcuqWUCFW1J53OvYfaxr3eNS716 06:40:32 INFO - Expected file size: 16476872 06:40:32 INFO - Obtained file size: 16476872 06:40:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TKx5qFPFSbmBFShewzG5gQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 06:40:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TKx5qFPFSbmBFShewzG5gQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:40:38 INFO - Fetch https://queue.taskcluster.net/v1/task/TKx5qFPFSbmBFShewzG5gQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 06:40:39 INFO - Http code: 200 06:40:39 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 06:40:39 INFO - x-amz-cf-id: Xh3Gq2tOo2f7L8AyLFsuoKMqTrq2VQvpKOL_bf7B7-rvJdIt7baZhw== 06:40:39 INFO - x-amz-version-id: TjG_61.wVbWOOg01DNCX73tcoiVAZSEa 06:40:39 INFO - Expected file size: 14409006 06:40:39 INFO - Obtained file size: 14409006 06:40:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:40:39 INFO - trying https://queue.taskcluster.net/v1/task/TKx5qFPFSbmBFShewzG5gQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 06:40:39 INFO - Downloading https://queue.taskcluster.net/v1/task/TKx5qFPFSbmBFShewzG5gQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 06:40:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TKx5qFPFSbmBFShewzG5gQ/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 06:40:42 INFO - Downloaded 111642435 bytes. 06:40:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TKx5qFPFSbmBFShewzG5gQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 06:40:42 INFO - mkdir: /builds/slave/test/properties 06:40:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:40:42 INFO - Writing to file /builds/slave/test/properties/build_url 06:40:42 INFO - Contents: 06:40:42 INFO - build_url:https://queue.taskcluster.net/v1/task/TKx5qFPFSbmBFShewzG5gQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 06:40:43 INFO - Running post-action listener: _resource_record_post_action 06:40:43 INFO - Running post-action listener: set_extra_try_arguments 06:40:43 INFO - [mozharness: 2016-10-05 13:40:43.440640Z] Finished download-and-extract step (success) 06:40:43 INFO - [mozharness: 2016-10-05 13:40:43.440784Z] Running populate-webroot step. 06:40:43 INFO - Running pre-action listener: _resource_record_pre_action 06:40:43 INFO - Running main action method: populate_webroot 06:40:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:40:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:40:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:40:43 INFO - '--tppagecycles', 06:40:43 INFO - '7'], 06:40:43 INFO - 'tests': ['tsvgm']}}, 06:40:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:40:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:40:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:40:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:40:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:40:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:40:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:40:43 INFO - 'talos_options': ['--disable-e10s'], 06:40:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:40:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:40:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:40:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:40:43 INFO - 'talos_options': ['--disable-e10s'], 06:40:43 INFO - 'tests': ['damp', 'tps']}, 06:40:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:40:43 INFO - 'tests': ['damp', 'tps']}, 06:40:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:40:43 INFO - 'tests': ['dromaeo_dom']}, 06:40:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:40:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:40:43 INFO - 'tests': ['basic_compositor_video']}, 06:40:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:40:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:40:43 INFO - 'tests': ['a11yr', 06:40:43 INFO - 'ts_paint', 06:40:43 INFO - 'tpaint', 06:40:43 INFO - 'sessionrestore', 06:40:43 INFO - 'sessionrestore_no_auto_restore', 06:40:43 INFO - 'tabpaint']}, 06:40:43 INFO - 'other-e10s': {'tests': ['a11yr', 06:40:43 INFO - 'ts_paint', 06:40:43 INFO - 'tpaint', 06:40:43 INFO - 'sessionrestore', 06:40:43 INFO - 'sessionrestore_no_auto_restore', 06:40:43 INFO - 'tabpaint']}, 06:40:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:40:43 INFO - 'ts_paint', 06:40:43 INFO - 'tpaint', 06:40:43 INFO - 'sessionrestore', 06:40:43 INFO - 'sessionrestore_no_auto_restore', 06:40:43 INFO - 'tabpaint']}, 06:40:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:40:43 INFO - 'ts_paint', 06:40:43 INFO - 'tpaint', 06:40:43 INFO - 'sessionrestore', 06:40:43 INFO - 'sessionrestore_no_auto_restore', 06:40:43 INFO - 'tabpaint']}, 06:40:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:40:43 INFO - 'tests': ['a11yr', 06:40:43 INFO - 'ts_paint', 06:40:43 INFO - 'tpaint', 06:40:43 INFO - 'sessionrestore', 06:40:43 INFO - 'sessionrestore_no_auto_restore', 06:40:43 INFO - 'tabpaint']}, 06:40:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:40:43 INFO - 'tests': ['a11yr', 06:40:43 INFO - 'ts_paint', 06:40:43 INFO - 'tpaint', 06:40:43 INFO - 'sessionrestore', 06:40:43 INFO - 'sessionrestore_no_auto_restore', 06:40:43 INFO - 'tabpaint']}, 06:40:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:40:43 INFO - 'tests': ['tsvgx', 06:40:43 INFO - 'tsvgr_opacity', 06:40:43 INFO - 'tart', 06:40:43 INFO - 'tscrollx', 06:40:43 INFO - 'cart']}, 06:40:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:40:43 INFO - 'tsvgr_opacity', 06:40:43 INFO - 'tart', 06:40:43 INFO - 'tscrollx', 06:40:43 INFO - 'cart']}, 06:40:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:40:43 INFO - 'talos_options': ['--disable-e10s'], 06:40:43 INFO - 'tests': ['tp5o']}, 06:40:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:40:43 INFO - 'tests': ['tp5o']}, 06:40:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:40:43 INFO - 'talos_options': ['--disable-e10s', 06:40:43 INFO - '--xperf_path', 06:40:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:40:43 INFO - 'tests': ['tp5n']}, 06:40:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:40:43 INFO - 'talos_options': ['--xperf_path', 06:40:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:40:43 INFO - 'tests': ['tp5n']}}, 06:40:43 INFO - 'talos.zip': {'path': '', 06:40:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:40:43 INFO - Running post-action listener: _resource_record_post_action 06:40:43 INFO - [mozharness: 2016-10-05 13:40:43.459393Z] Finished populate-webroot step (success) 06:40:43 INFO - [mozharness: 2016-10-05 13:40:43.459523Z] Running create-virtualenv step. 06:40:43 INFO - Running pre-action listener: _resource_record_pre_action 06:40:43 INFO - Running main action method: create_virtualenv 06:40:43 INFO - Creating virtualenv /builds/slave/test/build/venv 06:40:43 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 06:40:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:40:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:40:43 INFO - Using real prefix '/tools/python27' 06:40:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:40:44 INFO - Installing distribute.............................................................................................................................................................................................done. 06:40:47 INFO - Installing pip.................done. 06:40:47 INFO - Return code: 0 06:40:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:40:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:40:47 INFO - Reading from file tmpfile_stdout 06:40:47 INFO - Output received: 06:40:47 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) 06:40:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:40: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')]} 06:40:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:40:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:40: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')]} 06:40:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:40:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:40:47 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 0x10d1a8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d11e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82b9592490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfbcd30>, '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 0x10cde2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb4b20>, '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.4tkeZlLbMT/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.d4UApVJ1f2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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 06:40:47 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 06:40:47 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 06:40:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4tkeZlLbMT/Render', 06:40:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:40:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:40:47 INFO - 'HOME': '/Users/cltbld', 06:40:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:40:47 INFO - 'LOGNAME': 'cltbld', 06:40:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:40:47 INFO - 'MOZ_NO_REMOTE': '1', 06:40:47 INFO - 'NO_EM_RESTART': '1', 06:40:47 INFO - 'PAGER': '/bin/cat', 06:40:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:40:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:40:47 INFO - 'PWD': '/builds/slave/test', 06:40:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:40:47 INFO - 'SHELL': '/bin/bash', 06:40:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d4UApVJ1f2/Listeners', 06:40:47 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 06:40:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:40:47 INFO - 'USER': 'cltbld', 06:40:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:40:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:40:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:40:47 INFO - 'XPC_FLAGS': '0x0', 06:40:47 INFO - 'XPC_SERVICE_NAME': '0', 06:40:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:40:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:40:47 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 06:40:47 INFO - Cleaning up... 06:40:47 INFO - Return code: 0 06:40:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:40: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')]} 06:40:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:40:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:40: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')]} 06:40:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:40:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:40:47 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 0x10d1a8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d11e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82b9592490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfbcd30>, '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 0x10cde2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb4b20>, '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.4tkeZlLbMT/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.d4UApVJ1f2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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 06:40:47 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 06:40:47 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 06:40:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4tkeZlLbMT/Render', 06:40:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:40:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:40:47 INFO - 'HOME': '/Users/cltbld', 06:40:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:40:47 INFO - 'LOGNAME': 'cltbld', 06:40:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:40:47 INFO - 'MOZ_NO_REMOTE': '1', 06:40:47 INFO - 'NO_EM_RESTART': '1', 06:40:47 INFO - 'PAGER': '/bin/cat', 06:40:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:40:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:40:47 INFO - 'PWD': '/builds/slave/test', 06:40:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:40:47 INFO - 'SHELL': '/bin/bash', 06:40:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d4UApVJ1f2/Listeners', 06:40:47 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 06:40:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:40:47 INFO - 'USER': 'cltbld', 06:40:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:40:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:40:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:40:47 INFO - 'XPC_FLAGS': '0x0', 06:40:47 INFO - 'XPC_SERVICE_NAME': '0', 06:40:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:40:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:40:47 INFO - Downloading/unpacking psutil>=3.1.1 06:40:47 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 06:40:47 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 06:40:47 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 06:40:47 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 06:40:47 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 06:40:47 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 06:40:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:40:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:40:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:40:51 INFO - Installing collected packages: psutil 06:40:51 INFO - Running setup.py install for psutil 06:40:51 INFO - building 'psutil._psutil_osx' extension 06:40:51 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 06:40:51 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 06:40:52 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 06:40:52 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 06:40:52 INFO - building 'psutil._psutil_posix' extension 06:40:52 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 06:40:52 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:40:52 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:40:52 INFO - ^ 06:40:52 INFO - 1 warning generated. 06:40:52 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 06:40:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:40:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:40:52 INFO - Successfully installed psutil 06:40:52 INFO - Cleaning up... 06:40:52 INFO - Return code: 0 06:40:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:40:52 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')]} 06:40:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:40:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:40:52 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')]} 06:40:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:40:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:40:52 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 0x10d1a8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d11e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82b9592490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfbcd30>, '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 0x10cde2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb4b20>, '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.4tkeZlLbMT/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.d4UApVJ1f2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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 06:40:52 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 06:40:52 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 06:40:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4tkeZlLbMT/Render', 06:40:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:40:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:40:52 INFO - 'HOME': '/Users/cltbld', 06:40:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:40:52 INFO - 'LOGNAME': 'cltbld', 06:40:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:40:52 INFO - 'MOZ_NO_REMOTE': '1', 06:40:52 INFO - 'NO_EM_RESTART': '1', 06:40:52 INFO - 'PAGER': '/bin/cat', 06:40:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:40:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:40:52 INFO - 'PWD': '/builds/slave/test', 06:40:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:40:52 INFO - 'SHELL': '/bin/bash', 06:40:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d4UApVJ1f2/Listeners', 06:40:52 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 06:40:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:40:52 INFO - 'USER': 'cltbld', 06:40:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:40:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:40:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:40:52 INFO - 'XPC_FLAGS': '0x0', 06:40:52 INFO - 'XPC_SERVICE_NAME': '0', 06:40:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:40:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:40:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:40:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:40:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:40:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:40:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:40:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:40:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:40:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:40:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:40:55 INFO - Installing collected packages: mozsystemmonitor 06:40:55 INFO - Running setup.py install for mozsystemmonitor 06:40:55 INFO - Successfully installed mozsystemmonitor 06:40:55 INFO - Cleaning up... 06:40:55 INFO - Return code: 0 06:40:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:40:55 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')]} 06:40:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:40:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:40:55 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')]} 06:40:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:40:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:40:55 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 0x10d1a8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d11e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82b9592490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfbcd30>, '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 0x10cde2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb4b20>, '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.4tkeZlLbMT/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.d4UApVJ1f2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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 06:40:55 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 06:40:55 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 06:40:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4tkeZlLbMT/Render', 06:40:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:40:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:40:55 INFO - 'HOME': '/Users/cltbld', 06:40:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:40:55 INFO - 'LOGNAME': 'cltbld', 06:40:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:40:55 INFO - 'MOZ_NO_REMOTE': '1', 06:40:55 INFO - 'NO_EM_RESTART': '1', 06:40:55 INFO - 'PAGER': '/bin/cat', 06:40:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:40:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:40:55 INFO - 'PWD': '/builds/slave/test', 06:40:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:40:55 INFO - 'SHELL': '/bin/bash', 06:40:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d4UApVJ1f2/Listeners', 06:40:55 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 06:40:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:40:55 INFO - 'USER': 'cltbld', 06:40:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:40:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:40:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:40:55 INFO - 'XPC_FLAGS': '0x0', 06:40:55 INFO - 'XPC_SERVICE_NAME': '0', 06:40:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:40:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:40:55 INFO - Downloading/unpacking jsonschema==2.5.1 06:40:55 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 06:40:55 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 06:40:55 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 06:40:55 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 06:40:55 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 06:40:55 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 06:40:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:40:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:40:58 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 06:40:58 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 06:40:58 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 06:40:58 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 06:40:58 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 06:40:58 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 06:40:58 INFO - Downloading functools32-3.2.3-2.tar.gz 06:40:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:40:58 INFO - warning: no files found matching '*.txt' 06:40:58 INFO - no previously-included directories found matching 'build' 06:40:58 INFO - no previously-included directories found matching 'dist' 06:40:58 INFO - no previously-included directories found matching '.git*' 06:40:58 INFO - Installing collected packages: jsonschema, functools32 06:40:58 INFO - Running setup.py install for functools32 06:40:59 INFO - warning: no files found matching '*.txt' 06:40:59 INFO - no previously-included directories found matching 'build' 06:40:59 INFO - no previously-included directories found matching 'dist' 06:40:59 INFO - no previously-included directories found matching '.git*' 06:40:59 INFO - Successfully installed jsonschema functools32 06:40:59 INFO - Cleaning up... 06:40:59 INFO - Return code: 0 06:40:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:40: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')]} 06:40:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:40:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:40: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')]} 06:40:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:40:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:40:59 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 0x10d1a8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d11e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82b9592490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfbcd30>, '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 0x10cde2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb4b20>, '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.4tkeZlLbMT/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.d4UApVJ1f2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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 06:40:59 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 06:40:59 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 06:40:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4tkeZlLbMT/Render', 06:40:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:40:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:40:59 INFO - 'HOME': '/Users/cltbld', 06:40:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:40:59 INFO - 'LOGNAME': 'cltbld', 06:40:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:40:59 INFO - 'MOZ_NO_REMOTE': '1', 06:40:59 INFO - 'NO_EM_RESTART': '1', 06:40:59 INFO - 'PAGER': '/bin/cat', 06:40:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:40:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:40:59 INFO - 'PWD': '/builds/slave/test', 06:40:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:40:59 INFO - 'SHELL': '/bin/bash', 06:40:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d4UApVJ1f2/Listeners', 06:40:59 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 06:40:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:40:59 INFO - 'USER': 'cltbld', 06:40:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:40:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:40:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:40:59 INFO - 'XPC_FLAGS': '0x0', 06:40:59 INFO - 'XPC_SERVICE_NAME': '0', 06:40:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:40:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:40:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:40:59 INFO - Cleaning up... 06:40:59 INFO - Return code: 0 06:40:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:40: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')]} 06:40:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:40:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:40: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')]} 06:40:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:40:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:40:59 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 0x10d1a8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d11e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82b9592490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfbcd30>, '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 0x10cde2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb4b20>, '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.4tkeZlLbMT/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.d4UApVJ1f2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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 06:40:59 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 06:40:59 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 06:40:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4tkeZlLbMT/Render', 06:40:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:40:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:40:59 INFO - 'HOME': '/Users/cltbld', 06:40:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:40:59 INFO - 'LOGNAME': 'cltbld', 06:40:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:40:59 INFO - 'MOZ_NO_REMOTE': '1', 06:40:59 INFO - 'NO_EM_RESTART': '1', 06:40:59 INFO - 'PAGER': '/bin/cat', 06:40:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:40:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:40:59 INFO - 'PWD': '/builds/slave/test', 06:40:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:40:59 INFO - 'SHELL': '/bin/bash', 06:40:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d4UApVJ1f2/Listeners', 06:40:59 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 06:40:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:40:59 INFO - 'USER': 'cltbld', 06:40:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:40:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:40:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:40:59 INFO - 'XPC_FLAGS': '0x0', 06:40:59 INFO - 'XPC_SERVICE_NAME': '0', 06:40:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:40:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:40:59 INFO - Downloading/unpacking blobuploader==1.2.4 06:40:59 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 06:40:59 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 06:40:59 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 06:40:59 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 06:40:59 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 06:40:59 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 06:41:02 INFO - Downloading blobuploader-1.2.4.tar.gz 06:41:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:41:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:41:02 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 06:41:02 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 06:41:02 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 06:41:02 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 06:41:02 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 06:41:02 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 06:41:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:41:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:41:02 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 06:41:02 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 06:41:02 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 06:41:02 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 06:41:02 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 06:41:02 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 06:41:03 INFO - Downloading docopt-0.6.1.tar.gz 06:41:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:41:03 INFO - Installing collected packages: blobuploader, requests, docopt 06:41:03 INFO - Running setup.py install for blobuploader 06:41:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:41:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:41:03 INFO - Running setup.py install for requests 06:41:03 INFO - Running setup.py install for docopt 06:41:04 INFO - Successfully installed blobuploader requests docopt 06:41:04 INFO - Cleaning up... 06:41:04 INFO - Return code: 0 06:41:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:41: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')]} 06:41:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:41:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:41: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')]} 06:41:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:41:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:41:04 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 0x10d1a8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d11e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82b9592490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfbcd30>, '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 0x10cde2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb4b20>, '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.4tkeZlLbMT/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.d4UApVJ1f2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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 06:41:04 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 06:41:04 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 06:41:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4tkeZlLbMT/Render', 06:41:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:41:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:41:04 INFO - 'HOME': '/Users/cltbld', 06:41:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:41:04 INFO - 'LOGNAME': 'cltbld', 06:41:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:41:04 INFO - 'MOZ_NO_REMOTE': '1', 06:41:04 INFO - 'NO_EM_RESTART': '1', 06:41:04 INFO - 'PAGER': '/bin/cat', 06:41:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:41:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:41:04 INFO - 'PWD': '/builds/slave/test', 06:41:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:41:04 INFO - 'SHELL': '/bin/bash', 06:41:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d4UApVJ1f2/Listeners', 06:41:04 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 06:41:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:41:04 INFO - 'USER': 'cltbld', 06:41:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:41:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:41:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:41:04 INFO - 'XPC_FLAGS': '0x0', 06:41:04 INFO - 'XPC_SERVICE_NAME': '0', 06:41:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:41:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:41:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:41:05 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-IJFlM_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:41:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:41:05 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-igrY2J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:41:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:41:05 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-odNvBH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:41:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:41:05 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-7l8eKa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:41:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:41:05 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-8OHGXG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:41:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:41:05 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-oPKx9t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:41:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:41:05 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-vgPk_V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:41:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:41:05 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-5A3PT1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:41:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:41:06 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-vZ3fXQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:41:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:41:06 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-Boe8Ol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:41:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:41:06 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-miTw2Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:41:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:41:06 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-BEc21H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:41:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:41:06 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-iMlJs6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:41:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:41:06 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-bGQgel-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:41:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:41:06 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-7vyDcX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:41:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:41:06 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-KDDols-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:41:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:41:06 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-7gqjkX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:41:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:41:06 INFO - Running setup.py install for manifestparser 06:41:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:41:07 INFO - Running setup.py install for mozcrash 06:41:07 INFO - Running setup.py install for mozdebug 06:41:07 INFO - Running setup.py install for mozdevice 06:41:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:41:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:41:07 INFO - Running setup.py install for mozfile 06:41:07 INFO - Running setup.py install for mozhttpd 06:41:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:41:07 INFO - Running setup.py install for mozinfo 06:41:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:41:07 INFO - Running setup.py install for mozInstall 06:41:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:41:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:41:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:41:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:41:08 INFO - Running setup.py install for mozleak 06:41:08 INFO - Running setup.py install for mozlog 06:41:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:41:08 INFO - Running setup.py install for moznetwork 06:41:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:41:08 INFO - Running setup.py install for mozprocess 06:41:08 INFO - Running setup.py install for mozprofile 06:41:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:41:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:41:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:41:08 INFO - Running setup.py install for mozrunner 06:41:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:41:09 INFO - Running setup.py install for mozscreenshot 06:41:09 INFO - Running setup.py install for moztest 06:41:09 INFO - Running setup.py install for mozversion 06:41:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:41:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:41:09 INFO - Cleaning up... 06:41:09 INFO - Return code: 0 06:41:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:41:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:41:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:41:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:41:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:41:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:41:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:41:09 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 0x10d1a8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d11e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82b9592490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfbcd30>, '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 0x10cde2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb4b20>, '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.4tkeZlLbMT/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.d4UApVJ1f2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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 06:41:09 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 06:41:09 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 06:41:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4tkeZlLbMT/Render', 06:41:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:41:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:41:09 INFO - 'HOME': '/Users/cltbld', 06:41:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:41:09 INFO - 'LOGNAME': 'cltbld', 06:41:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:41:09 INFO - 'MOZ_NO_REMOTE': '1', 06:41:09 INFO - 'NO_EM_RESTART': '1', 06:41:09 INFO - 'PAGER': '/bin/cat', 06:41:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:41:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:41:09 INFO - 'PWD': '/builds/slave/test', 06:41:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:41:09 INFO - 'SHELL': '/bin/bash', 06:41:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d4UApVJ1f2/Listeners', 06:41:09 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 06:41:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:41:09 INFO - 'USER': 'cltbld', 06:41:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:41:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:41:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:41:09 INFO - 'XPC_FLAGS': '0x0', 06:41:09 INFO - 'XPC_SERVICE_NAME': '0', 06:41:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:41:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:41:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:41:10 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-gV95D3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:41:10 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)) 06:41:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:41:10 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-6uLn1_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:41:10 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)) 06:41:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:41:10 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-bHhGOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:41:10 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)) 06:41:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:41:10 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-7wlgwo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:41:10 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)) 06:41:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:41:10 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-Al8Hid-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:41:10 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)) 06:41:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:41:10 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-vhlEMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:41:10 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)) 06:41:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:41:10 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-_j03P7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:41:10 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)) 06:41:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:41:10 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-CBiV2A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:41:11 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)) 06:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:41:11 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-Pswr84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:41:11 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)) 06:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:41:11 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-GXaaOP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:41:11 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)) 06:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:41:11 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-JI9Ino-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:41:11 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)) 06:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:41:11 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-Jodnc1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:41:11 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)) 06:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:41:11 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-ZoPWs8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:41:11 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)) 06:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:41:11 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-FRSEki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:41:11 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)) 06:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:41:11 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-sJvYAV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:41:11 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)) 06:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:41:11 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-C7xXuL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:41:11 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)) 06:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:41:11 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-OdXbJI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:41:11 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)) 06:41:11 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)) 06:41:11 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)) 06:41:11 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)) 06:41:11 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)) 06:41:11 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)) 06:41:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:41: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 06:41: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 06:41: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 06:41: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 06:41: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 06:41: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 06:41:14 INFO - Downloading blessings-1.6.tar.gz 06:41:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:41:14 INFO - Installing collected packages: blessings 06:41:14 INFO - Running setup.py install for blessings 06:41:15 INFO - Successfully installed blessings 06:41:15 INFO - Cleaning up... 06:41:15 INFO - Return code: 0 06:41:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:41:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:41:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:41:15 INFO - Reading from file tmpfile_stdout 06:41:15 INFO - Current package versions: 06:41:15 INFO - blessings == 1.6 06:41:15 INFO - blobuploader == 1.2.4 06:41:15 INFO - docopt == 0.6.1 06:41:15 INFO - functools32 == 3.2.3-2 06:41:15 INFO - jsonschema == 2.5.1 06:41:15 INFO - manifestparser == 1.1 06:41:15 INFO - mozInstall == 1.12 06:41:15 INFO - mozcrash == 0.17 06:41:15 INFO - mozdebug == 0.1 06:41:15 INFO - mozdevice == 0.48 06:41:15 INFO - mozfile == 1.2 06:41:15 INFO - mozhttpd == 0.7 06:41:15 INFO - mozinfo == 0.9 06:41:15 INFO - mozleak == 0.1 06:41:15 INFO - mozlog == 3.4 06:41:15 INFO - moznetwork == 0.27 06:41:15 INFO - mozprocess == 0.23 06:41:15 INFO - mozprofile == 0.28 06:41:15 INFO - mozrunner == 6.12 06:41:15 INFO - mozscreenshot == 0.1 06:41:15 INFO - mozsystemmonitor == 0.3 06:41:15 INFO - moztest == 0.7 06:41:15 INFO - mozversion == 1.4 06:41:15 INFO - psutil == 3.1.1 06:41:15 INFO - requests == 1.2.3 06:41:15 INFO - wsgiref == 0.1.2 06:41:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:41:15 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')]} 06:41:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:41:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:41:15 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')]} 06:41:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:41:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:41:15 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 0x10d1a8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d11e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82b9592490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfbcd30>, '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 0x10cde2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb4b20>, '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.4tkeZlLbMT/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.d4UApVJ1f2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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 06:41:15 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 06:41:15 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 06:41:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4tkeZlLbMT/Render', 06:41:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:41:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:41:15 INFO - 'HOME': '/Users/cltbld', 06:41:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:41:15 INFO - 'LOGNAME': 'cltbld', 06:41:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:41:15 INFO - 'MOZ_NO_REMOTE': '1', 06:41:15 INFO - 'NO_EM_RESTART': '1', 06:41:15 INFO - 'PAGER': '/bin/cat', 06:41:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:41:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:41:15 INFO - 'PWD': '/builds/slave/test', 06:41:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:41:15 INFO - 'SHELL': '/bin/bash', 06:41:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d4UApVJ1f2/Listeners', 06:41:15 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 06:41:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:41:15 INFO - 'USER': 'cltbld', 06:41:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:41:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:41:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:41:15 INFO - 'XPC_FLAGS': '0x0', 06:41:15 INFO - 'XPC_SERVICE_NAME': '0', 06:41:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:41:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:41:15 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)) 06:41:15 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)) 06:41:15 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)) 06:41:15 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)) 06:41:15 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)) 06:41:15 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)) 06:41:15 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)) 06:41:15 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)) 06:41:15 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)) 06:41:15 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)) 06:41:15 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)) 06:41:15 INFO - Cleaning up... 06:41:15 INFO - Return code: 0 06:41:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:41:15 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')]} 06:41:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:41:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:41:15 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')]} 06:41:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:41:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:41:15 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 0x10d1a8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d11e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82b9592490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfbcd30>, '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 0x10cde2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb4b20>, '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.4tkeZlLbMT/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.d4UApVJ1f2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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 06:41:15 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 06:41:15 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 06:41:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4tkeZlLbMT/Render', 06:41:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:41:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:41:15 INFO - 'HOME': '/Users/cltbld', 06:41:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:41:15 INFO - 'LOGNAME': 'cltbld', 06:41:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:41:15 INFO - 'MOZ_NO_REMOTE': '1', 06:41:15 INFO - 'NO_EM_RESTART': '1', 06:41:15 INFO - 'PAGER': '/bin/cat', 06:41:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:41:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:41:15 INFO - 'PWD': '/builds/slave/test', 06:41:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:41:15 INFO - 'SHELL': '/bin/bash', 06:41:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d4UApVJ1f2/Listeners', 06:41:15 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 06:41:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:41:15 INFO - 'USER': 'cltbld', 06:41:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:41:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:41:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:41:15 INFO - 'XPC_FLAGS': '0x0', 06:41:15 INFO - 'XPC_SERVICE_NAME': '0', 06:41:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:41:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:41:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:41:15 INFO - Cleaning up... 06:41:16 INFO - Return code: 0 06:41:16 INFO - Running post-action listener: _resource_record_post_action 06:41:16 INFO - Running post-action listener: _start_resource_monitoring 06:41:16 INFO - Starting resource monitoring. 06:41:16 INFO - [mozharness: 2016-10-05 13:41:16.030843Z] Finished create-virtualenv step (success) 06:41:16 INFO - [mozharness: 2016-10-05 13:41:16.031835Z] Running install step. 06:41:16 INFO - Running pre-action listener: _resource_record_pre_action 06:41:16 INFO - Running main action method: install 06:41:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:41:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:41:16 INFO - Reading from file tmpfile_stdout 06:41:16 INFO - Detecting whether we're running mozinstall >=1.0... 06:41:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:41:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:41:16 INFO - Reading from file tmpfile_stdout 06:41:16 INFO - Output received: 06:41:16 INFO - Usage: mozinstall [options] installer 06:41:16 INFO - Options: 06:41:16 INFO - -h, --help show this help message and exit 06:41:16 INFO - -d DEST, --destination=DEST 06:41:16 INFO - Directory to install application into. [default: 06:41:16 INFO - "/builds/slave/test"] 06:41:16 INFO - --app=APP Application being installed. [default: firefox] 06:41:16 INFO - mkdir: /builds/slave/test/build/application 06:41:16 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'] 06:41:16 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 06:41:50 INFO - Reading from file tmpfile_stdout 06:41:50 INFO - Output received: 06:41:50 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:41:50 INFO - Running post-action listener: _resource_record_post_action 06:41:50 INFO - [mozharness: 2016-10-05 13:41:50.832042Z] Finished install step (success) 06:41:50 INFO - [mozharness: 2016-10-05 13:41:50.832253Z] Running run-tests step. 06:41:50 INFO - Running pre-action listener: _resource_record_pre_action 06:41:50 INFO - Running main action method: run_tests 06:41:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:41:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:41:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:41:50 INFO - 2016-10-05 06:41:50.846 screenresolution[1641:7507] starting screenresolution argv=screenresolution get 06:41:50 INFO - 2016-10-05 06:41:50.849 screenresolution[1641:7507] Display 0: 1600x1200x32@60 06:41:50 INFO - 2016-10-05 06:41:50.855 screenresolution[1642:7509] starting screenresolution argv=screenresolution list 06:41:50 INFO - Available Modes on Display 0 06:41:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:41:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:41:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:41:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:41:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:41:51 INFO - Intel Iris: 06:41:51 INFO - Chipset Model: Intel Iris 06:41:51 INFO - Type: GPU 06:41:51 INFO - Bus: Built-In 06:41:51 INFO - VRAM (Dynamic, Max): 1536 MB 06:41:51 INFO - Vendor: Intel (0x8086) 06:41:51 INFO - Device ID: 0x0a2e 06:41:51 INFO - Revision ID: 0x0009 06:41:51 INFO - Displays: 06:41:51 INFO - Display: 06:41:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:41:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:41:51 INFO - Display Serial Number: 1600x1200 60 06:41:51 INFO - Main Display: Yes 06:41:51 INFO - Mirror: Off 06:41:51 INFO - Online: Yes 06:41:51 INFO - Rotation: Supported 06:41:51 INFO - Return code: 0 06:41:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:41:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:41:51 INFO - Python 2.7.3 06:41:51 INFO - Return code: 0 06:41:51 INFO - grabbing minidump binary from tooltool 06:41:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:41:51 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 0x10cfbcd30>, '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 0x10cde2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:41:51 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 06:41:51 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 06:41:51 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 06:41:51 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:41:51 INFO - Return code: 0 06:41:51 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:41:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:41:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:41:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:41:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:41:51 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/TKx5qFPFSbmBFShewzG5gQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0124', '--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 06:41:51 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/TKx5qFPFSbmBFShewzG5gQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0124 --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 06:41:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4tkeZlLbMT/Render', 06:41:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:41:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:41:51 INFO - 'HOME': '/Users/cltbld', 06:41:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:41:51 INFO - 'LOGNAME': 'cltbld', 06:41:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:41:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:41:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:41:51 INFO - 'MOZ_NO_REMOTE': '1', 06:41:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:41:51 INFO - 'NO_EM_RESTART': '1', 06:41:51 INFO - 'PAGER': '/bin/cat', 06:41:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:41:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:41:51 INFO - 'PWD': '/builds/slave/test', 06:41:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:41:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:41:51 INFO - 'SHELL': '/bin/bash', 06:41:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d4UApVJ1f2/Listeners', 06:41:51 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 06:41:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:41:51 INFO - 'USER': 'cltbld', 06:41:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:41:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:41:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:41:51 INFO - 'XPC_FLAGS': '0x0', 06:41:51 INFO - 'XPC_SERVICE_NAME': '0', 06:41:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:41:51 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/TKx5qFPFSbmBFShewzG5gQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0124', '--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 06:41:51 INFO - mozversion application_buildid: 20161005054200 06:41:51 INFO - mozversion application_changeset: 2da81945cf4ede6ff3d02c8a945a3bf93770bc8f 06:41:51 INFO - mozversion application_display_name: Nightly 06:41:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:41:51 INFO - mozversion application_name: Firefox 06:41:51 INFO - mozversion application_remotingname: firefox 06:41:51 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 06:41:51 INFO - mozversion application_vendor: Mozilla 06:41:51 INFO - mozversion application_version: 52.0a1 06:41:51 INFO - mozversion platform_buildid: 20161005054200 06:41:51 INFO - mozversion platform_changeset: 2da81945cf4ede6ff3d02c8a945a3bf93770bc8f 06:41:51 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 06:41:51 INFO - mozversion platform_version: 52.0a1 06:41:51 INFO - using testdate: 1475674911 06:41:51 INFO - actual date: 1475674911 06:41:51 INFO - starting webserver on 'localhost:49267' 06:41:51 INFO - SUITE-START | Running 2 tests 06:41:51 INFO - TEST-START | dromaeo_css 06:41:51 INFO - operating with platform_type : mac_ 06:41:51 INFO - Initialising browser for dromaeo_css test... 06:41:51 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/tmpTxsPYn/profile http://localhost:49267/getInfo.html) 06:41:53 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 06:41:53 INFO - PROCESS | 1652 | colorDepth:24 06:41:53 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 06:41:53 INFO - PROCESS | 1652 | __metrics 06:41:54 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" 06:41:54 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] 06:41:54 INFO - TEST-INFO | 1652: exit 0 06:41:54 INFO - Browser initialized. 06:41:54 INFO - Running cycle 1/1 for dromaeo_css test... 06:41:54 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/tmpTxsPYn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:42:06 INFO - PROCESS | 1654 | RSS: Main: 235474944 06:42:06 INFO - PROCESS | 1654 | 06:42:58 INFO - PROCESS | 1654 | 1475674978291 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] 06:45:11 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 06:45:12 INFO - PROCESS | 1654 | RSS: Main: 363585536 06:45:12 INFO - PROCESS | 1654 | 06:48:15 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 06:48:16 INFO - PROCESS | 1654 | RSS: Main: 393388032 06:48:16 INFO - PROCESS | 1654 | 06:51:18 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 06:51:19 INFO - PROCESS | 1654 | RSS: Main: 361414656 06:51:19 INFO - PROCESS | 1654 | 06:54:21 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 06:54:22 INFO - PROCESS | 1654 | RSS: Main: 345935872 06:54:22 INFO - PROCESS | 1654 | 06:57:25 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 06:57:26 INFO - PROCESS | 1654 | RSS: Main: 332304384 06:57:26 INFO - PROCESS | 1654 | 07:00:29 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 07:00:29 INFO - PROCESS | 1654 | RSS: Main: 448458752 07:00:29 INFO - PROCESS | 1654 | 07:00:29 INFO - PROCESS | 1654 | __start_tp_report 07:00:29 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 07:00:29 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 07:00:29 INFO - PROCESS | 1654 | |i|pagename|runs| 07:00:29 INFO - PROCESS | 1654 | |0;dojo.html;3129.968700312997;3337.065621625945;3364.7812892162005;3389.389909816232;3393.745469089819;5623.915641265374;5654.717264136792;5706.486416222539;5792.855178620536;5801.1008293714485;5709.343425506057;5719.656820590768;5788.942110578893;5802.361740208584;5874.70626468677;5245.318108645871;5309.389420216667;5323.787048518055;5325;5331.973340133294;5292.02097611943;5303.867403314909;5316.282301889234;5356.1697936819855;5379.327584051994;5215.6609820361555;5363.222332761748;5427.240186373891;5428.348598168225;5431.049566325897;5315.388730296027;5317.8404647860625;5336.199570064483;5368.194770784375;5369.194620806871;5162.380514338303;5176.327077479941;5180.792768289264;5223.817166399196;5224.346956630421;5665.915011274834;5675.148727690879;5680.6591604503865;5735.426457354273;5738.741756620962;6281.3404592518045;6298.149749783812;6303.653299068549;6327.810165695037;6327.96836015817;6171.444569988723;6234.127222188898;6240.282367527747;6273.059041143865;6358.523110766711;5829.912551311733;5844.152597873284;5889.823305300848;5892.764289428461;5928.911066334009;6260.217472815898;6293.370662933761;6302.968485157545;6306.1171435998995;6322.462590679844;6038.939610603926;6099.824329823284;6113.506711826627;6192.938070619326;6194.5973511721595;6189.257289125244;6236.220472440945;6267.749290028348;6300.653464059521;6339.904901426482;6213.285472170668;6253.530985176039;6283.214598175228;6289.496840252768;6297.622142671455;6100.420460056288;6264.373562643699;6328.968355158195;6331.651759153198;6338.904916426257;4121.237571049365;4888.682235654742;4919.926201106986;4948.6783359082265;4969.627277954168;4449.421575195204;4454.420925279693;4468.061707041557;4510.548945105464;4522.34426008227;5813.389594092644;5816.243888294494;5825.38833422493;5835.503982161564;5843.941560584425;6133.1413602095745;6217.875642487125;6219;6252.343503932022;6304.716287767062;5759.7408116634015;5787.71061446926;5838.8906107839375;5841.883162336814;5860.8534786629825;5434.157705555645;5444.891102177934;5454.3454785426;5463.672179669233;5468.48049435303;6196.907046394398;6214.658193799384;6309.90535141964;6319.3364696706185;6328.588641738273;6234.0025595904435;6286.52851036165;6320.936790632219;6357.554971151975;6361.745530178741;6305.337939516285;6312.43188113072;6316.873662526816;6319.8104056878365;6328.683565821782;5921.259842519685;6018.277806663228;6248.68756562161;6256.092866534418;6279.120923070683;6217.4715149212825;6236.656983865931;6260.186175797117;6303.338149494237;6355.87288254223;6001.009833377619;6208.099825525183;6212.875742485216;6222.533310001859;6223.066540019034;5980.760769569169;6172.783952561639;6185.659788711664;6187.412195841388;6196.659183744938;5832.416758324303;5891.410858914074;5983.401659833982;6050.546209034428;6119.2350956130485;6109.602875813058;6124.782500621427;6148.754049837957;6167.814965551041;6189.442950134509;5843.503302219189;5875.412458754261;6109.877802444015;6136.079588061826;6136.417040381157 07:00:29 INFO - PROCESS | 1654 | |1;ext.html;510.1556923291951;526.6339893773827;531.221760121422;534.126702840855;536.1341433584762;27224.45551088977;40812.59187408125;42205.944851378656;42225.78887105564;44700.776496117476;42953;43223;43243.78378108113;43305.566944330545;43392.78303608477;9736.123748862601;9924.503774811266;9928;9939.2545559083;9963.053509916583;9762.072603102693;9863.60545578176;9874.703758887208;9877.555510002067;9906;9847;9902.306838521306;9905.257105717064;9921.206303495703;9942.502874856264;9819.852702209473;9860.950695246549;9864.704058878246;9874.654387096489;9888.555015024342;9766.36518626287;9847.16299114576;9850.901490985141;9855.950720246425;9870.309078364517;41557.58442415568;41831.95420114491;41832.58167418348;41834;41887.37168942468;3518.1556426457464;3522.20750331176;3745.5879853216125;3933.567307596162;4049.8380064797375;31293.530597041063;31336.843315783277;32311.51532727011;32428.513572296433;32672.346553069045;3291.9506207406907;3334.866605335784;3375.206826395795;3397.59228892532;3407.352603005421;370053;372016;373173.1341343303;373252.99999999726;373895;33416.66583334184;60330.396696032476;60801.695991520646;60917;60944.34333939889;34056.659433405846;35435.468467973;35476.64523354785;35518;35540.46689299611;14226.075305105134;14267.571972840624;14344.211068391338;14408.351624176938;14430.422783088768;11137.888621113685;12443;12594.24434533931;12606.117571769888;12606.747865042647;7797.571133587594;7816.374690024784;7841.686332546634;7895.328897043701;7914.525128492309;3194.9680503195136;3203.8077715337154;3217.9678203218136;3222.7099561039618;3247.5128730690585;886.8625363068735;903.9909600904039;940.5767404667927;951.286535098676;1026.3277553202665;28962;28972.27569310784;29929.95245166409;30159.09522714278;31001.534976975363;983.9508024598741;988.4761076629397;1009.9141572966233;1046.884842667306;1112.3270421395102;15226.390944362323;15620.68758624844;15769.132697701507;15823.683526329409;15976.034457918602;617349.9132504398;620335;621475;622477.6398478185;622663;42426;42560.361594576105;42668.78665606714;42719.9320017002;42807.14385712268;1181.261711430356;1348.7707089794562;1373.5535950816025;1386.0921096681489;1387.4450219912117;29098.854505727337;29170.7082929168;29608.851955740083;30098;30593;52020.479795201565;52381.47618523918;52398.73800630972;52432.95134097373;52620.94758104893;573093;577069;578541;578774;578914;26660.466790664464;26757.197284081507;26825.195244142706;27136.050238241565;27167.59248611312;47800.7609961948;47912.520874790804;48161.27758083701;48598.757006216154;48904.51095488999;37835.432468512445;38610.613893860704;39523.60476395199;40304.59695403126;40557.797211013756;39045.60954390533;39276;40092.58567908702;40371.1925761489;40608.59391406166;38014.23971520499;38046.47814087405;39012.2197556053;39013.02467438337;39064.80467597644;31133.377332452772;33264.10367514858;33939.321213576084;34133.31733365269;34315;25306.746932530437;25328.11351602685;25414.872925635253;25711.971521138945;25974.22077337683 07:00:29 INFO - PROCESS | 1654 | |2;jquery.html;354.80663038643934;371.53372517490544;376.7513441128855;383.73138802838;408.0940312506235;12025.819612705816;15305.158216298112;16485;20637.968101594935;20688.27591034314;19124.33064842731;19165.1375688094;20193.394198174043;20377.796222037774;20633.793662063374;5423.376311724143;5762.481376676098;6126.448619624233;6128.479079278267;6132.908006379907;5859.824205273827;6044.395560443964;6103.115048317991;6109.908351374733;6113.877722445548;5690.63010904292;5875.88248235035;6042.758289668408;6053.82062457515;6054.60645058072;5895.528357731371;5944.554158438113;5953.404659534055;5955.851103722398;5955.910661340105;5043.42000669924;5087.058894104603;5151.948480515184;5161.896762064737;5225.608079394061;25334.113306034385;25749.74250257492;25894.482110357876;25956.74043259562;26083.608745868827;2012.734030151153;2084.9061792219236;2256.5599708056934;2478.5786416309093;2539.5682733935278;15089.54731358061;15775.842241577668;15816.367345306175;15983.200839957955;16186.75719864203;1616.708992381371;1830.7345434911863;1847.3534263007973;1899.9050047497708;1948.9816645115993;119443;122513;122610.38694806558;122620;123995;10318.896811031944;10443.791124177475;10926.235163538635;10937.671869843918;11077.833832492519;13218.537351192663;13290.3354832258;13637.931810340884;13647.249401283025;13667.863321366858;7279.89080163798;8110.716124935599;8461.830763384698;8463.619137138843;8670.82658346842;8493.575321233913;8514.191151840565;8522.21033582942;8674.479531228146;8681.175288347597;6457.515686323544;6620.106285651472;6638.369354911276;6646.302138275411;6726;1454.70178613384;1756.6135450200936;1810.4297146398842;1820.963580728378;1844.6402951424343;775.3835700618022;851.9914800852036;866.1641515937151;944.1691311645845;962.4369743700014;20224.02946140873;21805.67736437102;21863.41822084015;21882.847893221257;21891.992736499218;920.6455514626917;923.6628630549801;964.7925695975399;971.3831716859845;988.7330420786348;10316.587336506605;11697.707557311136;11724.062075033977;11829.822552661717;11887.286762794087;586704;595456;606007;609454;611194;72176;96530;105288;115312.42343788395;124576;902.8510295801119;970.6602689058914;999.5402115027024;1013.9340942838692;1014.518103900656;16890.577735556217;19197.424077277705;19319.4204173875;19528.511787205432;19668.80331196727;45685.086298273185;45788;46533;46828.531714682416;51078.23382649189;61585.25089552337;62058;63163;63342.68328658327;64831.67584162049;7719.266669666373;7915.485493442908;7935.246151615587;7992.320652744349;8206.056303524932;10931.945340273247;12710.665380134953;12900.870991289967;13343.532976345781;13496.05527613097;9708.708738737849;10761.461926903467;11118.166137539554;11268;11656.708582285512;9577.521123943914;10110.69667909964;10998.725031874266;11036.77760068754;11455.541778328774;11345.943270283597;11740.236884602473;11793.764124717285;11816.586419475278;11837.704057398634;6978.91826766852;7925.960370198112;8135.75592732219;8429.4942303462;8557.61490732906;10877.728056798644;11033.668989930315;11211.439428028729;11213.775724485302;11267.647882254181 07:00:29 INFO - PROCESS | 1654 | |3;mootools.html;1480.3190532355115;1511.5918701950475;1690.8224636413177;1772.751814745935;1870.3453791173092;8494.150584941503;9019.503927283997;9023.639054437832;9052.90947090529;9099.272058235341;7801.687932482694;8110.594470276477;8130.878036829437;8497;8748.912510874889;2347.0753981734942;2384.9761502384927;2388.892499837503;2389.330987323552;2391.2467572714627;2017.273781438681;2350.8942097605564;2357.481354102095;2387.8925448354867;2393.413613664655;2360.6104992676187;2372.086746602562;2378.4172877645;2386.4033991502124;2392.9042838286446;2099.443647433416;2258.040332858545;2361.49227915998;2373.6320870265135;2374.7031621047367;1969.6356174107837;2296.9425764355856;2300.344401845466;2300.6318988961684;2306.7347255065715;8891.155340242667;8922.464652120894;9015.909840901639;9021.548922553846;9024.323175761843;5149.562287205592;5310.150375939869;5321.201819727034;5327.813526526553;5339.946600533984;3253.1216571525556;3303.223742420529;3357.2110554019787;3364.7981121132566;3433.2790114076074;1077.617445806735;1081.4376524207446;1081.7728277061826;1101.2841652925583;1107.053469283759;11623;11724.589639362666;11754.177207595472;11756.412179390996;11758.58844940423;4494.910101798011;4552.203364411214;4719.244920812654;4765.309030190603;4765.404324459442;9917.256205784452;9918.950405247928;9922.107010369102;9928.851067233998;9937.65218217359;5285.497877701612;5289.365276166807;5290.259363689087;5300;5300.469953004668;4379.540148284448;4545.272756358967;4709.693869898446;4845.3216549683075;4869.196582563908;3820.083180036827;3878.9224215515533;3951.7431366961055;3978.224246271949;3984.6812255019527;1254.4229654358915;1258.8741125887339;1264.6522206393315;1268.2308180088723;1276.6808297925518;876.9386142969931;878.7188099808;882.2765332427476;899.2958343938201;928.5218112671979;5580.497755202051;5762.567807414461;5989.490893274034;6011.549133814981;6050.21347224858;443.52321254650735;448.9236829738887;453.2702349217757;971.7764914069803;985.3102828020415;3491.546099007164;3493.7554371193773;3766.6045065267754;3892.844286228519;3949.7037722170953;10347.63783267582;10708.571657133783;10805.945970270099;10826.971437713404;10850.186236032328;10757.83863242052;10781.460926953621;10786.083182929538;10788.838167427495;10791.028807407214;7696.769096927102;7983.68065277394;8113.148119447373;8120.066192387832;8129.593520323961;5817.360090389969;5873.060310350324;5885.823425297248;6000.399960003964;6125.264968203844;9839;10149.898501014895;10153.49232538385;10185.541650625888;10188;10078.798424031625;10156.695299141038;10212;10369.74075648115;10403.843942341022;7459.888101678371;7734.342580880688;7746;8207.097219305992;8306.958465207636;7867.52794832289;7950.920490795018;7959.84080318402;8079.434439589172;8356.456830306011;6274.435300822948;7473.43949203801;7551.546907185587;7563.394928405824;7750.496217745828;7375.963120184365;7399.852002960019;7403.925960740324;7410.036695229585;7514.023176986957;6864.24493305726;7020.438364930896;7027;7028;7031.156261248683;4582.5417458253905;4931.802727890844;4967.379077615298;4976.676516026447;5036.773345199548;4878.0975519529;4893.755312234302;4979.0788704089855;5035.597152227886;5044.646874718735 07:00:29 INFO - PROCESS | 1654 | |4;prototype.html;278.8647505959609;297.1738566784339;308.61423220973774;314.65230919833596;315.3708351838083;5306.363236411631;5350.5184533391985;5372.656835791047;5379.704116273603;5388.299521062267;4850.68470549414;4942.393722040333;5016.172331565287;5197.974010129953;5330.733463326837;3772.3398405279104;3998.8800335989895;4050.6961977851633;4067.6339129478342;4079.388091786226;4030.3752918297564;4037.798110094498;4041.7574945503216;4053.412255222991;4070.104576993057;3326.600807903055;3494.2312691207894;3866.8066596670196;4004.1981108501145;4027.4695615666005;3004.3991201759736;3804.5244344457083;3895.2599006188734;3970.5433875104445;4119.546849846514;3051.61854768154;3507.8772242971627;3516.7691308042236;3521.9295614087832;3527.5766907971206;4307.827686892552;5091.410555558323;5111.061139844604;5190.974045129788;5211.5830733541225;4232;4388.297872340442;4531.297648864432;4553.704009239422;4565.817367305303;5356.1430171172815;5481.547389941668;5519.172124181365;5531.170324451325;5540.418256083134;3084.6144231971;3351.245969656832;3353.849076791526;3786.8863934082024;3814.0846196912817;5339.46605339463;5661.49970257882;5805.622634528742;5836.533077353802;5861.677607731573;3103.7516998640053;3583.784972901582;3808.9047773805323;3810;3845.7886715138907;3838.7120965927666;3885.1841113365945;3885.8445662173194;3893.6372269705944;3943.645071943539;3902.5316961964745;4273.7008409411665;4550.203714349976;4584.679072464895;4603.079384123189;5565.8293265626435;5566.749496272678;5579.776808927598;5580.888382232332;5641.717914104278;1292.9204114564257;1297.3124244150615;1325.8939284857381;1327.3761332173863;1333.5665908579751;829.088003196479;855.3798496090285;855.3883972959364;855.5422848775875;862.611824678897;4343.522212556616;4601.470830854428;4747.572718455286;4877.292792545061;5235.345581802275;5048.823291184791;5203;5609.691466969274;5621.943780562224;5644.435556444323;4256.829726810893;4994.5754610858485;5012.8496145115705;5112.364043506042;5168.870778230499;2927.195021369098;2958.4674758543238;2960.985195074054;2965.1104668599764;2972.687867773896;5141.845744627667;5156.8968620627375;5574.358948720868;5750.348528684232;5776.360892637611;4994;5263.605229607795;5921.881562368729;6003.309619393825;6012.879742405127;5964.403559644088;6006.249218847644;6018.879622407528;6023.2772067351325;6024.307204671519;4957.231629097496;5500.50495455411;5699.715014249354;5718.941995730803;5763.003238316136;4758.476567577494;5725.885482290248;5731.19763233156;5749.907517571815;5836.474717275466;4774.809007639656;5958.910616340845;5973.432523910222;6022.488088512526;6055.424734650202;3590;3811.4092315691114;3847.730658853853;3860.8534044753437;3883.1845312484693;4806.927896081632;5757.769689212552;5792.536597072309;5802.448767367094;5820.243368362086;5223.555997740083;5421.262262557383;5672.7730890763905;5708.914366284426;5710.172744721815;5248.186531087687;5540.529055030368;5629.437056294338;5632.88734225305;5637.9718101409235;4325.4593175853015;4856.514348565115;5539.950218419211;5559.416261292506;5586.128646903333;5407.485903946901;5511.995962200691;5568.721563921868;5626.774929002958;5656.490915817596;4809.639277054165;5478;5502.944970550243;5518;5535.16972454119 07:00:29 INFO - PROCESS | 1654 | |5;yui.html;604.6553464525219;709.2340272505694;747.207959562863;795.3478147918705;832.196929962586;15131.394744210233;15265.847341526582;15818.762718559217;15834.129122898235;16045.358185672589;14245.572632821006;14506.129632222073;14510.709785804303;14531.927340363283;14541.05483143595;709.1561042359575;863.8574635185203;896.3546246702397;900.1853322742895;900.3877363392891;725.8657148427558;728.3663213004667;865.0268447996004;892.6117139044504;896.9641214351418;916.4867674102491;937.5734041011358;939.9060093990614;943.9663568392633;944.9102335278137;939.2955283537347;941.2658126661215;942.0202988891544;942.561708805402;947.5357075033253;553.3857418265721;569.857535616096;572.5820151289582;574.7298769578333;575.9366469688331;14371.78442323366;14891;14959.700805983819;15263.465778697693;15284.617884552865;14388.352524136335;14785.630359240997;14844.25778711071;14929.776053359208;15118.924405378013;11729.648110556696;11766.117541184445;11897.286162830258;12007.879921200763;12125.575604853875;3846.307664620367;3879.670228030621;3891.9610803891883;3904.6095390460728;4036.0717035081802;111098;112265;112656;112681.43659281734;116339.83660163375;9928.305018648625;11165.888341116648;11292.491837867316;11344.829827552594;11395.544178232778;23296.883515582314;23297.534049319256;23300.3009909703;23302.417439564146;23313.7668623315;8002.1997800219515;8006.839863202703;8030.638621262057;8190.959045204736;8253.987294637534;4412.84555040572;4466.754328511963;4505.234110201273;4563.292689633112;4653.790579423934;3620.25784714133;3733.4213196954515;3739.4016957286703;3741.1956429367815;3746.3256613809776;665.1884700665278;665.4975493502375;671.4359937652396;693.6739732325799;695.9130108736408;599.7271241585069;629.5467263570222;669.1902797614844;670.718298314709;687.1376422589618;8861.601227944759;9292.53537323131;9435.782591091089;10041.268203642776;10218.91270370619;634.5621521150391;656.7996760987849;668.051367058768;677.3734295776486;679.7009315901087;4274.401583778274;4580.725156490621;4616.699914505546;4620.722756634613;4628.537146285412;123050.76949230394;127159.36420318024;127360;131147;140977;112285.43857280661;124714.12928806075;126925;127549;127658;89385.55307223552;104654.43018354883;105779;110827;111980;8185.508869467851;9333.57998890038;9824.263180261367;9869.11177993984;9933.801323973335;57767.71116144561;59468;60372.49068773316;61507;61950.0707489379;57847;64032;77808.16438767003;90324;95153.52423237839;12592.559260425842;13063;13127.718070482379;13263.270520121487;13293.468261269827;13397.459292181135;14363.281835908372;14639.634009149431;15103.244837757848;15164.620884477536;13288.667783305495;13408.128471649312;14228.35972381266;14403.78394324107;14414;13561.321933903462;13742.03805733589;13772.65568360799;13798.862011379757;13859.02986790915;13304.068715189844;13479.39342729599;13486.258255796025;13512.121712089076;13904.165750054624;10715.517801698787;11098.500567474644;11151.105999731335;11329.660110196708;11687.474063667325;11122.332660040423;11328.37693926809;11473;11546.364949927834;11553.826692599785 07:00:29 INFO - PROCESS | 1654 | __end_tp_report 07:00:29 INFO - PROCESS | 1654 | __start_cc_report 07:00:29 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,1426 07:00:29 INFO - PROCESS | 1654 | __end_cc_report 07:00:29 INFO - PROCESS | 1654 | __startTimestamp1475676029710__endTimestamp 07:00:29 INFO - PROCESS | 1654 | 07:00:29 INFO - PROCESS | 1654 | ------- Summary: start ------- 07:00:29 INFO - PROCESS | 1654 | Number of tests: 6 07:00:29 INFO - PROCESS | 1654 | 07:00:29 INFO - PROCESS | 1654 | [#0] dojo.html Cycles:165 Average:5790.66 Median:6001.01 stddev:647.61 (10.8%) stddev-sans-first:615.04 07:00:29 INFO - PROCESS | 1654 | Values: 3130.0 3337.1 3364.8 3389.4 3393.7 5623.9 5654.7 5706.5 5792.9 5801.1 5709.3 5719.7 5788.9 5802.4 5874.7 5245.3 5309.4 5323.8 5325.0 5332.0 5292.0 5303.9 5316.3 5356.2 5379.3 5215.7 5363.2 5427.2 5428.3 5431.0 5315.4 5317.8 5336.2 5368.2 5369.2 5162.4 5176.3 5180.8 5223.8 5224.3 5665.9 5675.1 5680.7 5735.4 5738.7 6281.3 6298.1 6303.7 6327.8 6328.0 6171.4 6234.1 6240.3 6273.1 6358.5 5829.9 5844.2 5889.8 5892.8 5928.9 6260.2 6293.4 6303.0 6306.1 6322.5 6038.9 6099.8 6113.5 6192.9 6194.6 6189.3 6236.2 6267.7 6300.7 6339.9 6213.3 6253.5 6283.2 6289.5 6297.6 6100.4 6264.4 6329.0 6331.7 6338.9 4121.2 4888.7 4919.9 4948.7 4969.6 4449.4 4454.4 4468.1 4510.5 4522.3 5813.4 5816.2 5825.4 5835.5 5843.9 6133.1 6217.9 6219.0 6252.3 6304.7 5759.7 5787.7 5838.9 5841.9 5860.9 5434.2 5444.9 5454.3 5463.7 5468.5 6196.9 6214.7 6309.9 6319.3 6328.6 6234.0 6286.5 6320.9 6357.6 6361.7 6305.3 6312.4 6316.9 6319.8 6328.7 5921.3 6018.3 6248.7 6256.1 6279.1 6217.5 6236.7 6260.2 6303.3 6355.9 6001.0 6208.1 6212.9 6222.5 6223.1 5980.8 6172.8 6185.7 6187.4 6196.7 5832.4 5891.4 5983.4 6050.5 6119.2 6109.6 6124.8 6148.8 6167.8 6189.4 5843.5 5875.4 6109.9 6136.1 6136.4 07:00:29 INFO - PROCESS | 1654 | 07:00:29 INFO - PROCESS | 1654 | [#1] ext.html Cycles:180 Average:64936.75 Median:40198.59 stddev:143118.91 (356.0%) stddev-sans-first:143438.65 07:00:29 INFO - PROCESS | 1654 | Values: 510.2 526.6 531.2 534.1 536.1 27224.5 40812.6 42205.9 42225.8 44700.8 42953.0 43223.0 43243.8 43305.6 43392.8 9736.1 9924.5 9928.0 9939.3 9963.1 9762.1 9863.6 9874.7 9877.6 9906.0 9847.0 9902.3 9905.3 9921.2 9942.5 9819.9 9861.0 9864.7 9874.7 9888.6 9766.4 9847.2 9850.9 9856.0 9870.3 41557.6 41832.0 41832.6 41834.0 41887.4 3518.2 3522.2 3745.6 3933.6 4049.8 31293.5 31336.8 32311.5 32428.5 32672.3 3292.0 3334.9 3375.2 3397.6 3407.4 370053.0 372016.0 373173.1 373253.0 373895.0 33416.7 60330.4 60801.7 60917.0 60944.3 34056.7 35435.5 35476.6 35518.0 35540.5 14226.1 14267.6 14344.2 14408.4 14430.4 11137.9 12443.0 12594.2 12606.1 12606.7 7797.6 7816.4 7841.7 7895.3 7914.5 3195.0 3203.8 3218.0 3222.7 3247.5 886.9 904.0 940.6 951.3 1026.3 28962.0 28972.3 29930.0 30159.1 31001.5 984.0 988.5 1009.9 1046.9 1112.3 15226.4 15620.7 15769.1 15823.7 15976.0 617349.9 620335.0 621475.0 622477.6 622663.0 42426.0 42560.4 42668.8 42719.9 42807.1 1181.3 1348.8 1373.6 1386.1 1387.4 29098.9 29170.7 29608.9 30098.0 30593.0 52020.5 52381.5 52398.7 52433.0 52620.9 573093.0 577069.0 578541.0 578774.0 578914.0 26660.5 26757.2 26825.2 27136.1 27167.6 47800.8 47912.5 48161.3 48598.8 48904.5 37835.4 38610.6 39523.6 40304.6 40557.8 39045.6 39276.0 40092.6 40371.2 40608.6 38014.2 38046.5 39012.2 39013.0 39064.8 31133.4 33264.1 33939.3 34133.3 34315.0 25306.7 25328.1 25414.9 25712.0 25974.2 07:00:29 INFO - PROCESS | 1654 | 07:00:29 INFO - PROCESS | 1654 | [#2] jquery.html Cycles:180 Average:34056.73 Median:25925.61 stddev:99789.71 (384.9%) stddev-sans-first:100037.56 07:00:29 INFO - PROCESS | 1654 | Values: 354.8 371.5 376.8 383.7 408.1 12025.8 15305.2 16485.0 20638.0 20688.3 19124.3 19165.1 20193.4 20377.8 20633.8 5423.4 5762.5 6126.4 6128.5 6132.9 5859.8 6044.4 6103.1 6109.9 6113.9 5690.6 5875.9 6042.8 6053.8 6054.6 5895.5 5944.6 5953.4 5955.9 5955.9 5043.4 5087.1 5151.9 5161.9 5225.6 25334.1 25749.7 25894.5 25956.7 26083.6 2012.7 2084.9 2256.6 2478.6 2539.6 15089.5 15775.8 15816.4 15983.2 16186.8 1616.7 1830.7 1847.4 1899.9 1949.0 119443.0 122513.0 122610.4 122620.0 123995.0 10318.9 10443.8 10926.2 10937.7 11077.8 13218.5 13290.3 13637.9 13647.2 13667.9 7279.9 8110.7 8461.8 8463.6 8670.8 8493.6 8514.2 8522.2 8674.5 8681.2 6457.5 6620.1 6638.4 6646.3 6726.0 1454.7 1756.6 1810.4 1821.0 1844.6 775.4 852.0 866.2 944.2 962.4 20224.0 21805.7 21863.4 21882.8 21892.0 920.6 923.7 964.8 971.4 988.7 10316.6 11697.7 11724.1 11829.8 11887.3 586704.0 595456.0 606007.0 609454.0 611194.0 72176.0 96530.0 105288.0 115312.4 124576.0 902.9 970.7 999.5 1013.9 1014.5 16890.6 19197.4 19319.4 19528.5 19668.8 45685.1 45788.0 46533.0 46828.5 51078.2 61585.3 62058.0 63163.0 63342.7 64831.7 7719.3 7915.5 7935.2 7992.3 8206.1 10931.9 12710.7 12900.9 13343.5 13496.1 9708.7 10761.5 11118.2 11268.0 11656.7 9577.5 10110.7 10998.7 11036.8 11455.5 11345.9 11740.2 11793.8 11816.6 11837.7 6978.9 7926.0 8135.8 8429.5 8557.6 10877.7 11033.7 11211.4 11213.8 11267.6 07:00:29 INFO - PROCESS | 1654 | 07:00:29 INFO - PROCESS | 1654 | [#3] mootools.html Cycles:180 Average:5696.59 Median:4805.36 stddev:3240.86 (67.4%) stddev-sans-first:3234.46 07:00:29 INFO - PROCESS | 1654 | Values: 1480.3 1511.6 1690.8 1772.8 1870.3 8494.2 9019.5 9023.6 9052.9 9099.3 7801.7 8110.6 8130.9 8497.0 8748.9 2347.1 2385.0 2388.9 2389.3 2391.2 2017.3 2350.9 2357.5 2387.9 2393.4 2360.6 2372.1 2378.4 2386.4 2392.9 2099.4 2258.0 2361.5 2373.6 2374.7 1969.6 2296.9 2300.3 2300.6 2306.7 8891.2 8922.5 9015.9 9021.5 9024.3 5149.6 5310.2 5321.2 5327.8 5339.9 3253.1 3303.2 3357.2 3364.8 3433.3 1077.6 1081.4 1081.8 1101.3 1107.1 11623.0 11724.6 11754.2 11756.4 11758.6 4494.9 4552.2 4719.2 4765.3 4765.4 9917.3 9919.0 9922.1 9928.9 9937.7 5285.5 5289.4 5290.3 5300.0 5300.5 4379.5 4545.3 4709.7 4845.3 4869.2 3820.1 3878.9 3951.7 3978.2 3984.7 1254.4 1258.9 1264.7 1268.2 1276.7 876.9 878.7 882.3 899.3 928.5 5580.5 5762.6 5989.5 6011.5 6050.2 443.5 448.9 453.3 971.8 985.3 3491.5 3493.8 3766.6 3892.8 3949.7 10347.6 10708.6 10805.9 10827.0 10850.2 10757.8 10781.5 10786.1 10788.8 10791.0 7696.8 7983.7 8113.1 8120.1 8129.6 5817.4 5873.1 5885.8 6000.4 6125.3 9839.0 10149.9 10153.5 10185.5 10188.0 10078.8 10156.7 10212.0 10369.7 10403.8 7459.9 7734.3 7746.0 8207.1 8307.0 7867.5 7950.9 7959.8 8079.4 8356.5 6274.4 7473.4 7551.5 7563.4 7750.5 7376.0 7399.9 7403.9 7410.0 7514.0 6864.2 7020.4 7027.0 7028.0 7031.2 4582.5 4931.8 4967.4 4976.7 5036.8 4878.1 4893.8 4979.1 5035.6 5044.6 07:00:29 INFO - PROCESS | 1654 | 07:00:29 INFO - PROCESS | 1654 | [#4] prototype.html Cycles:180 Average:4501.59 Median:5101.24 stddev:1409.64 (27.6%) stddev-sans-first:1377.50 07:00:29 INFO - PROCESS | 1654 | Values: 278.9 297.2 308.6 314.7 315.4 5306.4 5350.5 5372.7 5379.7 5388.3 4850.7 4942.4 5016.2 5198.0 5330.7 3772.3 3998.9 4050.7 4067.6 4079.4 4030.4 4037.8 4041.8 4053.4 4070.1 3326.6 3494.2 3866.8 4004.2 4027.5 3004.4 3804.5 3895.3 3970.5 4119.5 3051.6 3507.9 3516.8 3521.9 3527.6 4307.8 5091.4 5111.1 5191.0 5211.6 4232.0 4388.3 4531.3 4553.7 4565.8 5356.1 5481.5 5519.2 5531.2 5540.4 3084.6 3351.2 3353.8 3786.9 3814.1 5339.5 5661.5 5805.6 5836.5 5861.7 3103.8 3583.8 3808.9 3810.0 3845.8 3838.7 3885.2 3885.8 3893.6 3943.6 3902.5 4273.7 4550.2 4584.7 4603.1 5565.8 5566.7 5579.8 5580.9 5641.7 1292.9 1297.3 1325.9 1327.4 1333.6 829.1 855.4 855.4 855.5 862.6 4343.5 4601.5 4747.6 4877.3 5235.3 5048.8 5203.0 5609.7 5621.9 5644.4 4256.8 4994.6 5012.8 5112.4 5168.9 2927.2 2958.5 2961.0 2965.1 2972.7 5141.8 5156.9 5574.4 5750.3 5776.4 4994.0 5263.6 5921.9 6003.3 6012.9 5964.4 6006.2 6018.9 6023.3 6024.3 4957.2 5500.5 5699.7 5718.9 5763.0 4758.5 5725.9 5731.2 5749.9 5836.5 4774.8 5958.9 5973.4 6022.5 6055.4 3590.0 3811.4 3847.7 3860.9 3883.2 4806.9 5757.8 5792.5 5802.4 5820.2 5223.6 5421.3 5672.8 5708.9 5710.2 5248.2 5540.5 5629.4 5632.9 5638.0 4325.5 4856.5 5540.0 5559.4 5586.1 5407.5 5512.0 5568.7 5626.8 5656.5 4809.6 5478.0 5502.9 5518.0 5535.2 07:00:29 INFO - PROCESS | 1654 | 07:00:29 INFO - PROCESS | 1654 | [#5] yui.html Cycles:180 Average:23797.50 Median:3676.84 stddev:36870.15 (1002.8%) stddev-sans-first:36932.46 07:00:29 INFO - PROCESS | 1654 | Values: 604.7 709.2 747.2 795.3 832.2 15131.4 15265.8 15818.8 15834.1 16045.4 14245.6 14506.1 14510.7 14531.9 14541.1 709.2 863.9 896.4 900.2 900.4 725.9 728.4 865.0 892.6 897.0 916.5 937.6 939.9 944.0 944.9 939.3 941.3 942.0 942.6 947.5 553.4 569.9 572.6 574.7 575.9 14371.8 14891.0 14959.7 15263.5 15284.6 14388.4 14785.6 14844.3 14929.8 15118.9 11729.6 11766.1 11897.3 12007.9 12125.6 3846.3 3879.7 3892.0 3904.6 4036.1 111098.0 112265.0 112656.0 112681.4 116339.8 9928.3 11165.9 11292.5 11344.8 11395.5 23296.9 23297.5 23300.3 23302.4 23313.8 8002.2 8006.8 8030.6 8191.0 8254.0 4412.8 4466.8 4505.2 4563.3 4653.8 3620.3 3733.4 3739.4 3741.2 3746.3 665.2 665.5 671.4 693.7 695.9 599.7 629.5 669.2 670.7 687.1 8861.6 9292.5 9435.8 10041.3 10218.9 634.6 656.8 668.1 677.4 679.7 4274.4 4580.7 4616.7 4620.7 4628.5 123050.8 127159.4 127360.0 131147.0 140977.0 112285.4 124714.1 126925.0 127549.0 127658.0 89385.6 104654.4 105779.0 110827.0 111980.0 8185.5 9333.6 9824.3 9869.1 9933.8 57767.7 59468.0 60372.5 61507.0 61950.1 57847.0 64032.0 77808.2 90324.0 95153.5 12592.6 13063.0 13127.7 13263.3 13293.5 13397.5 14363.3 14639.6 15103.2 15164.6 13288.7 13408.1 14228.4 14403.8 14414.0 13561.3 13742.0 13772.7 13798.9 13859.0 13304.1 13479.4 13486.3 13512.1 13904.2 10715.5 11098.5 11151.1 11329.7 11687.5 11122.3 11328.4 11473.0 11546.4 11553.8 07:00:29 INFO - PROCESS | 1654 | -------- Summary: end -------- 07:00:29 INFO - PROCESS | 1654 | 07:00:30 INFO - TEST-INFO | 1654: exit 0 07:00:30 INFO - TEST-OK | dromaeo_css | took 1118891ms 07:00:30 INFO - TEST-START | kraken 07:00:30 INFO - operating with platform_type : mac_ 07:00:30 INFO - Initialising browser for kraken test... 07:00:30 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/tmpsvVQnG/profile http://localhost:49267/getInfo.html) 07:00:32 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 07:00:32 INFO - PROCESS | 1661 | colorDepth:24 07:00:32 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 07:00:32 INFO - PROCESS | 1661 | __metrics 07:00:32 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" 07:00:32 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] 07:00:33 INFO - TEST-INFO | 1661: exit 0 07:00:33 INFO - Browser initialized. 07:00:33 INFO - Running cycle 1/1 for kraken test... 07:00:33 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/tmpsvVQnG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:00:45 INFO - PROCESS | 1662 | RSS: Main: 235687936 07:00:45 INFO - PROCESS | 1662 | 07:00:48 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 07:00:49 INFO - PROCESS | 1662 | RSS: Main: 294764544 07:00:49 INFO - PROCESS | 1662 | 07:00:51 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 07:00:52 INFO - PROCESS | 1662 | RSS: Main: 293609472 07:00:52 INFO - PROCESS | 1662 | 07:00:55 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 07:00:56 INFO - PROCESS | 1662 | RSS: Main: 385425408 07:00:56 INFO - PROCESS | 1662 | 07:00:58 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 07:00:58 INFO - PROCESS | 1662 | RSS: Main: 286642176 07:00:58 INFO - PROCESS | 1662 | 07:01:00 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:01:01 INFO - PROCESS | 1662 | RSS: Main: 290512896 07:01:01 INFO - PROCESS | 1662 | 07:01:04 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 07:01:04 INFO - PROCESS | 1662 | RSS: Main: 329211904 07:01:04 INFO - PROCESS | 1662 | 07:01:08 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 07:01:08 INFO - PROCESS | 1662 | RSS: Main: 329019392 07:01:08 INFO - PROCESS | 1662 | 07:01:11 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 07:01:12 INFO - PROCESS | 1662 | RSS: Main: 326258688 07:01:12 INFO - PROCESS | 1662 | 07:01:13 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:01:14 INFO - PROCESS | 1662 | RSS: Main: 302014464 07:01:14 INFO - PROCESS | 1662 | 07:01:16 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:01:16 INFO - PROCESS | 1662 | RSS: Main: 306294784 07:01:16 INFO - PROCESS | 1662 | 07:01:18 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:01:19 INFO - PROCESS | 1662 | RSS: Main: 316399616 07:01:19 INFO - PROCESS | 1662 | 07:01:22 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:01:23 INFO - PROCESS | 1662 | RSS: Main: 321998848 07:01:23 INFO - PROCESS | 1662 | 07:01:26 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:01:26 INFO - PROCESS | 1662 | RSS: Main: 321515520 07:01:26 INFO - PROCESS | 1662 | 07:01:28 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 07:01:28 INFO - PROCESS | 1662 | RSS: Main: 365633536 07:01:28 INFO - PROCESS | 1662 | 07:01:28 INFO - PROCESS | 1662 | __start_tp_report 07:01:28 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 07:01:28 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 07:01:28 INFO - PROCESS | 1662 | |i|pagename|runs| 07:01:28 INFO - PROCESS | 1662 | |0;ai-astar;115;114;113;112;113;113;113;114;114;112 07:01:28 INFO - PROCESS | 1662 | |1;audio-beat-detection;147;113;118;142;112;116;113;113;146;113 07:01:28 INFO - PROCESS | 1662 | |2;audio-dft;181;185;185;183;185;186;184;186;182;185 07:01:28 INFO - PROCESS | 1662 | |3;audio-fft;79;58;58;86;61;59;58;59;81;57 07:01:28 INFO - PROCESS | 1662 | |4;audio-oscillator;83;72;91;70;68;89;130;98;74;90 07:01:28 INFO - PROCESS | 1662 | |5;imaging-gaussian-blur;88;84;87;85;87;101;88;84;87;83 07:01:28 INFO - PROCESS | 1662 | |6;imaging-darkroom;93;95;93;94;93;108;141;151;144;93 07:01:28 INFO - PROCESS | 1662 | |7;imaging-desaturate;84;84;85;85;84;84;86;83;84;105 07:01:28 INFO - PROCESS | 1662 | |8;json-parse-financial;54;51;51;52;53;54;52;54;52;55 07:01:28 INFO - PROCESS | 1662 | |9;json-stringify-tinderbox;64;75;79;72;71;68;69;70;70;68 07:01:28 INFO - PROCESS | 1662 | |10;stanford-crypto-aes;87;90;88;89;89;85;93;86;75;97 07:01:28 INFO - PROCESS | 1662 | |11;stanford-crypto-ccm;143;141;150;150;152;158;148;142;173;139 07:01:28 INFO - PROCESS | 1662 | |12;stanford-crypto-pbkdf2;170;170;170;169;171;179;168;180;169;169 07:01:28 INFO - PROCESS | 1662 | |13;stanford-crypto-sha256-iterative;69;69;70;70;68;69;68;68;68;68 07:01:28 INFO - PROCESS | 1662 | __end_tp_report 07:01:28 INFO - PROCESS | 1662 | __start_cc_report 07:01:28 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,1971 07:01:28 INFO - PROCESS | 1662 | __end_cc_report 07:01:28 INFO - PROCESS | 1662 | __startTimestamp1475676088729__endTimestamp 07:01:28 INFO - PROCESS | 1662 | 07:01:28 INFO - PROCESS | 1662 | ------- Summary: start ------- 07:01:28 INFO - PROCESS | 1662 | Number of tests: 14 07:01:28 INFO - PROCESS | 1662 | 07:01:28 INFO - PROCESS | 1662 | [#0] ai-astar Cycles:10 Average:113.30 Median:113.50 stddev:0.95 (0.8%) stddev-sans-first:0.78 07:01:28 INFO - PROCESS | 1662 | Values: 115.0 114.0 113.0 112.0 113.0 113.0 113.0 114.0 114.0 112.0 07:01:28 INFO - PROCESS | 1662 | 07:01:28 INFO - PROCESS | 1662 | [#1] audio-beat-detection Cycles:10 Average:123.30 Median:117.00 stddev:15.13 (12.9%) stddev-sans-first:13.40 07:01:28 INFO - PROCESS | 1662 | Values: 147.0 113.0 118.0 142.0 112.0 116.0 113.0 113.0 146.0 113.0 07:01:28 INFO - PROCESS | 1662 | 07:01:28 INFO - PROCESS | 1662 | [#2] audio-dft Cycles:10 Average:184.20 Median:185.00 stddev:1.69 (0.9%) stddev-sans-first:1.33 07:01:28 INFO - PROCESS | 1662 | Values: 181.0 185.0 185.0 183.0 185.0 186.0 184.0 186.0 182.0 185.0 07:01:28 INFO - PROCESS | 1662 | 07:01:28 INFO - PROCESS | 1662 | [#3] audio-fft Cycles:10 Average:65.60 Median:60.00 stddev:11.49 (19.2%) stddev-sans-first:11.12 07:01:28 INFO - PROCESS | 1662 | Values: 79.0 58.0 58.0 86.0 61.0 59.0 58.0 59.0 81.0 57.0 07:01:28 INFO - PROCESS | 1662 | 07:01:28 INFO - PROCESS | 1662 | [#4] audio-oscillator Cycles:10 Average:86.50 Median:86.00 stddev:18.43 (21.4%) stddev-sans-first:19.50 07:01:28 INFO - PROCESS | 1662 | Values: 83.0 72.0 91.0 70.0 68.0 89.0 130.0 98.0 74.0 90.0 07:01:28 INFO - PROCESS | 1662 | 07:01:28 INFO - PROCESS | 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:87.40 Median:87.00 stddev:5.10 (5.9%) stddev-sans-first:5.41 07:01:28 INFO - PROCESS | 1662 | Values: 88.0 84.0 87.0 85.0 87.0 101.0 88.0 84.0 87.0 83.0 07:01:28 INFO - PROCESS | 1662 | 07:01:28 INFO - PROCESS | 1662 | [#6] imaging-darkroom Cycles:10 Average:110.50 Median:93.00 stddev:24.58 (26.4%) stddev-sans-first:25.24 07:01:28 INFO - PROCESS | 1662 | Values: 93.0 95.0 93.0 94.0 93.0 108.0 141.0 151.0 144.0 93.0 07:01:28 INFO - PROCESS | 1662 | 07:01:28 INFO - PROCESS | 1662 | [#7] imaging-desaturate Cycles:10 Average:86.40 Median:84.00 stddev:6.59 (7.8%) stddev-sans-first:6.93 07:01:28 INFO - PROCESS | 1662 | Values: 84.0 84.0 85.0 85.0 84.0 84.0 86.0 83.0 84.0 105.0 07:01:28 INFO - PROCESS | 1662 | 07:01:28 INFO - PROCESS | 1662 | [#8] json-parse-financial Cycles:10 Average:52.80 Median:53.50 stddev:1.40 (2.6%) stddev-sans-first:1.41 07:01:28 INFO - PROCESS | 1662 | Values: 54.0 51.0 51.0 52.0 53.0 54.0 52.0 54.0 52.0 55.0 07:01:28 INFO - PROCESS | 1662 | 07:01:28 INFO - PROCESS | 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:70.60 Median:70.50 stddev:4.12 (5.8%) stddev-sans-first:3.61 07:01:28 INFO - PROCESS | 1662 | Values: 64.0 75.0 79.0 72.0 71.0 68.0 69.0 70.0 70.0 68.0 07:01:28 INFO - PROCESS | 1662 | 07:01:28 INFO - PROCESS | 1662 | [#10] stanford-crypto-aes Cycles:10 Average:87.90 Median:89.00 stddev:5.72 (6.4%) stddev-sans-first:6.06 07:01:28 INFO - PROCESS | 1662 | Values: 87.0 90.0 88.0 89.0 89.0 85.0 93.0 86.0 75.0 97.0 07:01:28 INFO - PROCESS | 1662 | 07:01:28 INFO - PROCESS | 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:149.60 Median:150.00 stddev:10.08 (6.7%) stddev-sans-first:10.40 07:01:28 INFO - PROCESS | 1662 | Values: 143.0 141.0 150.0 150.0 152.0 158.0 148.0 142.0 173.0 139.0 07:01:28 INFO - PROCESS | 1662 | 07:01:28 INFO - PROCESS | 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.50 Median:170.00 stddev:4.30 (2.5%) stddev-sans-first:4.53 07:01:28 INFO - PROCESS | 1662 | Values: 170.0 170.0 170.0 169.0 171.0 179.0 168.0 180.0 169.0 169.0 07:01:28 INFO - PROCESS | 1662 | 07:01:28 INFO - PROCESS | 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:69.00 stddev:0.82 (1.2%) stddev-sans-first:0.87 07:01:28 INFO - PROCESS | 1662 | Values: 69.0 69.0 70.0 70.0 68.0 69.0 68.0 68.0 68.0 68.0 07:01:28 INFO - PROCESS | 1662 | -------- Summary: end -------- 07:01:28 INFO - PROCESS | 1662 | 07:01:29 INFO - TEST-INFO | 1662: exit 0 07:01:29 INFO - TEST-OK | kraken | took 59457ms 07:01:29 INFO - SUITE-END | took 1178s 07:01:29 INFO - Completed test suite (00:19:38) 07:01:29 INFO - javascript benchmark 07:01:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3129.968700312997, 3337.065621625945, 3364.7812892162005, 3389.389909816232, 3393.745469089819, 5623.915641265374, 5654.717264136792, 5706.486416222539, 5792.855178620536, 5801.1008293714485, 5709.343425506057, 5719.656820590768, 5788.942110578893, 5802.361740208584, 5874.70626468677, 5245.318108645871, 5309.389420216667, 5323.787048518055, 5325.0, 5331.973340133294, 5292.02097611943, 5303.867403314909, 5316.282301889234, 5356.1697936819855, 5379.327584051994, 5215.6609820361555, 5363.222332761748, 5427.240186373891, 5428.348598168225, 5431.049566325897, 5315.388730296027, 5317.8404647860625, 5336.199570064483, 5368.194770784375, 5369.194620806871, 5162.380514338303, 5176.327077479941, 5180.792768289264, 5223.817166399196, 5224.346956630421, 5665.915011274834, 5675.148727690879, 5680.6591604503865, 5735.426457354273, 5738.741756620962, 6281.3404592518045, 6298.149749783812, 6303.653299068549, 6327.810165695037, 6327.96836015817, 6171.444569988723, 6234.127222188898, 6240.282367527747, 6273.059041143865, 6358.523110766711, 5829.912551311733, 5844.152597873284, 5889.823305300848, 5892.764289428461, 5928.911066334009, 6260.217472815898, 6293.370662933761, 6302.968485157545, 6306.1171435998995, 6322.462590679844, 6038.939610603926, 6099.824329823284, 6113.506711826627, 6192.938070619326, 6194.5973511721595, 6189.257289125244, 6236.220472440945, 6267.749290028348, 6300.653464059521, 6339.904901426482, 6213.285472170668, 6253.530985176039, 6283.214598175228, 6289.496840252768, 6297.622142671455, 6100.420460056288, 6264.373562643699, 6328.968355158195, 6331.651759153198, 6338.904916426257, 4121.237571049365, 4888.682235654742, 4919.926201106986, 4948.6783359082265, 4969.627277954168, 4449.421575195204, 4454.420925279693, 4468.061707041557, 4510.548945105464, 4522.34426008227, 5813.389594092644, 5816.243888294494, 5825.38833422493, 5835.503982161564, 5843.941560584425, 6133.1413602095745, 6217.875642487125, 6219.0, 6252.343503932022, 6304.716287767062, 5759.7408116634015, 5787.71061446926, 5838.8906107839375, 5841.883162336814, 5860.8534786629825, 5434.157705555645, 5444.891102177934, 5454.3454785426, 5463.672179669233, 5468.48049435303, 6196.907046394398, 6214.658193799384, 6309.90535141964, 6319.3364696706185, 6328.588641738273, 6234.0025595904435, 6286.52851036165, 6320.936790632219, 6357.554971151975, 6361.745530178741, 6305.337939516285, 6312.43188113072, 6316.873662526816, 6319.8104056878365, 6328.683565821782, 5921.259842519685, 6018.277806663228, 6248.68756562161, 6256.092866534418, 6279.120923070683, 6217.4715149212825, 6236.656983865931, 6260.186175797117, 6303.338149494237, 6355.87288254223, 6001.009833377619, 6208.099825525183, 6212.875742485216, 6222.533310001859, 6223.066540019034, 5980.760769569169, 6172.783952561639, 6185.659788711664, 6187.412195841388, 6196.659183744938, 5832.416758324303, 5891.410858914074, 5983.401659833982, 6050.546209034428, 6119.2350956130485, 6109.602875813058, 6124.782500621427, 6148.754049837957, 6167.814965551041, 6189.442950134509, 5843.503302219189, 5875.412458754261, 6109.877802444015, 6136.079588061826, 6136.417040381157], "value": 5747.755292601768, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [510.1556923291951, 526.6339893773827, 531.221760121422, 534.126702840855, 536.1341433584762, 27224.45551088977, 40812.59187408125, 42205.944851378656, 42225.78887105564, 44700.776496117476, 42953.0, 43223.0, 43243.78378108113, 43305.566944330545, 43392.78303608477, 9736.123748862601, 9924.503774811266, 9928.0, 9939.2545559083, 9963.053509916583, 9762.072603102693, 9863.60545578176, 9874.703758887208, 9877.555510002067, 9906.0, 9847.0, 9902.306838521306, 9905.257105717064, 9921.206303495703, 9942.502874856264, 9819.852702209473, 9860.950695246549, 9864.704058878246, 9874.654387096489, 9888.555015024342, 9766.36518626287, 9847.16299114576, 9850.901490985141, 9855.950720246425, 9870.309078364517, 41557.58442415568, 41831.95420114491, 41832.58167418348, 41834.0, 41887.37168942468, 3518.1556426457464, 3522.20750331176, 3745.5879853216125, 3933.567307596162, 4049.8380064797375, 31293.530597041063, 31336.843315783277, 32311.51532727011, 32428.513572296433, 32672.346553069045, 3291.9506207406907, 3334.866605335784, 3375.206826395795, 3397.59228892532, 3407.352603005421, 370053.0, 372016.0, 373173.1341343303, 373252.99999999726, 373895.0, 33416.66583334184, 60330.396696032476, 60801.695991520646, 60917.0, 60944.34333939889, 34056.659433405846, 35435.468467973, 35476.64523354785, 35518.0, 35540.46689299611, 14226.075305105134, 14267.571972840624, 14344.211068391338, 14408.351624176938, 14430.422783088768, 11137.888621113685, 12443.0, 12594.24434533931, 12606.117571769888, 12606.747865042647, 7797.571133587594, 7816.374690024784, 7841.686332546634, 7895.328897043701, 7914.525128492309, 3194.9680503195136, 3203.8077715337154, 3217.9678203218136, 3222.7099561039618, 3247.5128730690585, 886.8625363068735, 903.9909600904039, 940.5767404667927, 951.286535098676, 1026.3277553202665, 28962.0, 28972.27569310784, 29929.95245166409, 30159.09522714278, 31001.534976975363, 983.9508024598741, 988.4761076629397, 1009.9141572966233, 1046.884842667306, 1112.3270421395102, 15226.390944362323, 15620.68758624844, 15769.132697701507, 15823.683526329409, 15976.034457918602, 617349.9132504398, 620335.0, 621475.0, 622477.6398478185, 622663.0, 42426.0, 42560.361594576105, 42668.78665606714, 42719.9320017002, 42807.14385712268, 1181.261711430356, 1348.7707089794562, 1373.5535950816025, 1386.0921096681489, 1387.4450219912117, 29098.854505727337, 29170.7082929168, 29608.851955740083, 30098.0, 30593.0, 52020.479795201565, 52381.47618523918, 52398.73800630972, 52432.95134097373, 52620.94758104893, 573093.0, 577069.0, 578541.0, 578774.0, 578914.0, 26660.466790664464, 26757.197284081507, 26825.195244142706, 27136.050238241565, 27167.59248611312, 47800.7609961948, 47912.520874790804, 48161.27758083701, 48598.757006216154, 48904.51095488999, 37835.432468512445, 38610.613893860704, 39523.60476395199, 40304.59695403126, 40557.797211013756, 39045.60954390533, 39276.0, 40092.58567908702, 40371.1925761489, 40608.59391406166, 38014.23971520499, 38046.47814087405, 39012.2197556053, 39013.02467438337, 39064.80467597644, 31133.377332452772, 33264.10367514858, 33939.321213576084, 34133.31733365269, 34315.0, 25306.746932530437, 25328.11351602685, 25414.872925635253, 25711.971521138945, 25974.22077337683], "value": 18535.68448867193, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.80663038643934, 371.53372517490544, 376.7513441128855, 383.73138802838, 408.0940312506235, 12025.819612705816, 15305.158216298112, 16485.0, 20637.968101594935, 20688.27591034314, 19124.33064842731, 19165.1375688094, 20193.394198174043, 20377.796222037774, 20633.793662063374, 5423.376311724143, 5762.481376676098, 6126.448619624233, 6128.479079278267, 6132.908006379907, 5859.824205273827, 6044.395560443964, 6103.115048317991, 6109.908351374733, 6113.877722445548, 5690.63010904292, 5875.88248235035, 6042.758289668408, 6053.82062457515, 6054.60645058072, 5895.528357731371, 5944.554158438113, 5953.404659534055, 5955.851103722398, 5955.910661340105, 5043.42000669924, 5087.058894104603, 5151.948480515184, 5161.896762064737, 5225.608079394061, 25334.113306034385, 25749.74250257492, 25894.482110357876, 25956.74043259562, 26083.608745868827, 2012.734030151153, 2084.9061792219236, 2256.5599708056934, 2478.5786416309093, 2539.5682733935278, 15089.54731358061, 15775.842241577668, 15816.367345306175, 15983.200839957955, 16186.75719864203, 1616.708992381371, 1830.7345434911863, 1847.3534263007973, 1899.9050047497708, 1948.9816645115993, 119443.0, 122513.0, 122610.38694806558, 122620.0, 123995.0, 10318.896811031944, 10443.791124177475, 10926.235163538635, 10937.671869843918, 11077.833832492519, 13218.537351192663, 13290.3354832258, 13637.931810340884, 13647.249401283025, 13667.863321366858, 7279.89080163798, 8110.716124935599, 8461.830763384698, 8463.619137138843, 8670.82658346842, 8493.575321233913, 8514.191151840565, 8522.21033582942, 8674.479531228146, 8681.175288347597, 6457.515686323544, 6620.106285651472, 6638.369354911276, 6646.302138275411, 6726.0, 1454.70178613384, 1756.6135450200936, 1810.4297146398842, 1820.963580728378, 1844.6402951424343, 775.3835700618022, 851.9914800852036, 866.1641515937151, 944.1691311645845, 962.4369743700014, 20224.02946140873, 21805.67736437102, 21863.41822084015, 21882.847893221257, 21891.992736499218, 920.6455514626917, 923.6628630549801, 964.7925695975399, 971.3831716859845, 988.7330420786348, 10316.587336506605, 11697.707557311136, 11724.062075033977, 11829.822552661717, 11887.286762794087, 586704.0, 595456.0, 606007.0, 609454.0, 611194.0, 72176.0, 96530.0, 105288.0, 115312.42343788395, 124576.0, 902.8510295801119, 970.6602689058914, 999.5402115027024, 1013.9340942838692, 1014.518103900656, 16890.577735556217, 19197.424077277705, 19319.4204173875, 19528.511787205432, 19668.80331196727, 45685.086298273185, 45788.0, 46533.0, 46828.531714682416, 51078.23382649189, 61585.25089552337, 62058.0, 63163.0, 63342.68328658327, 64831.67584162049, 7719.266669666373, 7915.485493442908, 7935.246151615587, 7992.320652744349, 8206.056303524932, 10931.945340273247, 12710.665380134953, 12900.870991289967, 13343.532976345781, 13496.05527613097, 9708.708738737849, 10761.461926903467, 11118.166137539554, 11268.0, 11656.708582285512, 9577.521123943914, 10110.69667909964, 10998.725031874266, 11036.77760068754, 11455.541778328774, 11345.943270283597, 11740.236884602473, 11793.764124717285, 11816.586419475278, 11837.704057398634, 6978.91826766852, 7925.960370198112, 8135.75592732219, 8429.4942303462, 8557.61490732906, 10877.728056798644, 11033.668989930315, 11211.439428028729, 11213.775724485302, 11267.647882254181], "value": 9527.735418043734, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1480.3190532355115, 1511.5918701950475, 1690.8224636413177, 1772.751814745935, 1870.3453791173092, 8494.150584941503, 9019.503927283997, 9023.639054437832, 9052.90947090529, 9099.272058235341, 7801.687932482694, 8110.594470276477, 8130.878036829437, 8497.0, 8748.912510874889, 2347.0753981734942, 2384.9761502384927, 2388.892499837503, 2389.330987323552, 2391.2467572714627, 2017.273781438681, 2350.8942097605564, 2357.481354102095, 2387.8925448354867, 2393.413613664655, 2360.6104992676187, 2372.086746602562, 2378.4172877645, 2386.4033991502124, 2392.9042838286446, 2099.443647433416, 2258.040332858545, 2361.49227915998, 2373.6320870265135, 2374.7031621047367, 1969.6356174107837, 2296.9425764355856, 2300.344401845466, 2300.6318988961684, 2306.7347255065715, 8891.155340242667, 8922.464652120894, 9015.909840901639, 9021.548922553846, 9024.323175761843, 5149.562287205592, 5310.150375939869, 5321.201819727034, 5327.813526526553, 5339.946600533984, 3253.1216571525556, 3303.223742420529, 3357.2110554019787, 3364.7981121132566, 3433.2790114076074, 1077.617445806735, 1081.4376524207446, 1081.7728277061826, 1101.2841652925583, 1107.053469283759, 11623.0, 11724.589639362666, 11754.177207595472, 11756.412179390996, 11758.58844940423, 4494.910101798011, 4552.203364411214, 4719.244920812654, 4765.309030190603, 4765.404324459442, 9917.256205784452, 9918.950405247928, 9922.107010369102, 9928.851067233998, 9937.65218217359, 5285.497877701612, 5289.365276166807, 5290.259363689087, 5300.0, 5300.469953004668, 4379.540148284448, 4545.272756358967, 4709.693869898446, 4845.3216549683075, 4869.196582563908, 3820.083180036827, 3878.9224215515533, 3951.7431366961055, 3978.224246271949, 3984.6812255019527, 1254.4229654358915, 1258.8741125887339, 1264.6522206393315, 1268.2308180088723, 1276.6808297925518, 876.9386142969931, 878.7188099808, 882.2765332427476, 899.2958343938201, 928.5218112671979, 5580.497755202051, 5762.567807414461, 5989.490893274034, 6011.549133814981, 6050.21347224858, 443.52321254650735, 448.9236829738887, 453.2702349217757, 971.7764914069803, 985.3102828020415, 3491.546099007164, 3493.7554371193773, 3766.6045065267754, 3892.844286228519, 3949.7037722170953, 10347.63783267582, 10708.571657133783, 10805.945970270099, 10826.971437713404, 10850.186236032328, 10757.83863242052, 10781.460926953621, 10786.083182929538, 10788.838167427495, 10791.028807407214, 7696.769096927102, 7983.68065277394, 8113.148119447373, 8120.066192387832, 8129.593520323961, 5817.360090389969, 5873.060310350324, 5885.823425297248, 6000.399960003964, 6125.264968203844, 9839.0, 10149.898501014895, 10153.49232538385, 10185.541650625888, 10188.0, 10078.798424031625, 10156.695299141038, 10212.0, 10369.74075648115, 10403.843942341022, 7459.888101678371, 7734.342580880688, 7746.0, 8207.097219305992, 8306.958465207636, 7867.52794832289, 7950.920490795018, 7959.84080318402, 8079.434439589172, 8356.456830306011, 6274.435300822948, 7473.43949203801, 7551.546907185587, 7563.394928405824, 7750.496217745828, 7375.963120184365, 7399.852002960019, 7403.925960740324, 7410.036695229585, 7514.023176986957, 6864.24493305726, 7020.438364930896, 7027.0, 7028.0, 7031.156261248683, 4582.5417458253905, 4931.802727890844, 4967.379077615298, 4976.676516026447, 5036.773345199548, 4878.0975519529, 4893.755312234302, 4979.0788704089855, 5035.597152227886, 5044.646874718735], "value": 4527.188525134408, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [278.8647505959609, 297.1738566784339, 308.61423220973774, 314.65230919833596, 315.3708351838083, 5306.363236411631, 5350.5184533391985, 5372.656835791047, 5379.704116273603, 5388.299521062267, 4850.68470549414, 4942.393722040333, 5016.172331565287, 5197.974010129953, 5330.733463326837, 3772.3398405279104, 3998.8800335989895, 4050.6961977851633, 4067.6339129478342, 4079.388091786226, 4030.3752918297564, 4037.798110094498, 4041.7574945503216, 4053.412255222991, 4070.104576993057, 3326.600807903055, 3494.2312691207894, 3866.8066596670196, 4004.1981108501145, 4027.4695615666005, 3004.3991201759736, 3804.5244344457083, 3895.2599006188734, 3970.5433875104445, 4119.546849846514, 3051.61854768154, 3507.8772242971627, 3516.7691308042236, 3521.9295614087832, 3527.5766907971206, 4307.827686892552, 5091.410555558323, 5111.061139844604, 5190.974045129788, 5211.5830733541225, 4232.0, 4388.297872340442, 4531.297648864432, 4553.704009239422, 4565.817367305303, 5356.1430171172815, 5481.547389941668, 5519.172124181365, 5531.170324451325, 5540.418256083134, 3084.6144231971, 3351.245969656832, 3353.849076791526, 3786.8863934082024, 3814.0846196912817, 5339.46605339463, 5661.49970257882, 5805.622634528742, 5836.533077353802, 5861.677607731573, 3103.7516998640053, 3583.784972901582, 3808.9047773805323, 3810.0, 3845.7886715138907, 3838.7120965927666, 3885.1841113365945, 3885.8445662173194, 3893.6372269705944, 3943.645071943539, 3902.5316961964745, 4273.7008409411665, 4550.203714349976, 4584.679072464895, 4603.079384123189, 5565.8293265626435, 5566.749496272678, 5579.776808927598, 5580.888382232332, 5641.717914104278, 1292.9204114564257, 1297.3124244150615, 1325.8939284857381, 1327.3761332173863, 1333.5665908579751, 829.088003196479, 855.3798496090285, 855.3883972959364, 855.5422848775875, 862.611824678897, 4343.522212556616, 4601.470830854428, 4747.572718455286, 4877.292792545061, 5235.345581802275, 5048.823291184791, 5203.0, 5609.691466969274, 5621.943780562224, 5644.435556444323, 4256.829726810893, 4994.5754610858485, 5012.8496145115705, 5112.364043506042, 5168.870778230499, 2927.195021369098, 2958.4674758543238, 2960.985195074054, 2965.1104668599764, 2972.687867773896, 5141.845744627667, 5156.8968620627375, 5574.358948720868, 5750.348528684232, 5776.360892637611, 4994.0, 5263.605229607795, 5921.881562368729, 6003.309619393825, 6012.879742405127, 5964.403559644088, 6006.249218847644, 6018.879622407528, 6023.2772067351325, 6024.307204671519, 4957.231629097496, 5500.50495455411, 5699.715014249354, 5718.941995730803, 5763.003238316136, 4758.476567577494, 5725.885482290248, 5731.19763233156, 5749.907517571815, 5836.474717275466, 4774.809007639656, 5958.910616340845, 5973.432523910222, 6022.488088512526, 6055.424734650202, 3590.0, 3811.4092315691114, 3847.730658853853, 3860.8534044753437, 3883.1845312484693, 4806.927896081632, 5757.769689212552, 5792.536597072309, 5802.448767367094, 5820.243368362086, 5223.555997740083, 5421.262262557383, 5672.7730890763905, 5708.914366284426, 5710.172744721815, 5248.186531087687, 5540.529055030368, 5629.437056294338, 5632.88734225305, 5637.9718101409235, 4325.4593175853015, 4856.514348565115, 5539.950218419211, 5559.416261292506, 5586.128646903333, 5407.485903946901, 5511.995962200691, 5568.721563921868, 5626.774929002958, 5656.490915817596, 4809.639277054165, 5478.0, 5502.944970550243, 5518.0, 5535.16972454119], "value": 4051.161670804804, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [604.6553464525219, 709.2340272505694, 747.207959562863, 795.3478147918705, 832.196929962586, 15131.394744210233, 15265.847341526582, 15818.762718559217, 15834.129122898235, 16045.358185672589, 14245.572632821006, 14506.129632222073, 14510.709785804303, 14531.927340363283, 14541.05483143595, 709.1561042359575, 863.8574635185203, 896.3546246702397, 900.1853322742895, 900.3877363392891, 725.8657148427558, 728.3663213004667, 865.0268447996004, 892.6117139044504, 896.9641214351418, 916.4867674102491, 937.5734041011358, 939.9060093990614, 943.9663568392633, 944.9102335278137, 939.2955283537347, 941.2658126661215, 942.0202988891544, 942.561708805402, 947.5357075033253, 553.3857418265721, 569.857535616096, 572.5820151289582, 574.7298769578333, 575.9366469688331, 14371.78442323366, 14891.0, 14959.700805983819, 15263.465778697693, 15284.617884552865, 14388.352524136335, 14785.630359240997, 14844.25778711071, 14929.776053359208, 15118.924405378013, 11729.648110556696, 11766.117541184445, 11897.286162830258, 12007.879921200763, 12125.575604853875, 3846.307664620367, 3879.670228030621, 3891.9610803891883, 3904.6095390460728, 4036.0717035081802, 111098.0, 112265.0, 112656.0, 112681.43659281734, 116339.83660163375, 9928.305018648625, 11165.888341116648, 11292.491837867316, 11344.829827552594, 11395.544178232778, 23296.883515582314, 23297.534049319256, 23300.3009909703, 23302.417439564146, 23313.7668623315, 8002.1997800219515, 8006.839863202703, 8030.638621262057, 8190.959045204736, 8253.987294637534, 4412.84555040572, 4466.754328511963, 4505.234110201273, 4563.292689633112, 4653.790579423934, 3620.25784714133, 3733.4213196954515, 3739.4016957286703, 3741.1956429367815, 3746.3256613809776, 665.1884700665278, 665.4975493502375, 671.4359937652396, 693.6739732325799, 695.9130108736408, 599.7271241585069, 629.5467263570222, 669.1902797614844, 670.718298314709, 687.1376422589618, 8861.601227944759, 9292.53537323131, 9435.782591091089, 10041.268203642776, 10218.91270370619, 634.5621521150391, 656.7996760987849, 668.051367058768, 677.3734295776486, 679.7009315901087, 4274.401583778274, 4580.725156490621, 4616.699914505546, 4620.722756634613, 4628.537146285412, 123050.76949230394, 127159.36420318024, 127360.0, 131147.0, 140977.0, 112285.43857280661, 124714.12928806075, 126925.0, 127549.0, 127658.0, 89385.55307223552, 104654.43018354883, 105779.0, 110827.0, 111980.0, 8185.508869467851, 9333.57998890038, 9824.263180261367, 9869.11177993984, 9933.801323973335, 57767.71116144561, 59468.0, 60372.49068773316, 61507.0, 61950.0707489379, 57847.0, 64032.0, 77808.16438767003, 90324.0, 95153.52423237839, 12592.559260425842, 13063.0, 13127.718070482379, 13263.270520121487, 13293.468261269827, 13397.459292181135, 14363.281835908372, 14639.634009149431, 15103.244837757848, 15164.620884477536, 13288.667783305495, 13408.128471649312, 14228.35972381266, 14403.78394324107, 14414.0, 13561.321933903462, 13742.03805733589, 13772.65568360799, 13798.862011379757, 13859.02986790915, 13304.068715189844, 13479.39342729599, 13486.258255796025, 13512.121712089076, 13904.165750054624, 10715.517801698787, 11098.500567474644, 11151.105999731335, 11329.660110196708, 11687.474063667325, 11122.332660040423, 11328.37693926809, 11473.0, 11546.364949927834, 11553.826692599785], "value": 7819.550901452005, "unit": "score"}], "name": "dromaeo_css", "value": 7253.06807091796, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 113.0, 112.0, 113.0, 113.0, 113.0, 114.0, 114.0, 112.0], "value": 113.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 113.0, 118.0, 142.0, 112.0, 116.0, 113.0, 113.0, 146.0, 113.0], "value": 123.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 185.0, 185.0, 183.0, 185.0, 186.0, 184.0, 186.0, 182.0, 185.0], "value": 184.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 58.0, 86.0, 61.0, 59.0, 58.0, 59.0, 81.0, 57.0], "value": 65.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 72.0, 91.0, 70.0, 68.0, 89.0, 130.0, 98.0, 74.0, 90.0], "value": 86.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 87.0, 85.0, 87.0, 101.0, 88.0, 84.0, 87.0, 83.0], "value": 87.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 93.0, 94.0, 93.0, 108.0, 141.0, 151.0, 144.0, 93.0], "value": 110.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 85.0, 85.0, 84.0, 84.0, 86.0, 83.0, 84.0, 105.0], "value": 86.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 51.0, 51.0, 52.0, 53.0, 54.0, 52.0, 54.0, 52.0, 55.0], "value": 52.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 75.0, 79.0, 72.0, 71.0, 68.0, 69.0, 70.0, 70.0, 68.0], "value": 70.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 90.0, 88.0, 89.0, 89.0, 85.0, 93.0, 86.0, 75.0, 97.0], "value": 87.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 141.0, 150.0, 150.0, 152.0, 158.0, 148.0, 142.0, 173.0, 139.0], "value": 149.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 170.0, 170.0, 169.0, 171.0, 179.0, 168.0, 180.0, 169.0, 169.0], "value": 171.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 70.0, 70.0, 68.0, 69.0, 68.0, 68.0, 68.0, 68.0], "value": 68.7, "unit": "score"}], "name": "kraken", "value": 1458.3, "alertThreshold": 2.0}]} 07:01:29 INFO - Return code: 0 07:01:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:01:30 INFO - # TBPL SUCCESS # 07:01:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:01:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:01:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:01:30 INFO - 2016-10-05 07:01:30.079 screenresolution[1666:9781] starting screenresolution argv=screenresolution get 07:01:30 INFO - 2016-10-05 07:01:30.082 screenresolution[1666:9781] Display 0: 1600x1200x32@60 07:01:30 INFO - 2016-10-05 07:01:30.088 screenresolution[1667:9784] starting screenresolution argv=screenresolution list 07:01:30 INFO - Available Modes on Display 0 07:01:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:01:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:01:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:01:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:01:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:01:30 INFO - Intel Iris: 07:01:30 INFO - Chipset Model: Intel Iris 07:01:30 INFO - Type: GPU 07:01:30 INFO - Bus: Built-In 07:01:30 INFO - VRAM (Dynamic, Max): 1536 MB 07:01:30 INFO - Vendor: Intel (0x8086) 07:01:30 INFO - Device ID: 0x0a2e 07:01:30 INFO - Revision ID: 0x0009 07:01:30 INFO - Displays: 07:01:30 INFO - Display: 07:01:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:01:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:01:30 INFO - Display Serial Number: 1600x1200 60 07:01:30 INFO - Main Display: Yes 07:01:30 INFO - Mirror: Off 07:01:30 INFO - Online: Yes 07:01:30 INFO - Rotation: Supported 07:01:30 INFO - Return code: 0 07:01:30 INFO - Running post-action listener: _resource_record_post_action 07:01:30 INFO - [mozharness: 2016-10-05 14:01:30.331501Z] Finished run-tests step (success) 07:01:30 INFO - Running post-run listener: _resource_record_post_run 07:01:30 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 36897792; Write bytes: 708644864; Read time: 375; Write time: 1838 07:01:30 INFO - TinderboxPrint: CPU usage
53.6% 07:01:30 INFO - TinderboxPrint: I/O read bytes / time
36,897,792 / 375 07:01:30 INFO - TinderboxPrint: I/O write bytes / time
708,644,864 / 1,838 07:01:30 INFO - TinderboxPrint: CPU idle
2,250.6 (46.4%) 07:01:30 INFO - TinderboxPrint: CPU system
972.7 (20.0%) 07:01:30 INFO - TinderboxPrint: CPU user
1,630.4 (33.6%) 07:01:30 INFO - TinderboxPrint: Swap in / out
444,411,904 / 0 07:01:30 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 397136384; Write bytes: 387452416; Read time: 29601; Write time: 692 07:01:30 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 30620672; Write bytes: 294703616; Read time: 294; Write time: 1092 07:01:30 INFO - Running post-run listener: _upload_blobber_files 07:01:30 INFO - Blob upload gear active. 07:01:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:01:30 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 07:01:30 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'] 07:01:30 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 07:01:31 INFO - (blobuploader) - INFO - Open directory for files ... 07:01:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:01:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:01:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:01:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:01:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:01:31 INFO - (blobuploader) - INFO - Done attempting. 07:01:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:01:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:01:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:01:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:01:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:01:33 INFO - (blobuploader) - INFO - Done attempting. 07:01:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:01:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:01:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:01:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:01:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:01:36 INFO - (blobuploader) - INFO - Done attempting. 07:01:36 INFO - (blobuploader) - INFO - Iteration through files over. 07:01:36 INFO - Return code: 0 07:01:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:01:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:01:36 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f9cfa59dfb3376f6a344ba6bb6a78719e84aea2c1f64df4d21c24d7f6de88293618a6c877dee98756239a7d820a767e51e4881da800a909322fd4208cb228b3d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1d858615b81ebcfaa72cd5d1be9007a5667b51f869bf851efc63d9e3b69cdc208a74e775bd6cd829e73dc7c2838a22f3a08eedfb82f0cc4d6fea7a80788e10cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/372dbe137cb2422c8ee9d810bb819509eb708e0028ec084fcbffc7d9df879817df0ead40c5f10810ad0ffe5acbb772a51a6f466cf04dcee50b22345d496fee02"} 07:01:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:01:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:01:36 INFO - Contents: 07:01:36 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f9cfa59dfb3376f6a344ba6bb6a78719e84aea2c1f64df4d21c24d7f6de88293618a6c877dee98756239a7d820a767e51e4881da800a909322fd4208cb228b3d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1d858615b81ebcfaa72cd5d1be9007a5667b51f869bf851efc63d9e3b69cdc208a74e775bd6cd829e73dc7c2838a22f3a08eedfb82f0cc4d6fea7a80788e10cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/372dbe137cb2422c8ee9d810bb819509eb708e0028ec084fcbffc7d9df879817df0ead40c5f10810ad0ffe5acbb772a51a6f466cf04dcee50b22345d496fee02"} 07:01:36 INFO - Running post-run listener: copy_logs_to_upload_dir 07:01:36 INFO - Copying logs to upload dir... 07:01:36 INFO - mkdir: /builds/slave/test/build/upload/logs 07:01:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.949693 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-05 07:01:36.107468) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-05 07:01:36.111207) ========= 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.4tkeZlLbMT/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.d4UApVJ1f2/Listeners TMPDIR=/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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/f9cfa59dfb3376f6a344ba6bb6a78719e84aea2c1f64df4d21c24d7f6de88293618a6c877dee98756239a7d820a767e51e4881da800a909322fd4208cb228b3d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1d858615b81ebcfaa72cd5d1be9007a5667b51f869bf851efc63d9e3b69cdc208a74e775bd6cd829e73dc7c2838a22f3a08eedfb82f0cc4d6fea7a80788e10cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/372dbe137cb2422c8ee9d810bb819509eb708e0028ec084fcbffc7d9df879817df0ead40c5f10810ad0ffe5acbb772a51a6f466cf04dcee50b22345d496fee02"} build_url:https://queue.taskcluster.net/v1/task/TKx5qFPFSbmBFShewzG5gQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011542 build_url: 'https://queue.taskcluster.net/v1/task/TKx5qFPFSbmBFShewzG5gQ/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/f9cfa59dfb3376f6a344ba6bb6a78719e84aea2c1f64df4d21c24d7f6de88293618a6c877dee98756239a7d820a767e51e4881da800a909322fd4208cb228b3d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1d858615b81ebcfaa72cd5d1be9007a5667b51f869bf851efc63d9e3b69cdc208a74e775bd6cd829e73dc7c2838a22f3a08eedfb82f0cc4d6fea7a80788e10cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/372dbe137cb2422c8ee9d810bb819509eb708e0028ec084fcbffc7d9df879817df0ead40c5f10810ad0ffe5acbb772a51a6f466cf04dcee50b22345d496fee02"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-05 07:01:36.144710) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 07:01:36.145094) ========= 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.4tkeZlLbMT/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.d4UApVJ1f2/Listeners TMPDIR=/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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.005033 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 07:01:36.194025) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-05 07:01:36.194374) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-05 07:01:36.194695) ========= ========= Total master_lag: 2.02 =========