builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0273 starttime: 1476766063.01 results: success (0) buildid: 20161017194648 builduid: b7d8bcd7dd0f468393ec7c1565e4da5c revision: 37737974ba7fe5f8226570911ef885973ce354f2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-17 21:47:43.012171) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-17 21:47:43.012612) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-17 21:47:43.012889) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-17 21:47:43.041031) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 21:47:43.041330) ========= 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.gZLJ5wubgm/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.zm9NxzVG61/Listeners TMPDIR=/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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-17 21:47:43-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 62.8M=0s 2016-10-17 21:47:43 (62.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.625273 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 21:47:43.687998) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 21:47:43.688285) ========= 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.gZLJ5wubgm/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.zm9NxzVG61/Listeners TMPDIR=/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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.019065 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 21:47:43.736137) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-17 21:47:43.736485) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 37737974ba7fe5f8226570911ef885973ce354f2 --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 37737974ba7fe5f8226570911ef885973ce354f2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gZLJ5wubgm/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.zm9NxzVG61/Listeners TMPDIR=/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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-17 21:47:43,815 truncating revision to first 12 chars 2016-10-17 21:47:43,815 Setting DEBUG logging. 2016-10-17 21:47:43,815 attempt 1/10 2016-10-17 21:47:43,816 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/37737974ba7f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-17 21:47:44,609 unpacking tar archive at: autoland-37737974ba7f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.070928 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-17 21:47:44.833215) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-17 21:47:44.833844) ========= script_repo_revision: 37737974ba7fe5f8226570911ef885973ce354f2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-17 21:47:44.834283) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-17 21:47:44.834629) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-17 21:47:44.853082) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-17 21:47:44.853648) ========= /tools/buildbot/bin/python -u 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', '-u', '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.gZLJ5wubgm/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.zm9NxzVG61/Listeners TMPDIR=/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 21:47:45 INFO - MultiFileLogger online at 20161017 21:47:45 in /builds/slave/test 21:47:45 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 21:47:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:47:45 INFO - {'append_to_log': False, 21:47:45 INFO - 'base_work_dir': '/builds/slave/test', 21:47:45 INFO - 'blob_upload_branch': 'Autoland', 21:47:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:47:45 INFO - 'branch': 'Autoland', 21:47:45 INFO - 'buildbot_json_path': 'buildprops.json', 21:47:45 INFO - 'config_files': ('talos/mac_config.py',), 21:47:45 INFO - 'default_actions': ('clobber', 21:47:45 INFO - 'read-buildbot-config', 21:47:45 INFO - 'download-and-extract', 21:47:45 INFO - 'populate-webroot', 21:47:45 INFO - 'create-virtualenv', 21:47:45 INFO - 'install', 21:47:45 INFO - 'run-tests'), 21:47:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:47:45 INFO - 'download_minidump_stackwalk': True, 21:47:45 INFO - 'download_symbols': 'ondemand', 21:47:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:47:45 INFO - 'tooltool.py': '/tools/tooltool.py', 21:47:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:47:45 INFO - '/tools/misc-python/virtualenv.py')}, 21:47:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:47:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:47:45 INFO - 'installer_path': 'installer.exe', 21:47:45 INFO - 'log_level': 'info', 21:47:45 INFO - 'log_name': 'talos', 21:47:45 INFO - 'log_to_console': True, 21:47:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:47:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:47:45 INFO - 'opt_config_files': (), 21:47:45 INFO - 'pip_index': False, 21:47:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:47:45 INFO - 'cmd': ('bash', 21:47:45 INFO - '-c', 21:47:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:47:45 INFO - 'enabled': True, 21:47:45 INFO - 'halt_on_failure': False, 21:47:45 INFO - 'name': 'check_screen_resolution'},), 21:47:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:47:45 INFO - 'cmd': ('bash', 21:47:45 INFO - '-c', 21:47:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:47:45 INFO - 'enabled': True, 21:47:45 INFO - 'halt_on_failure': False, 21:47:45 INFO - 'name': 'check_screen_resolution'},), 21:47:45 INFO - 'run_cmd_checks_enabled': True, 21:47:45 INFO - 'sps_profile': False, 21:47:45 INFO - 'sps_profile_interval': 0, 21:47:45 INFO - 'suite': 'dromaeojs', 21:47:45 INFO - 'system_bits': '32', 21:47:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:47:45 INFO - 'title': 't-yosemite-r7-0273', 21:47:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:47:45 INFO - 'use_talos_json': True, 21:47:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:47:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:47:45 INFO - 'work_dir': 'build'} 21:47:45 INFO - [mozharness: 2016-10-18 04:47:45.054759Z] Running clobber step. 21:47:45 INFO - Running pre-action listener: _resource_record_pre_action 21:47:45 INFO - Running main action method: clobber 21:47:45 INFO - rmtree: /builds/slave/test/build 21:47:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:47:46 INFO - Running post-action listener: _resource_record_post_action 21:47:46 INFO - [mozharness: 2016-10-18 04:47:46.678599Z] Finished clobber step (success) 21:47:46 INFO - [mozharness: 2016-10-18 04:47:46.678705Z] Running read-buildbot-config step. 21:47:46 INFO - Running pre-action listener: _resource_record_pre_action 21:47:46 INFO - Running main action method: read_buildbot_config 21:47:46 INFO - Using buildbot properties: 21:47:46 INFO - { 21:47:46 INFO - "project": "", 21:47:46 INFO - "product": "firefox", 21:47:46 INFO - "script_repo_revision": "production", 21:47:46 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 21:47:46 INFO - "repository": "", 21:47:46 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 21:47:46 INFO - "buildid": "20161017194648", 21:47:46 INFO - "pgo_build": "False", 21:47:46 INFO - "basedir": "/builds/slave/test", 21:47:46 INFO - "buildnumber": 384, 21:47:46 INFO - "slavename": "t-yosemite-r7-0273", 21:47:46 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 21:47:46 INFO - "platform": "yosemite_r7", 21:47:46 INFO - "scheduler": "tests-autoland-macosx64-talos", 21:47:46 INFO - "branch": "autoland", 21:47:46 INFO - "repo_path": "integration/autoland", 21:47:46 INFO - "revision": "37737974ba7fe5f8226570911ef885973ce354f2", 21:47:46 INFO - "stage_platform": "macosx64", 21:47:46 INFO - "builduid": "b7d8bcd7dd0f468393ec7c1565e4da5c", 21:47:46 INFO - "slavebuilddir": "test" 21:47:46 INFO - } 21:47:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/YQr02MRyTseuoeZopCh-Rw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 21:47:46 INFO - Running post-action listener: _resource_record_post_action 21:47:46 INFO - [mozharness: 2016-10-18 04:47:46.682244Z] Finished read-buildbot-config step (success) 21:47:46 INFO - [mozharness: 2016-10-18 04:47:46.682393Z] Running download-and-extract step. 21:47:46 INFO - Running pre-action listener: _resource_record_pre_action 21:47:46 INFO - Running main action method: download_and_extract 21:47:46 INFO - mkdir: /builds/slave/test/build/tests 21:47:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:47:46 INFO - trying https://queue.taskcluster.net/v1/task/YQr02MRyTseuoeZopCh-Rw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 21:47:46 INFO - Downloading https://queue.taskcluster.net/v1/task/YQr02MRyTseuoeZopCh-Rw/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 21:47:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YQr02MRyTseuoeZopCh-Rw/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 21:47:48 INFO - Downloaded 1199 bytes. 21:47:48 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 21:47:48 INFO - Using the following test package requirements: 21:47:48 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 21:47:48 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:47:48 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 21:47:48 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:47:48 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 21:47:48 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:47:48 INFO - u'jsshell-mac.zip'], 21:47:48 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:47:48 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 21:47:48 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 21:47:48 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:47:48 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 21:47:48 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:47:48 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 21:47:48 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:47:48 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 21:47:48 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:47:48 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 21:47:48 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:47:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YQr02MRyTseuoeZopCh-Rw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 21:47:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YQr02MRyTseuoeZopCh-Rw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:47:48 INFO - Fetch https://queue.taskcluster.net/v1/task/YQr02MRyTseuoeZopCh-Rw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 21:47:48 INFO - Http code: 200 21:47:48 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 21:47:48 INFO - x-amz-cf-id: MxDmSdDv3L_DcYNhGfSAdtyDgL0OrMxxp-sX-aTAFAuMhDgqoudGNA== 21:47:48 INFO - x-amz-version-id: bhO2UKQ_UKXoN0SPwVdBo2YI1PSVAVbY 21:47:48 INFO - Expected file size: 16483825 21:47:48 INFO - Obtained file size: 16483825 21:47:55 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 21:47:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YQr02MRyTseuoeZopCh-Rw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 21:47:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YQr02MRyTseuoeZopCh-Rw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:47:55 INFO - Fetch https://queue.taskcluster.net/v1/task/YQr02MRyTseuoeZopCh-Rw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 21:47:55 INFO - Http code: 200 21:47:55 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 21:47:55 INFO - x-amz-cf-id: S08jD7H87O9ThfiRLw6pgrEwXTKhLQyLMWBdNtj1NaDEbgIq8BXlVg== 21:47:55 INFO - x-amz-version-id: bhO2UKQ_UKXoN0SPwVdBo2YI1PSVAVbY 21:47:56 INFO - Expected file size: 16483825 21:47:56 INFO - Obtained file size: 16483825 21:48:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YQr02MRyTseuoeZopCh-Rw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 21:48:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YQr02MRyTseuoeZopCh-Rw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 21:48:02 INFO - Fetch https://queue.taskcluster.net/v1/task/YQr02MRyTseuoeZopCh-Rw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 21:48:03 INFO - Http code: 200 21:48:03 INFO - via: 1.1 9baadab4e4d95afe7af7bcba342b29c6.cloudfront.net (CloudFront) 21:48:03 INFO - x-amz-cf-id: IYnhAqloh_Boqoovxex87mJOt9uq5iAt4mPZgvu8EbsD505n8yWmZw== 21:48:03 INFO - x-amz-version-id: F_VTNCsch31dSwrg8zoehzdMQAAcGeB5 21:48:04 INFO - Expected file size: 14409028 21:48:04 INFO - Obtained file size: 14409028 21:48: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')]} 21:48:04 INFO - trying https://queue.taskcluster.net/v1/task/YQr02MRyTseuoeZopCh-Rw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 21:48:04 INFO - Downloading https://queue.taskcluster.net/v1/task/YQr02MRyTseuoeZopCh-Rw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 21:48:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YQr02MRyTseuoeZopCh-Rw/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 21:48:07 INFO - Downloaded 111637927 bytes. 21:48:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YQr02MRyTseuoeZopCh-Rw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 21:48:07 INFO - mkdir: /builds/slave/test/properties 21:48:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:48:07 INFO - Writing to file /builds/slave/test/properties/build_url 21:48:07 INFO - Contents: 21:48:07 INFO - build_url:https://queue.taskcluster.net/v1/task/YQr02MRyTseuoeZopCh-Rw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 21:48:07 INFO - Running post-action listener: _resource_record_post_action 21:48:07 INFO - Running post-action listener: set_extra_try_arguments 21:48:07 INFO - [mozharness: 2016-10-18 04:48:07.940621Z] Finished download-and-extract step (success) 21:48:07 INFO - [mozharness: 2016-10-18 04:48:07.940741Z] Running populate-webroot step. 21:48:07 INFO - Running pre-action listener: _resource_record_pre_action 21:48:07 INFO - Running main action method: populate_webroot 21:48:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:48:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:48:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:48:07 INFO - '--tppagecycles', 21:48:07 INFO - '7'], 21:48:07 INFO - 'tests': ['tsvgm']}}, 21:48:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:48:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:48:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:48:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:48:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:48:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:48:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:48:07 INFO - 'talos_options': ['--disable-e10s'], 21:48:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:48:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:48:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:48:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:48:07 INFO - 'talos_options': ['--disable-e10s'], 21:48:07 INFO - 'tests': ['damp', 'tps']}, 21:48:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:48:07 INFO - 'tests': ['damp', 'tps']}, 21:48:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:48:07 INFO - 'tests': ['dromaeo_dom']}, 21:48:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:48:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:48:07 INFO - 'tests': ['basic_compositor_video']}, 21:48:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:48:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:48:07 INFO - 'tests': ['a11yr', 21:48:07 INFO - 'ts_paint', 21:48:07 INFO - 'tpaint', 21:48:07 INFO - 'sessionrestore', 21:48:07 INFO - 'sessionrestore_no_auto_restore', 21:48:07 INFO - 'tabpaint']}, 21:48:07 INFO - 'other-e10s': {'tests': ['a11yr', 21:48:07 INFO - 'ts_paint', 21:48:07 INFO - 'tpaint', 21:48:07 INFO - 'sessionrestore', 21:48:07 INFO - 'sessionrestore_no_auto_restore', 21:48:07 INFO - 'tabpaint']}, 21:48:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:48:07 INFO - 'ts_paint', 21:48:07 INFO - 'tpaint', 21:48:07 INFO - 'sessionrestore', 21:48:07 INFO - 'sessionrestore_no_auto_restore', 21:48:07 INFO - 'tabpaint']}, 21:48:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:48:07 INFO - 'ts_paint', 21:48:07 INFO - 'tpaint', 21:48:07 INFO - 'sessionrestore', 21:48:07 INFO - 'sessionrestore_no_auto_restore', 21:48:07 INFO - 'tabpaint']}, 21:48:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:48:07 INFO - 'tests': ['a11yr', 21:48:07 INFO - 'ts_paint', 21:48:07 INFO - 'tpaint', 21:48:07 INFO - 'sessionrestore', 21:48:07 INFO - 'sessionrestore_no_auto_restore', 21:48:07 INFO - 'tabpaint']}, 21:48:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:48:07 INFO - 'tests': ['a11yr', 21:48:07 INFO - 'ts_paint', 21:48:07 INFO - 'tpaint', 21:48:07 INFO - 'sessionrestore', 21:48:07 INFO - 'sessionrestore_no_auto_restore', 21:48:07 INFO - 'tabpaint']}, 21:48:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:48:07 INFO - 'tests': ['tsvgx', 21:48:07 INFO - 'tsvgr_opacity', 21:48:07 INFO - 'tart', 21:48:07 INFO - 'tscrollx', 21:48:07 INFO - 'cart']}, 21:48:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:48:07 INFO - 'tsvgr_opacity', 21:48:07 INFO - 'tart', 21:48:07 INFO - 'tscrollx', 21:48:07 INFO - 'cart']}, 21:48:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:48:07 INFO - 'talos_options': ['--disable-e10s'], 21:48:07 INFO - 'tests': ['tp5o']}, 21:48:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:48:07 INFO - 'tests': ['tp5o']}, 21:48:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:48:07 INFO - 'talos_options': ['--disable-e10s', 21:48:07 INFO - '--xperf_path', 21:48:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:48:07 INFO - 'tests': ['tp5n']}, 21:48:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:48:07 INFO - 'talos_options': ['--xperf_path', 21:48:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:48:07 INFO - 'tests': ['tp5n']}}, 21:48:07 INFO - 'talos.zip': {'path': '', 21:48:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:48:07 INFO - Running post-action listener: _resource_record_post_action 21:48:07 INFO - [mozharness: 2016-10-18 04:48:07.959604Z] Finished populate-webroot step (success) 21:48:07 INFO - [mozharness: 2016-10-18 04:48:07.959739Z] Running create-virtualenv step. 21:48:07 INFO - Running pre-action listener: _resource_record_pre_action 21:48:07 INFO - Running main action method: create_virtualenv 21:48:07 INFO - Creating virtualenv /builds/slave/test/build/venv 21:48:07 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 21:48:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:48:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:48:08 INFO - Using real prefix '/tools/python27' 21:48:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:48:08 INFO - Installing distribute.............................................................................................................................................................................................done. 21:48:11 INFO - Installing pip.................done. 21:48:11 INFO - Return code: 0 21:48:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:48:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:48:11 INFO - Reading from file tmpfile_stdout 21:48:11 INFO - Output received: 21:48:11 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) 21:48:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:48:11 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')]} 21:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:11 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')]} 21:48:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:11 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 0x10ae3e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adb4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1e85a8690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac52d30>, '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 0x10aa78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac4ab20>, '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.gZLJ5wubgm/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.zm9NxzVG61/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 21:48:11 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 21:48:11 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 21:48:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gZLJ5wubgm/Render', 21:48:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:11 INFO - 'HOME': '/Users/cltbld', 21:48:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:11 INFO - 'LOGNAME': 'cltbld', 21:48:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:11 INFO - 'MOZ_NO_REMOTE': '1', 21:48:11 INFO - 'NO_EM_RESTART': '1', 21:48:11 INFO - 'PAGER': '/bin/cat', 21:48:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:11 INFO - 'PWD': '/builds/slave/test', 21:48:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:11 INFO - 'SHELL': '/bin/bash', 21:48:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zm9NxzVG61/Listeners', 21:48:11 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 21:48:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:11 INFO - 'USER': 'cltbld', 21:48:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:11 INFO - 'XPC_FLAGS': '0x0', 21:48:11 INFO - 'XPC_SERVICE_NAME': '0', 21:48:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:12 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 21:48:12 INFO - Cleaning up... 21:48:12 INFO - Return code: 0 21:48:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:48:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:12 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 0x10ae3e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adb4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1e85a8690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac52d30>, '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 0x10aa78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac4ab20>, '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.gZLJ5wubgm/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.zm9NxzVG61/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 21:48:12 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 21:48:12 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 21:48:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gZLJ5wubgm/Render', 21:48:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:12 INFO - 'HOME': '/Users/cltbld', 21:48:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:12 INFO - 'LOGNAME': 'cltbld', 21:48:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:12 INFO - 'MOZ_NO_REMOTE': '1', 21:48:12 INFO - 'NO_EM_RESTART': '1', 21:48:12 INFO - 'PAGER': '/bin/cat', 21:48:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:12 INFO - 'PWD': '/builds/slave/test', 21:48:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:12 INFO - 'SHELL': '/bin/bash', 21:48:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zm9NxzVG61/Listeners', 21:48:12 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 21:48:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:12 INFO - 'USER': 'cltbld', 21:48:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:12 INFO - 'XPC_FLAGS': '0x0', 21:48:12 INFO - 'XPC_SERVICE_NAME': '0', 21:48:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:12 INFO - Downloading/unpacking psutil>=3.1.1 21:48:12 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 21:48:12 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 21:48:12 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 21:48:12 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 21:48:12 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 21:48:12 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 21:48:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:48:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:48:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:48:16 INFO - Installing collected packages: psutil 21:48:16 INFO - Running setup.py install for psutil 21:48:16 INFO - building 'psutil._psutil_osx' extension 21:48:16 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 21:48:16 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 21:48:16 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 21:48:16 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 21:48:16 INFO - building 'psutil._psutil_posix' extension 21:48:16 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 21:48:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:48:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:48:16 INFO - ^ 21:48:16 INFO - 1 warning generated. 21:48:16 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 21:48:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:48:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:48:17 INFO - Successfully installed psutil 21:48:17 INFO - Cleaning up... 21:48:17 INFO - Return code: 0 21:48:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:48:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:17 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 0x10ae3e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adb4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1e85a8690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac52d30>, '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 0x10aa78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac4ab20>, '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.gZLJ5wubgm/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.zm9NxzVG61/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 21:48:17 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 21:48:17 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 21:48:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gZLJ5wubgm/Render', 21:48:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:17 INFO - 'HOME': '/Users/cltbld', 21:48:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:17 INFO - 'LOGNAME': 'cltbld', 21:48:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:17 INFO - 'MOZ_NO_REMOTE': '1', 21:48:17 INFO - 'NO_EM_RESTART': '1', 21:48:17 INFO - 'PAGER': '/bin/cat', 21:48:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:17 INFO - 'PWD': '/builds/slave/test', 21:48:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:17 INFO - 'SHELL': '/bin/bash', 21:48:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zm9NxzVG61/Listeners', 21:48:17 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 21:48:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:17 INFO - 'USER': 'cltbld', 21:48:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:17 INFO - 'XPC_FLAGS': '0x0', 21:48:17 INFO - 'XPC_SERVICE_NAME': '0', 21:48:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:48:17 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 21:48:17 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 21:48:17 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 21:48:17 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 21:48:17 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 21:48:17 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 21:48:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:48:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:48:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:48:19 INFO - Installing collected packages: mozsystemmonitor 21:48:19 INFO - Running setup.py install for mozsystemmonitor 21:48:20 INFO - Successfully installed mozsystemmonitor 21:48:20 INFO - Cleaning up... 21:48:20 INFO - Return code: 0 21:48:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:48:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:20 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 0x10ae3e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adb4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1e85a8690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac52d30>, '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 0x10aa78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac4ab20>, '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.gZLJ5wubgm/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.zm9NxzVG61/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 21:48:20 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 21:48:20 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 21:48:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gZLJ5wubgm/Render', 21:48:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:20 INFO - 'HOME': '/Users/cltbld', 21:48:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:20 INFO - 'LOGNAME': 'cltbld', 21:48:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:20 INFO - 'MOZ_NO_REMOTE': '1', 21:48:20 INFO - 'NO_EM_RESTART': '1', 21:48:20 INFO - 'PAGER': '/bin/cat', 21:48:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:20 INFO - 'PWD': '/builds/slave/test', 21:48:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:20 INFO - 'SHELL': '/bin/bash', 21:48:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zm9NxzVG61/Listeners', 21:48:20 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 21:48:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:20 INFO - 'USER': 'cltbld', 21:48:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:20 INFO - 'XPC_FLAGS': '0x0', 21:48:20 INFO - 'XPC_SERVICE_NAME': '0', 21:48:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:20 INFO - Downloading/unpacking jsonschema==2.5.1 21:48:20 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 21:48:20 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 21:48:20 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 21:48:20 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 21:48:20 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 21:48:20 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 21:48:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:48:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:48:22 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 21:48:22 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 21:48:22 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 21:48:22 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 21:48:22 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 21:48:22 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 21:48:23 INFO - Downloading functools32-3.2.3-2.tar.gz 21:48:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:48:23 INFO - warning: no files found matching '*.txt' 21:48:23 INFO - no previously-included directories found matching 'build' 21:48:23 INFO - no previously-included directories found matching 'dist' 21:48:23 INFO - no previously-included directories found matching '.git*' 21:48:23 INFO - Installing collected packages: jsonschema, functools32 21:48:23 INFO - Running setup.py install for functools32 21:48:23 INFO - warning: no files found matching '*.txt' 21:48:23 INFO - no previously-included directories found matching 'build' 21:48:23 INFO - no previously-included directories found matching 'dist' 21:48:23 INFO - no previously-included directories found matching '.git*' 21:48:23 INFO - Successfully installed jsonschema functools32 21:48:23 INFO - Cleaning up... 21:48:23 INFO - Return code: 0 21:48:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:48:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ae3e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adb4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1e85a8690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac52d30>, '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 0x10aa78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac4ab20>, '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.gZLJ5wubgm/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.zm9NxzVG61/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 21:48:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 21:48:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 21:48:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gZLJ5wubgm/Render', 21:48:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:23 INFO - 'HOME': '/Users/cltbld', 21:48:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:23 INFO - 'LOGNAME': 'cltbld', 21:48:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:23 INFO - 'MOZ_NO_REMOTE': '1', 21:48:23 INFO - 'NO_EM_RESTART': '1', 21:48:23 INFO - 'PAGER': '/bin/cat', 21:48:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:23 INFO - 'PWD': '/builds/slave/test', 21:48:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:23 INFO - 'SHELL': '/bin/bash', 21:48:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zm9NxzVG61/Listeners', 21:48:23 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 21:48:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:23 INFO - 'USER': 'cltbld', 21:48:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:23 INFO - 'XPC_FLAGS': '0x0', 21:48:23 INFO - 'XPC_SERVICE_NAME': '0', 21:48:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:48:24 INFO - Cleaning up... 21:48:24 INFO - Return code: 0 21:48:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:48:24 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')]} 21:48:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:24 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')]} 21:48:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:24 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 0x10ae3e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adb4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1e85a8690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac52d30>, '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 0x10aa78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac4ab20>, '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.gZLJ5wubgm/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.zm9NxzVG61/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 21:48:24 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 21:48:24 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 21:48:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gZLJ5wubgm/Render', 21:48:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:24 INFO - 'HOME': '/Users/cltbld', 21:48:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:24 INFO - 'LOGNAME': 'cltbld', 21:48:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:24 INFO - 'MOZ_NO_REMOTE': '1', 21:48:24 INFO - 'NO_EM_RESTART': '1', 21:48:24 INFO - 'PAGER': '/bin/cat', 21:48:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:24 INFO - 'PWD': '/builds/slave/test', 21:48:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:24 INFO - 'SHELL': '/bin/bash', 21:48:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zm9NxzVG61/Listeners', 21:48:24 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 21:48:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:24 INFO - 'USER': 'cltbld', 21:48:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:24 INFO - 'XPC_FLAGS': '0x0', 21:48:24 INFO - 'XPC_SERVICE_NAME': '0', 21:48:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:24 INFO - Downloading/unpacking blobuploader==1.2.4 21:48:24 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 21:48:24 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 21:48:24 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 21:48:24 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 21:48:24 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 21:48:24 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 21:48:26 INFO - Downloading blobuploader-1.2.4.tar.gz 21:48:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:48:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:48:26 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 21:48:26 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 21:48:26 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 21:48:26 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 21:48:26 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 21:48:26 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 21:48:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:48:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:48:27 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 21:48:27 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 21:48:27 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 21:48:27 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 21:48:27 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 21:48:27 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 21:48:27 INFO - Downloading docopt-0.6.1.tar.gz 21:48:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:48:28 INFO - Installing collected packages: blobuploader, requests, docopt 21:48:28 INFO - Running setup.py install for blobuploader 21:48:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:48:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:48:28 INFO - Running setup.py install for requests 21:48:28 INFO - Running setup.py install for docopt 21:48:28 INFO - Successfully installed blobuploader requests docopt 21:48:28 INFO - Cleaning up... 21:48:28 INFO - Return code: 0 21:48:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:48:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:28 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 0x10ae3e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adb4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1e85a8690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac52d30>, '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 0x10aa78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac4ab20>, '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.gZLJ5wubgm/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.zm9NxzVG61/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 21:48:28 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 21:48:28 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 21:48:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gZLJ5wubgm/Render', 21:48:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:29 INFO - 'HOME': '/Users/cltbld', 21:48:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:29 INFO - 'LOGNAME': 'cltbld', 21:48:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:29 INFO - 'MOZ_NO_REMOTE': '1', 21:48:29 INFO - 'NO_EM_RESTART': '1', 21:48:29 INFO - 'PAGER': '/bin/cat', 21:48:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:29 INFO - 'PWD': '/builds/slave/test', 21:48:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:29 INFO - 'SHELL': '/bin/bash', 21:48:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zm9NxzVG61/Listeners', 21:48:29 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 21:48:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:29 INFO - 'USER': 'cltbld', 21:48:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:29 INFO - 'XPC_FLAGS': '0x0', 21:48:29 INFO - 'XPC_SERVICE_NAME': '0', 21:48:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:48:29 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-Y9e6Dh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:48:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:48:29 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-rAz0Cn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:48:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:48:29 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-lyRn40-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:48:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:48:29 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-AEygUY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:48:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:48:29 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-S4fNn5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:48:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:48:29 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-gpcYXo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:48:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:48:29 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-0pChu5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:48:30 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-I87aqJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:48:30 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-N_Ppzs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:48:30 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-9Y1m0W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:48:30 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-nuHbEk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:48:30 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-nTd95x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:48:30 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-51trAn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:48:30 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-7dJM5M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:48:30 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-8hBhB6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:48:30 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-T7Woko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:48:30 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-6LcYNU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:48:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:48:31 INFO - Running setup.py install for manifestparser 21:48:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:48:31 INFO - Running setup.py install for mozcrash 21:48:31 INFO - Running setup.py install for mozdebug 21:48:31 INFO - Running setup.py install for mozdevice 21:48:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:48:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:48:31 INFO - Running setup.py install for mozfile 21:48:31 INFO - Running setup.py install for mozhttpd 21:48:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:48:31 INFO - Running setup.py install for mozinfo 21:48:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:48:32 INFO - Running setup.py install for mozInstall 21:48:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:48:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:48:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:48:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:48:32 INFO - Running setup.py install for mozleak 21:48:32 INFO - Running setup.py install for mozlog 21:48:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:48:32 INFO - Running setup.py install for moznetwork 21:48:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:48:32 INFO - Running setup.py install for mozprocess 21:48:32 INFO - Running setup.py install for mozprofile 21:48:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:48:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:48:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:48:33 INFO - Running setup.py install for mozrunner 21:48:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:48:33 INFO - Running setup.py install for mozscreenshot 21:48:33 INFO - Running setup.py install for moztest 21:48:33 INFO - Running setup.py install for mozversion 21:48:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:48:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:48:33 INFO - Cleaning up... 21:48:33 INFO - Return code: 0 21:48:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:48:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:33 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 0x10ae3e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adb4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1e85a8690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac52d30>, '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 0x10aa78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac4ab20>, '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.gZLJ5wubgm/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.zm9NxzVG61/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 21:48:33 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 21:48:33 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 21:48:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gZLJ5wubgm/Render', 21:48:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:33 INFO - 'HOME': '/Users/cltbld', 21:48:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:33 INFO - 'LOGNAME': 'cltbld', 21:48:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:33 INFO - 'MOZ_NO_REMOTE': '1', 21:48:33 INFO - 'NO_EM_RESTART': '1', 21:48:33 INFO - 'PAGER': '/bin/cat', 21:48:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:33 INFO - 'PWD': '/builds/slave/test', 21:48:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:33 INFO - 'SHELL': '/bin/bash', 21:48:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zm9NxzVG61/Listeners', 21:48:33 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 21:48:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:33 INFO - 'USER': 'cltbld', 21:48:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:33 INFO - 'XPC_FLAGS': '0x0', 21:48:33 INFO - 'XPC_SERVICE_NAME': '0', 21:48:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:48:33 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-PcWA0C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:48:34 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)) 21:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:48:34 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-CO9z2r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:48:34 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)) 21:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:48:34 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-E4ertA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:48:34 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)) 21:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:48:34 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-ayzNP1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:48:34 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)) 21:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:48:34 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-30NEQf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:48:34 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)) 21:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:48:34 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-oJxAAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:48:34 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)) 21:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:48:34 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-KZBeeY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:48:34 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)) 21:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:48:34 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-wlNEZq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:48:34 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)) 21:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:48:34 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-Zi3w1d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:48:34 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)) 21:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:48:34 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-8cvwP_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:48:35 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)) 21:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:48:35 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-9C8gvF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:48:35 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)) 21:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:48:35 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-oTikeo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:48:35 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)) 21:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:48:35 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-x3ix5M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:48:35 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)) 21:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:48:35 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-rWuB6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:48:35 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)) 21:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:48:35 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-XoaqeX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:48:35 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)) 21:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:48:35 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-9SX6B1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:48:35 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)) 21:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:48:35 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-BEyaKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:48:35 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)) 21:48:35 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)) 21:48:35 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)) 21:48:35 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)) 21:48:35 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)) 21:48:35 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)) 21:48:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:48:35 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 21:48:35 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 21:48:35 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 21:48:35 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 21:48:35 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 21:48:35 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 21:48:38 INFO - Downloading blessings-1.6.tar.gz 21:48:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:48:38 INFO - Installing collected packages: blessings 21:48:38 INFO - Running setup.py install for blessings 21:48:38 INFO - Successfully installed blessings 21:48:38 INFO - Cleaning up... 21:48:38 INFO - Return code: 0 21:48:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:48:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:48:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:48:39 INFO - Reading from file tmpfile_stdout 21:48:39 INFO - Current package versions: 21:48:39 INFO - blessings == 1.6 21:48:39 INFO - blobuploader == 1.2.4 21:48:39 INFO - docopt == 0.6.1 21:48:39 INFO - functools32 == 3.2.3-2 21:48:39 INFO - jsonschema == 2.5.1 21:48:39 INFO - manifestparser == 1.1 21:48:39 INFO - mozInstall == 1.12 21:48:39 INFO - mozcrash == 0.17 21:48:39 INFO - mozdebug == 0.1 21:48:39 INFO - mozdevice == 0.48 21:48:39 INFO - mozfile == 1.2 21:48:39 INFO - mozhttpd == 0.7 21:48:39 INFO - mozinfo == 0.9 21:48:39 INFO - mozleak == 0.1 21:48:39 INFO - mozlog == 3.4 21:48:39 INFO - moznetwork == 0.27 21:48:39 INFO - mozprocess == 0.23 21:48:39 INFO - mozprofile == 0.28 21:48:39 INFO - mozrunner == 6.12 21:48:39 INFO - mozscreenshot == 0.1 21:48:39 INFO - mozsystemmonitor == 0.3 21:48:39 INFO - moztest == 0.7 21:48:39 INFO - mozversion == 1.4 21:48:39 INFO - psutil == 3.1.1 21:48:39 INFO - requests == 1.2.3 21:48:39 INFO - wsgiref == 0.1.2 21:48:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:48: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')]} 21:48:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48: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')]} 21:48:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:39 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 0x10ae3e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adb4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1e85a8690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac52d30>, '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 0x10aa78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac4ab20>, '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.gZLJ5wubgm/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.zm9NxzVG61/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 21:48:39 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 21:48:39 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 21:48:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gZLJ5wubgm/Render', 21:48:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:39 INFO - 'HOME': '/Users/cltbld', 21:48:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:39 INFO - 'LOGNAME': 'cltbld', 21:48:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:39 INFO - 'MOZ_NO_REMOTE': '1', 21:48:39 INFO - 'NO_EM_RESTART': '1', 21:48:39 INFO - 'PAGER': '/bin/cat', 21:48:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:39 INFO - 'PWD': '/builds/slave/test', 21:48:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:39 INFO - 'SHELL': '/bin/bash', 21:48:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zm9NxzVG61/Listeners', 21:48:39 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 21:48:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:39 INFO - 'USER': 'cltbld', 21:48:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:39 INFO - 'XPC_FLAGS': '0x0', 21:48:39 INFO - 'XPC_SERVICE_NAME': '0', 21:48:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:39 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)) 21:48:39 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)) 21:48:39 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)) 21:48:39 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)) 21:48:39 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)) 21:48:39 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)) 21:48:39 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)) 21:48:39 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)) 21:48:39 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)) 21:48:39 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)) 21:48:39 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)) 21:48:39 INFO - Cleaning up... 21:48:39 INFO - Return code: 0 21:48:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:48: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')]} 21:48:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48: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')]} 21:48:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ae3e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adb4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1e85a8690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac52d30>, '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 0x10aa78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac4ab20>, '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.gZLJ5wubgm/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.zm9NxzVG61/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 21:48:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 21:48:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:48:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gZLJ5wubgm/Render', 21:48:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:39 INFO - 'HOME': '/Users/cltbld', 21:48:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:39 INFO - 'LOGNAME': 'cltbld', 21:48:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:39 INFO - 'MOZ_NO_REMOTE': '1', 21:48:39 INFO - 'NO_EM_RESTART': '1', 21:48:39 INFO - 'PAGER': '/bin/cat', 21:48:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:39 INFO - 'PWD': '/builds/slave/test', 21:48:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:39 INFO - 'SHELL': '/bin/bash', 21:48:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zm9NxzVG61/Listeners', 21:48:39 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 21:48:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:39 INFO - 'USER': 'cltbld', 21:48:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:39 INFO - 'XPC_FLAGS': '0x0', 21:48:39 INFO - 'XPC_SERVICE_NAME': '0', 21:48:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:48:39 INFO - Cleaning up... 21:48:39 INFO - Return code: 0 21:48:39 INFO - Running post-action listener: _resource_record_post_action 21:48:39 INFO - Running post-action listener: _start_resource_monitoring 21:48:39 INFO - Starting resource monitoring. 21:48:39 INFO - [mozharness: 2016-10-18 04:48:39.614132Z] Finished create-virtualenv step (success) 21:48:39 INFO - [mozharness: 2016-10-18 04:48:39.615125Z] Running install step. 21:48:39 INFO - Running pre-action listener: _resource_record_pre_action 21:48:39 INFO - Running main action method: install 21:48:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:48:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:48:39 INFO - Reading from file tmpfile_stdout 21:48:39 INFO - Detecting whether we're running mozinstall >=1.0... 21:48:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:48:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:48:39 INFO - Reading from file tmpfile_stdout 21:48:39 INFO - Output received: 21:48:39 INFO - Usage: mozinstall [options] installer 21:48:39 INFO - Options: 21:48:39 INFO - -h, --help show this help message and exit 21:48:39 INFO - -d DEST, --destination=DEST 21:48:39 INFO - Directory to install application into. [default: 21:48:39 INFO - "/builds/slave/test"] 21:48:39 INFO - --app=APP Application being installed. [default: firefox] 21:48:39 INFO - mkdir: /builds/slave/test/build/application 21:48:39 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'] 21:48:39 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 21:49:14 INFO - Reading from file tmpfile_stdout 21:49:14 INFO - Output received: 21:49:14 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:49:14 INFO - Running post-action listener: _resource_record_post_action 21:49:14 INFO - [mozharness: 2016-10-18 04:49:14.286222Z] Finished install step (success) 21:49:14 INFO - [mozharness: 2016-10-18 04:49:14.286353Z] Running run-tests step. 21:49:14 INFO - Running pre-action listener: _resource_record_pre_action 21:49:14 INFO - Running main action method: run_tests 21:49:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:49:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:49:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:49:14 INFO - 2016-10-17 21:49:14.300 screenresolution[1942:15269] starting screenresolution argv=screenresolution get 21:49:14 INFO - 2016-10-17 21:49:14.303 screenresolution[1942:15269] Display 0: 1600x1200x32@60 21:49:14 INFO - 2016-10-17 21:49:14.309 screenresolution[1943:15271] starting screenresolution argv=screenresolution list 21:49:14 INFO - Available Modes on Display 0 21:49:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:49:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:49:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:49:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:49:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:49:14 INFO - Intel Iris: 21:49:14 INFO - Chipset Model: Intel Iris 21:49:14 INFO - Type: GPU 21:49:14 INFO - Bus: Built-In 21:49:14 INFO - VRAM (Dynamic, Max): 1536 MB 21:49:14 INFO - Vendor: Intel (0x8086) 21:49:14 INFO - Device ID: 0x0a2e 21:49:14 INFO - Revision ID: 0x0009 21:49:14 INFO - Displays: 21:49:14 INFO - Display: 21:49:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:49:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:49:14 INFO - Display Serial Number: 1600x1200 60 21:49:14 INFO - Main Display: Yes 21:49:14 INFO - Mirror: Off 21:49:14 INFO - Online: Yes 21:49:14 INFO - Rotation: Supported 21:49:14 INFO - Return code: 0 21:49:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:49:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:49:14 INFO - Python 2.7.3 21:49:14 INFO - Return code: 0 21:49:14 INFO - grabbing minidump binary from tooltool 21:49:14 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')]} 21:49:14 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 0x10ac52d30>, '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 0x10aa78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac4ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:49:14 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 21:49:14 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 21:49:14 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 21:49:14 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:49:14 INFO - Return code: 0 21:49:14 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:49:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:49:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:49:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:49:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:49:14 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/YQr02MRyTseuoeZopCh-Rw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0273', '--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 21:49:14 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/YQr02MRyTseuoeZopCh-Rw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0273 --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 21:49:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gZLJ5wubgm/Render', 21:49:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:49:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:49:14 INFO - 'HOME': '/Users/cltbld', 21:49:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:49:14 INFO - 'LOGNAME': 'cltbld', 21:49:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:49:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:49:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:49:14 INFO - 'MOZ_NO_REMOTE': '1', 21:49:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:49:14 INFO - 'NO_EM_RESTART': '1', 21:49:14 INFO - 'PAGER': '/bin/cat', 21:49:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:49:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:49:14 INFO - 'PWD': '/builds/slave/test', 21:49:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:49:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:49:14 INFO - 'SHELL': '/bin/bash', 21:49:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zm9NxzVG61/Listeners', 21:49:14 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 21:49:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:49:14 INFO - 'USER': 'cltbld', 21:49:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:49:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:49:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:49:14 INFO - 'XPC_FLAGS': '0x0', 21:49:14 INFO - 'XPC_SERVICE_NAME': '0', 21:49:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:49:14 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/YQr02MRyTseuoeZopCh-Rw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0273', '--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 21:49:14 INFO - mozversion application_buildid: 20161017194648 21:49:14 INFO - mozversion application_changeset: 37737974ba7fe5f8226570911ef885973ce354f2 21:49:14 INFO - mozversion application_display_name: Nightly 21:49:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:49:14 INFO - mozversion application_name: Firefox 21:49:14 INFO - mozversion application_remotingname: firefox 21:49:14 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 21:49:14 INFO - mozversion application_vendor: Mozilla 21:49:14 INFO - mozversion application_version: 52.0a1 21:49:14 INFO - mozversion platform_buildid: 20161017194648 21:49:14 INFO - mozversion platform_changeset: 37737974ba7fe5f8226570911ef885973ce354f2 21:49:14 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 21:49:14 INFO - mozversion platform_version: 52.0a1 21:49:14 INFO - using testdate: 1476766154 21:49:14 INFO - actual date: 1476766154 21:49:14 INFO - starting webserver on 'localhost:51502' 21:49:14 INFO - SUITE-START | Running 2 tests 21:49:14 INFO - TEST-START | dromaeo_css 21:49:14 INFO - operating with platform_type : mac_ 21:49:14 INFO - Initialising browser for dromaeo_css test... 21:49:14 INFO - TEST-INFO | started process 1953 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/tmpBq0Oym/profile http://localhost:51502/getInfo.html) 21:49:17 INFO - PROCESS | 1953 | __metrics Screen width/height:1600/1200 21:49:17 INFO - PROCESS | 1953 | colorDepth:24 21:49:17 INFO - PROCESS | 1953 | Browser inner width/height: 1024/689 21:49:17 INFO - PROCESS | 1953 | __metrics 21:49:17 INFO - PROCESS | 1953 | 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" 21:49:17 INFO - PROCESS | 1953 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:49:18 INFO - TEST-INFO | 1953: exit 0 21:49:18 INFO - Browser initialized. 21:49:18 INFO - Running cycle 1/1 for dromaeo_css test... 21:49:18 INFO - TEST-INFO | started process 1955 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/tmpBq0Oym/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:49:30 INFO - PROCESS | 1955 | RSS: Main: 231841792 21:49:30 INFO - PROCESS | 1955 | 21:50:21 INFO - PROCESS | 1955 | 1476766221644 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] 21:52:34 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51502/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51502/tests/dromaeo/cssquery-ext.html) 21:52:36 INFO - PROCESS | 1955 | RSS: Main: 413573120 21:52:36 INFO - PROCESS | 1955 | 21:55:39 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51502/tests/dromaeo/cssquery-ext.html (next: http://localhost:51502/tests/dromaeo/cssquery-jquery.html) 21:55:40 INFO - PROCESS | 1955 | RSS: Main: 381792256 21:55:40 INFO - PROCESS | 1955 | 21:58:42 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51502/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51502/tests/dromaeo/cssquery-mootools.html) 21:58:43 INFO - PROCESS | 1955 | RSS: Main: 350351360 21:58:43 INFO - PROCESS | 1955 | 22:01:45 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51502/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51502/tests/dromaeo/cssquery-prototype.html) 22:01:46 INFO - PROCESS | 1955 | RSS: Main: 347996160 22:01:46 INFO - PROCESS | 1955 | 22:04:49 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51502/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51502/tests/dromaeo/cssquery-yui.html) 22:04:50 INFO - PROCESS | 1955 | RSS: Main: 332222464 22:04:50 INFO - PROCESS | 1955 | 22:07:53 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51502/tests/dromaeo/cssquery-yui.html (next: http://localhost:51502/tests/dromaeo/cssquery-dojo.html) 22:07:53 INFO - PROCESS | 1955 | RSS: Main: 417886208 22:07:53 INFO - PROCESS | 1955 | 22:07:53 INFO - PROCESS | 1955 | __start_tp_report 22:07:53 INFO - PROCESS | 1955 | _x_x_mozilla_page_load 22:07:53 INFO - PROCESS | 1955 | _x_x_mozilla_page_load_details 22:07:53 INFO - PROCESS | 1955 | |i|pagename|runs| 22:07:53 INFO - PROCESS | 1955 | |0;dojo.html;3033.5752994580757;3160.5417214503896;3199.536067270246;3202.08740508955;3215.710586047255;5347.304850369452;5398.838034858951;5420.756065977031;5541.833744987647;5618.410066942974;5605.943940560594;5666.178404131399;5720.856978575538;5735.627184233022;5760.884782304352;5109.770060347293;5118.539331460168;5137.280780690707;5161.0968080585935;5173.767180476888;5149.356330458692;5254.422013578523;5271.024860400819;5277.577793776489;5297.735113244342;4565.748883811389;5034.697918124924;5229.320188375525;5240.371155461331;5335;4235.322348424268;5128.333316668846;5129.410117836422;5137.8201762938115;5144.022635699205;5072;5091.490850914879;5122.231665250205;5123.2059030849905;5143;5628.015097357987;5651.491365777099;5651.7739290428335;5667.404922483162;5679.772809087632;6092.3603021682975;6092.878142437126;6092.878142437171;6094.939050609526;6102.389761023906;5176.922346164811;6109.185657386345;6146.231721034871;6155.984262596666;6164.106204600353;5598.328200615912;5686.488216060575;5688.772449102073;5694.4590263924865;5704.515116215168;5180.352455943007;5398.676079435168;6228.252609686776;6228.377162283736;6261;6064.3935606439;6065.909011364833;6078.240219972504;6082.513398928075;6094.969525152346;6079.452849243589;6080.179175811298;6096.073160812928;6140.478059365004;6180.845478863064;5969.820905372846;6093.405094372345;6096.42239923716;6100.641728746782;6114.289413018248;6103.84384175518;6113.563193224698;6116.153172518083;6133.003204776275;6138.265785110703;4804.711717296974;4843.975780121077;4872.537108974641;4875.219964805614;4889.779959901813;3751.4185301278344;4357.455318085239;4413.404190434315;4425.982024134467;4475.552444755564;5718.313802343745;5723.484886360247;5735.168400581976;5740.1102829061565;5742.48317651405;6095.634261944298;6171.537134714914;6199.876002479925;6200.131981522591;6231.719572619243;5571.665700057928;5601.859953501195;5641.746121424546;5655.886882262414;5720.2849643794525;5357.1696387060065;5363.329583802025;5382.811601593926;5395.838124856261;5401.837944861661;6283.3088360280335;6284.5600807943;6302.747890084345;6305.653189074645;6307.400796924285;6053.061775424816;6108.584917381041;6140.258857967636;6201.255849297932;6211.409916057967;6147.815565533041;6166.784162554289;6221.377862213742;6267.2165979252595;6274.905876411766;6102.572819902742;6104.084387341934;6105.877882442415;6116.449519543263;6137.693115344304;6075.362086980804;6124.908126378019;6170.197874276495;6204.410580994799;6244.937550624436;5904.645721256738;5910;5931.051031834886;5964.194833697396;6011.27864656244;5915;5958.19564358806;5960.165576819162;5973.880522389615;5976.163337132892;5791.305043394819;5842.503387211965;5849.64902105875;5855.882882342244;5857.970710146422;5872.148538461814;5928.051511758098;5943.346231914573;5996.940030599638;6003.189569408074;5803.129530570448;5815.331236907809;5822;5827.621204621686;5830.4461076197695 22:07:53 INFO - PROCESS | 1955 | |1;ext.html;450.0391663797792;476.96661233713655;505.95699365553924;507.63196682405237;508.0626244578752;30477.390452190943;31085.06744797654;40670.99999999996;41751;42018.949526261786;40648;43159.56840431595;43266.591676040494;43395.3490697639;43450.34824477627;9410.106039926213;9579.712608621752;9602.807943841119;9606.615735370577;9620.134187923086;8547.957260213721;8758.737237882873;11411.54353825846;11707.941460292686;11711.941440292787;11497.965183133516;11586.884131158666;11608.187426880122;11608.361540115291;11679.299242045461;8662.307015438813;11508.424578771028;11568.421578921021;11579.826302605383;11583.957443830095;8559.700410485602;11154.163437742201;11456.968872801404;11474.967252947275;11616.419179041099;40269.39595906064;40424.59575404238;40435.595644043475;40437.59562404368;40477.595224047676;3318.3363327334628;3339.699427051577;3441.3633477806684;3569.5002699622073;3569.803660798655;29797.255068623243;30305;31647.525287120712;31650.525242121388;31686.36627267442;3161.4309424303615;3263.3473305339026;3318.5851768528933;3349.6482869298616;3350.676482789309;503321;505163;507204;507627;514033.9999999963;62804.68597657074;63217;63268.05097923535;63272.683636581525;63312.366876331565;55857.720711396185;66803;66928.99606505907;67025.65948681;67040.6647966757;15938.60153496172;16183.109928953785;16202.837971620369;16223.918880405523;16234.107124108288;11079.889201108046;11257.774844503065;12303.384830758427;12351.19717218378;12361.07291953107;16630.83369166317;18880.33918812835;19240.4227873164;19356.8064319355;19549.51126221856;6508.739650414025;7323.414126869838;7432.888506672404;7943.285104340522;8032.759017229492;865.8181781825762;900.7072701372081;1009.535613617744;1024.078329503438;1083.5936523803573;28201.71798282032;30198.84900575483;30367.848160759055;30605.02134722472;30783.8460807699;1006.6074231049896;1045.6340280901622;1053.1627356251797;1120.78705046041;1143.5482984221353;12635.684107897192;14546.345414456375;15558.110472381904;15615.21923903823;15870.365185392455;638086;639636;641960;642222;643315;59640;60157.796844062876;60216.39783602108;60239.69880150659;60457;1350.1628990026247;1377.7451171533098;1412.5903487988369;1414.6180531256502;1419.2336138485516;28269.71730282671;29157;29704;30491.542626861057;30668.693313067477;54120.45879541154;58071.709641451525;58144;58146.41853581579;58258;591091;599060;599063;600551;601624;35437.46843797397;42546.787266063475;43293.3505997404;43360.48238311644;43821;37704.43443348407;40590;45982.77008614936;47688;49079.509204907496;37892.62107378891;38245;39688.60311396849;39875.800620996706;40969.59030409658;39554.20891582095;39688.60311396964;39720.00699982524;40578.594214057484;41332.79333603313;38266.426003610526;38969.41545876757;38982.80508597439;38995.635152769515;39008;32713.509297360084;33639;34447.82776086104;35262.29475410426;35509;25553.9778408869;25644.487110258062;25912.74087259103;26040.218793436226;26287 22:07:53 INFO - PROCESS | 1955 | |2;jquery.html;343.74047594066474;374.76577139287946;380.7772453114928;389.89667738049405;403.886911664734;19186.616267674657;20717.378478645664;20974.16103355866;21556.568868622617;21694.023768930398;18736.34422795199;20381.388558343235;20688.8965555172;20994.790052099474;21233.15067397302;5652.604317697763;5668.234788303567;5867.266591676041;5911.024680927641;5946;4111.588841115894;4340.43574335336;5867.735951882154;5876.529877609781;5946.554008449362;4327;6133.969330153343;6419.293877673452;6474.0612611171355;6534.477241820667;4676.602488788457;6335.081413195084;6510.023496475543;6537.673116344211;6544.443722283564;5680.488756011938;5688.744006519716;5695.316562012544;5698.800541981011;5699.857503562403;25690;25859.67262946344;25922.74077259241;26079.73920260811;26320;2598.8570628615607;2827.363843135299;2871.8851245950345;3070.1076084190913;3184.506401507747;14581.70836583273;15002.549923502313;15410.3835846566;16230.918845405815;16398.098104604243;1836.2654938024898;1839.0345068838833;1851.8425933795643;1861.292708770672;1867.3557622620174;123359;124493.08110702244;125460;126160.36919815434;127186.36406817999;8115.4319197655595;9359.15767580921;9894.55474503649;10059.195264378832;10089.646862359783;12264.141510094209;12306.692332691755;12328.81506777399;12335.629931102081;12357.443915023845;7821.061472623322;7901.209879012053;7943.4042446815565;8084.555349455719;8110.675572977133;6860.828479288058;8120.26917577409;8192.385571082194;8206.015278166538;8395.076541580422;3246.366958443081;3820.1977584706965;3843.615638436134;3860.749051311656;3865.55787929552;1752.8615089590678;1788.8497618423958;1877.755891734066;1921.1354890299422;1958.0209895052474;780.9609519524115;926.6802952981211;937.9671711490065;983.8179936711731;1006.8590397344377;17080.829191707922;20788.376348709564;21756.78243217579;22002.779972200075;22011.33965981023;901.1169054326665;936.7377134402242;938.1884669760608;1012.2559918459926;1058.1164727452663;10520.421376824348;11440.9703126719;11452.255603385753;11486.023687986448;11505.13711471643;561055;562763;568045;583566;583846;69010.65494672595;69515;95809;108009.45995269973;121896;964.5996911281879;965.2856885904521;969.3989726369696;998.770282834952;999.8550210219478;14694.926525367304;19127.52181195481;19375.612487749884;20146.39560813178;20150.496237594176;50592.74703626458;50675.864827034005;51345.22982155196;51723.741381294356;52335.47664523306;68112;69372.3062769386;70255.64872175606;70606.64696676483;71131;6674.632895190811;8123.268905798506;8246;8290.37822163328;8328.916710833057;11065.723356916142;12946.546870859474;13000.804987925;13418.530351437652;13447.663808404868;11077.778444431227;11199.552017919192;11417.37204453763;11455.885441145481;11533.480993355486;11073.501692423699;11141.83287250708;11192.440377981231;11501.194916355775;11587.42062896835;9965.202783777426;10298.897011029794;11518.193726439069;11647.417629118341;11877.82183267269;7938.682452701827;8246.793830154294;8278.751637450887;8288.668453262044;8487.108853570286;10185.032421919906;10646.680599582025;11131.109511239243;11250.718732031764;11291.943540282246 22:07:53 INFO - PROCESS | 1955 | |3;mootools.html;1537.1545649892557;1562.2266977845964;1791.6954117799974;1885.3495544037319;1945.5816999345147;8361.372897032716;8871.86692199616;8885.866711999326;8912.554372281393;8917.10828917108;7837.764867053983;8108.351331893449;8147.959260203691;8185.34517238621;8651.48091114532;2137.807597316245;2165.891705414731;2296.873671948042;2370.3126093432884;2371.051579368258;2370.01644317608;2375.8455700379504;2376.940576485584;2383.84505007174;2384.701912260967;2032.6686020656493;2335.5328934213226;2387.606045002581;2388.080588973249;2391.6173412254043;2332.4518738096617;2344.5932440535626;2356.9882150589137;2360.2919124262653;2367.562001029815;2332.07882886259;2332.148765700524;2344.8358614896965;2361.0791791201445;2368.289513146049;8641.395102342838;8855.822883542292;8873.023967363584;8887.955560222223;8903.421277616999;5196.974015129938;5363.919541206885;5364.973175134139;5381.219723140122;5406.324209473816;3192.1859925718795;3392.915177120567;3607.8556857725657;3650.233450975298;3686.889393318205;1024.2267088348249;1066.807974564578;1092.431935393591;1094.0973696700255;1094.2231015978664;11549.307041577533;11594.6521604352;11636.883631163664;11641.089727568107;11661.883381166164;4412.514623391425;4562.178807814559;4760.214564596872;4763.404574428197;4769.73766442849;9871.160951319218;9879.901200987899;9885.752856178653;9895.554700038516;9903;5358.892822143535;5381.192821076792;5386.461353864583;5388.784448622089;5392.973035134799;4777.092352453023;4829.396325459317;4832.95167048325;4835.58897493717;4867.026594681078;3583.7670551414076;3723.6648701616987;3729.9440508392395;3732.4028155495535;3732.850685972585;1241.3606992398923;1293.178831442041;1299.629605562429;1300.6782892536964;1303.426492343366;775.6432041261083;838.9790255243668;845.6955496021427;920.2867777472512;977.2914636888199;5734.770609175586;5815.185873977646;5881.676967428936;5908.497777688859;6095.542834287447;901.6078006067381;908.6597269028131;924.9213816825511;969.8496733006396;1006.3056491021172;3898.278818418545;3916.1580260244186;3948.781742879544;3978.8209530571194;4002.3396139636634;10522.947385263025;10828.296160749525;10916.727081822859;10918;10961.561537538568;10866.293690910066;10867.29362591429;10869.945650271698;10899.29154604948;10911.83632245517;7696.345810606049;8113.797155071053;8122.106568277485;8210.876836847452;8217.383696222687;4973.751312434436;5797.753483001175;5991.970040149771;6130.938690613037;6133.754649813957;7163.641817909188;9959;10088.546015429176;10101.595936162765;10105.696829095139;9819.36174148678;10216.948915255376;10247;10247.128994035591;10259.58961641526;7289.271072892669;7421.851562968818;7423.665935032828;7430.888536671846;7433.851322973402;7399.445041621792;8161.387895907949;8190.50856948585;8210.671573137246;8218.465799722999;6816.591004539743;7109.82225444368;7394.33450989392;7410.666520006721;7481.66332515049;7191.352778249982;7285.23505031964;7298.087739032621;7333.779986600197;7363.521371111075;6835.453163746789;6975.9302406975285;6981.197162326194;6989.510734248757;6989.930100698928;4843.6367272454;4970.229614409773;4979.228219625964;4999.325091112653;5133.383993920754;4937.061958227925;4945.085159245551;5085.821996230115;5097.7706003229205;5178.119719647593 22:07:53 INFO - PROCESS | 1955 | |4;prototype.html;296.4870773561738;300.2553666906073;309.6361774914475;312.3721320146506;316.1669002179257;4326.41593384893;4699.530046995299;4790.778351520366;4794.616430685545;5188.146519414321;4227.619514243717;4377.905523619095;4379.321205213189;4604.815807367701;5017.297578339027;3318.966810331902;3778.6410291022307;3914.4324073009466;3965.6629186519035;4037.3540233562635;3499.860005599773;3850.3839385698293;3907.4920260366252;4001.6798656107585;4003.219372222418;3113.922151946208;3643.5627724673077;3664.8167591620445;3960.9801950990204;3968.821403036856;3214.3089235814414;3446.5002574626787;3887.4838812862863;3954.3079961006997;4000.539937907134;3407.6081250656243;3418.538497302882;3428.674275943781;3430.9485357719404;3435.9312813743836;4034.415009823559;4526.275795872678;5017.613111149959;5059.241113832954;5076.517730815566;3645.8541658333634;4037.253108174997;4463.62059224963;4525.660575456854;4557.635389168859;5168.431472538018;5481.835544933659;5486.561075114021;5509.586780991442;5565.693886836222;3506.6668666476644;3751.4185301278344;3770.5852356240794;3779.1496913194587;3811.4092315691114;5029.346184995927;5473.698946557938;5702.0042984954935;5770.19217309577;5832.562557808182;3328.9014625173636;3354.781939173946;3773.603771603942;3779.4708740776314;3801.5438147421933;3246.7077962983444;3825.980870095632;3886.805659717003;3936.0946982194255;3952.1898010907694;4320.632746216606;4475.888102797391;4501.353903338307;4545.31820226962;4587.311903214544;5460.778140896889;5482.479164479368;5495.378863235355;5527.226188333637;5630.971845140748;1232.9013678905667;1233.6052463211865;1251.8608066659297;1301.5834932821595;1302.3423171298623;723.6417973103347;823.6334831000147;845.2815107158989;874.3617159473492;890.8708237305555;4454.086912182995;4594.748573199176;5085.288059671649;5169.276301317818;5185.766640501187;5174.534291913746;5628.521575666033;5639.943600564024;5643.209950606918;5650.954573403934;4891.774242719554;5020.799168033311;5041.747912604355;5061.5950723942;5062.519060689228;2710.539208334588;2720.1567514070307;2880.25113470495;2919.664238612544;2940.6471223452895;5451.320358757823;5709.65742055478;5732.426757324318;5735.684537350402;5767.538596912237;5854.151148083504;5855.853603659943;5910.34986151523;5919.704014799243;5975.355033429604;5008.69947803133;5117.795288188505;5895.469407753237;5949.196858424058;5969.910451343147;5522.558195344443;5706.42935706426;5735.627184233012;5748.281464816898;5764;5680.68756218412;5688.3742788294085;5698;5704.201411802268;5716.65658570244;5495.313085864267;5545.306836645419;5884.6469211847425;5894.587378883437;5953.761849526144;3357.733529867379;3703.518542589446;3743.213925075656;3765.1340191755503;3818.8281527330782;4584.908301834012;5075.974620126875;5731.971340143273;5770.740316685843;5837.824865254049;5097.311862898461;5577.637453565505;5579.553635709215;5617.691026993554;5618.016847051668;5092.261622064854;5137.89535249923;5484.2596249507405;5533.916991245215;5561.721913904208;4744.576627011814;5143.8456846294675;5180.720482987474;5345.705986170798;5442.183672449006;4414.536473670219;5379.569634429158;5481.808136715355;5524.281843360337;5580.330360356783;4491.550844915482;4735.029318989667;5190.403103642979;5266.865510574334;5408.269883565668 22:07:53 INFO - PROCESS | 1955 | |5;yui.html;608.4879075497389;699.4055053204777;729.27437199986;744.3970383988969;820.1101804542071;14823.703525929488;15084.245787710597;15671.216439178053;15699.686006279868;15725.213739313045;14316;14423.134611923291;14437;14456.421743130288;14474.2762861857;868.270652651774;902.5938327752505;906.4289497616526;910.0399078971682;911.9922485653357;720.8882623193388;744.5346658338539;898.5507246376811;903.1510380242551;907.0430695616122;780.9765707028798;781.1407451803;782.7495201535511;784.4744021505605;925.6528801699362;943.6838659049226;943.7923656795529;945.0407836046419;947.9951251673249;949.3496954586061;702.8313204830855;703.3458883238586;703.6481759120439;703.8134894253027;708.8724029674656;12069.094817888694;12528.624141275777;14812.777808332883;15291.158986255752;15327.31027103783;13432.664183395396;13564.593062208149;13602.31988400586;13883.930580347134;14370.640733981629;11682.824757628643;11728.413579321;11771.234869733526;11803.527858885634;11997.94001029998;3740.532433445819;3826;3829.425586162098;3954.782486963216;3956.0900992771535;112748;113067;113765;114043;114418;8551.486910785372;9155.679551215675;9664.71005869825;10780.544626475483;11358.432078396047;20313.6639286481;21902.671459928115;23024.424389390064;23042;23457;7543.585102819288;7802.531848089019;7899.131095579482;8067.83864322722;8180.141085186089;4276.401303817468;4316.870493885126;4378;4388.363687265329;4504.481984571817;3538.133157376459;3777.0934975605655;3778.2254637799183;3779.5842457329672;3779.8677046303246;704.8590281943632;712.7968528969219;712.8467379513429;714.3428046197511;714.5569746757044;528.4926470588201;532.3558494221958;550.411060165627;554.6699713670359;656.579788935082;7493.737719179802;7583.084730737299;8790.208881200595;8909.777255568533;9075.773105672411;543.804230477028;604.8306474187235;656.5273003437518;689.1420181873599;691.7820886420761;4377.474703035592;4554.089182163514;4662.4405071390975;4765.142274390643;5085.415177254589;105263;152530;153082;153106;153443.23278383535;80411.19588804155;80740;120587.39706301587;125978.74021259854;127836;94991.52504237572;116918;117293.4135329335;118239;123175;8404.20160084791;9472.384295020802;9880.40717556949;9906;9943.900560994298;63305.68347158235;64393;65851;65880.34119658743;66207.7309176706;91117;96344.51827740816;99081;99167;104758;12458;12875.871241287841;12983.480660773841;13363.198208107544;13374.465021399035;14499.420023198954;14661.41354345857;14845.554633361016;14979.176145311676;15330.923345383202;13316.386292834892;13803.447862085806;13809.378577963815;13827.585172444842;13996.37016334247;13337;13547.79678304846;13778.931105344409;14038.1577105374;14216;11366.374849383032;13104.082714209913;13361.66595835112;13429.328533573087;13917.860821391656;10675.092617127631;10959.506822192861;11080.224384293022;11479.139064570023;11544.365059921785;10057.547410366406;11499.425028748696;11545.942270288931;11548.538058477567;11612.709682258012 22:07:53 INFO - PROCESS | 1955 | __end_tp_report 22:07:53 INFO - PROCESS | 1955 | __start_cc_report 22:07:53 INFO - PROCESS | 1955 | _x_x_mozilla_cycle_collect,1889 22:07:53 INFO - PROCESS | 1955 | __end_cc_report 22:07:53 INFO - PROCESS | 1955 | __startTimestamp1476767273865__endTimestamp 22:07:53 INFO - PROCESS | 1955 | 22:07:53 INFO - PROCESS | 1955 | ------- Summary: start ------- 22:07:53 INFO - PROCESS | 1955 | Number of tests: 6 22:07:53 INFO - PROCESS | 1955 | 22:07:53 INFO - PROCESS | 1955 | [#0] dojo.html Cycles:165 Average:5636.34 Median:5827.62 stddev:658.35 (11.3%) stddev-sans-first:627.91 22:07:53 INFO - PROCESS | 1955 | Values: 3033.6 3160.5 3199.5 3202.1 3215.7 5347.3 5398.8 5420.8 5541.8 5618.4 5605.9 5666.2 5720.9 5735.6 5760.9 5109.8 5118.5 5137.3 5161.1 5173.8 5149.4 5254.4 5271.0 5277.6 5297.7 4565.7 5034.7 5229.3 5240.4 5335.0 4235.3 5128.3 5129.4 5137.8 5144.0 5072.0 5091.5 5122.2 5123.2 5143.0 5628.0 5651.5 5651.8 5667.4 5679.8 6092.4 6092.9 6092.9 6094.9 6102.4 5176.9 6109.2 6146.2 6156.0 6164.1 5598.3 5686.5 5688.8 5694.5 5704.5 5180.4 5398.7 6228.3 6228.4 6261.0 6064.4 6065.9 6078.2 6082.5 6095.0 6079.5 6080.2 6096.1 6140.5 6180.8 5969.8 6093.4 6096.4 6100.6 6114.3 6103.8 6113.6 6116.2 6133.0 6138.3 4804.7 4844.0 4872.5 4875.2 4889.8 3751.4 4357.5 4413.4 4426.0 4475.6 5718.3 5723.5 5735.2 5740.1 5742.5 6095.6 6171.5 6199.9 6200.1 6231.7 5571.7 5601.9 5641.7 5655.9 5720.3 5357.2 5363.3 5382.8 5395.8 5401.8 6283.3 6284.6 6302.7 6305.7 6307.4 6053.1 6108.6 6140.3 6201.3 6211.4 6147.8 6166.8 6221.4 6267.2 6274.9 6102.6 6104.1 6105.9 6116.4 6137.7 6075.4 6124.9 6170.2 6204.4 6244.9 5904.6 5910.0 5931.1 5964.2 6011.3 5915.0 5958.2 5960.2 5973.9 5976.2 5791.3 5842.5 5849.6 5855.9 5858.0 5872.1 5928.1 5943.3 5996.9 6003.2 5803.1 5815.3 5822.0 5827.6 5830.4 22:07:53 INFO - PROCESS | 1955 | 22:07:53 INFO - PROCESS | 1955 | [#1] ext.html Cycles:180 Average:72246.93 Median:38617.92 stddev:156170.46 (404.4%) stddev-sans-first:156515.53 22:07:53 INFO - PROCESS | 1955 | Values: 450.0 477.0 506.0 507.6 508.1 30477.4 31085.1 40671.0 41751.0 42018.9 40648.0 43159.6 43266.6 43395.3 43450.3 9410.1 9579.7 9602.8 9606.6 9620.1 8548.0 8758.7 11411.5 11707.9 11711.9 11498.0 11586.9 11608.2 11608.4 11679.3 8662.3 11508.4 11568.4 11579.8 11584.0 8559.7 11154.2 11457.0 11475.0 11616.4 40269.4 40424.6 40435.6 40437.6 40477.6 3318.3 3339.7 3441.4 3569.5 3569.8 29797.3 30305.0 31647.5 31650.5 31686.4 3161.4 3263.3 3318.6 3349.6 3350.7 503321.0 505163.0 507204.0 507627.0 514034.0 62804.7 63217.0 63268.1 63272.7 63312.4 55857.7 66803.0 66929.0 67025.7 67040.7 15938.6 16183.1 16202.8 16223.9 16234.1 11079.9 11257.8 12303.4 12351.2 12361.1 16630.8 18880.3 19240.4 19356.8 19549.5 6508.7 7323.4 7432.9 7943.3 8032.8 865.8 900.7 1009.5 1024.1 1083.6 28201.7 30198.8 30367.8 30605.0 30783.8 1006.6 1045.6 1053.2 1120.8 1143.5 12635.7 14546.3 15558.1 15615.2 15870.4 638086.0 639636.0 641960.0 642222.0 643315.0 59640.0 60157.8 60216.4 60239.7 60457.0 1350.2 1377.7 1412.6 1414.6 1419.2 28269.7 29157.0 29704.0 30491.5 30668.7 54120.5 58071.7 58144.0 58146.4 58258.0 591091.0 599060.0 599063.0 600551.0 601624.0 35437.5 42546.8 43293.4 43360.5 43821.0 37704.4 40590.0 45982.8 47688.0 49079.5 37892.6 38245.0 39688.6 39875.8 40969.6 39554.2 39688.6 39720.0 40578.6 41332.8 38266.4 38969.4 38982.8 38995.6 39008.0 32713.5 33639.0 34447.8 35262.3 35509.0 25554.0 25644.5 25912.7 26040.2 26287.0 22:07:53 INFO - PROCESS | 1955 | 22:07:53 INFO - PROCESS | 1955 | [#2] jquery.html Cycles:180 Average:33290.04 Median:3215.44 stddev:95068.24 (2956.6%) stddev-sans-first:95302.74 22:07:53 INFO - PROCESS | 1955 | Values: 343.7 374.8 380.8 389.9 403.9 19186.6 20717.4 20974.2 21556.6 21694.0 18736.3 20381.4 20688.9 20994.8 21233.2 5652.6 5668.2 5867.3 5911.0 5946.0 4111.6 4340.4 5867.7 5876.5 5946.6 4327.0 6134.0 6419.3 6474.1 6534.5 4676.6 6335.1 6510.0 6537.7 6544.4 5680.5 5688.7 5695.3 5698.8 5699.9 25690.0 25859.7 25922.7 26079.7 26320.0 2598.9 2827.4 2871.9 3070.1 3184.5 14581.7 15002.5 15410.4 16230.9 16398.1 1836.3 1839.0 1851.8 1861.3 1867.4 123359.0 124493.1 125460.0 126160.4 127186.4 8115.4 9359.2 9894.6 10059.2 10089.6 12264.1 12306.7 12328.8 12335.6 12357.4 7821.1 7901.2 7943.4 8084.6 8110.7 6860.8 8120.3 8192.4 8206.0 8395.1 3246.4 3820.2 3843.6 3860.7 3865.6 1752.9 1788.8 1877.8 1921.1 1958.0 781.0 926.7 938.0 983.8 1006.9 17080.8 20788.4 21756.8 22002.8 22011.3 901.1 936.7 938.2 1012.3 1058.1 10520.4 11441.0 11452.3 11486.0 11505.1 561055.0 562763.0 568045.0 583566.0 583846.0 69010.7 69515.0 95809.0 108009.5 121896.0 964.6 965.3 969.4 998.8 999.9 14694.9 19127.5 19375.6 20146.4 20150.5 50592.7 50675.9 51345.2 51723.7 52335.5 68112.0 69372.3 70255.6 70606.6 71131.0 6674.6 8123.3 8246.0 8290.4 8328.9 11065.7 12946.5 13000.8 13418.5 13447.7 11077.8 11199.6 11417.4 11455.9 11533.5 11073.5 11141.8 11192.4 11501.2 11587.4 9965.2 10298.9 11518.2 11647.4 11877.8 7938.7 8246.8 8278.8 8288.7 8487.1 10185.0 10646.7 11131.1 11250.7 11291.9 22:07:53 INFO - PROCESS | 1955 | 22:07:53 INFO - PROCESS | 1955 | [#3] mootools.html Cycles:180 Average:5675.79 Median:4957.66 stddev:3180.57 (64.2%) stddev-sans-first:3174.29 22:07:53 INFO - PROCESS | 1955 | Values: 1537.2 1562.2 1791.7 1885.3 1945.6 8361.4 8871.9 8885.9 8912.6 8917.1 7837.8 8108.4 8148.0 8185.3 8651.5 2137.8 2165.9 2296.9 2370.3 2371.1 2370.0 2375.8 2376.9 2383.8 2384.7 2032.7 2335.5 2387.6 2388.1 2391.6 2332.5 2344.6 2357.0 2360.3 2367.6 2332.1 2332.1 2344.8 2361.1 2368.3 8641.4 8855.8 8873.0 8888.0 8903.4 5197.0 5363.9 5365.0 5381.2 5406.3 3192.2 3392.9 3607.9 3650.2 3686.9 1024.2 1066.8 1092.4 1094.1 1094.2 11549.3 11594.7 11636.9 11641.1 11661.9 4412.5 4562.2 4760.2 4763.4 4769.7 9871.2 9879.9 9885.8 9895.6 9903.0 5358.9 5381.2 5386.5 5388.8 5393.0 4777.1 4829.4 4833.0 4835.6 4867.0 3583.8 3723.7 3729.9 3732.4 3732.9 1241.4 1293.2 1299.6 1300.7 1303.4 775.6 839.0 845.7 920.3 977.3 5734.8 5815.2 5881.7 5908.5 6095.5 901.6 908.7 924.9 969.8 1006.3 3898.3 3916.2 3948.8 3978.8 4002.3 10522.9 10828.3 10916.7 10918.0 10961.6 10866.3 10867.3 10869.9 10899.3 10911.8 7696.3 8113.8 8122.1 8210.9 8217.4 4973.8 5797.8 5992.0 6130.9 6133.8 7163.6 9959.0 10088.5 10101.6 10105.7 9819.4 10216.9 10247.0 10247.1 10259.6 7289.3 7421.9 7423.7 7430.9 7433.9 7399.4 8161.4 8190.5 8210.7 8218.5 6816.6 7109.8 7394.3 7410.7 7481.7 7191.4 7285.2 7298.1 7333.8 7363.5 6835.5 6975.9 6981.2 6989.5 6989.9 4843.6 4970.2 4979.2 4999.3 5133.4 4937.1 4945.1 5085.8 5097.8 5178.1 22:07:53 INFO - PROCESS | 1955 | 22:07:53 INFO - PROCESS | 1955 | [#4] prototype.html Cycles:180 Average:4423.67 Median:5019.21 stddev:1396.22 (27.8%) stddev-sans-first:1365.34 22:07:53 INFO - PROCESS | 1955 | Values: 296.5 300.3 309.6 312.4 316.2 4326.4 4699.5 4790.8 4794.6 5188.1 4227.6 4377.9 4379.3 4604.8 5017.3 3319.0 3778.6 3914.4 3965.7 4037.4 3499.9 3850.4 3907.5 4001.7 4003.2 3113.9 3643.6 3664.8 3961.0 3968.8 3214.3 3446.5 3887.5 3954.3 4000.5 3407.6 3418.5 3428.7 3430.9 3435.9 4034.4 4526.3 5017.6 5059.2 5076.5 3645.9 4037.3 4463.6 4525.7 4557.6 5168.4 5481.8 5486.6 5509.6 5565.7 3506.7 3751.4 3770.6 3779.1 3811.4 5029.3 5473.7 5702.0 5770.2 5832.6 3328.9 3354.8 3773.6 3779.5 3801.5 3246.7 3826.0 3886.8 3936.1 3952.2 4320.6 4475.9 4501.4 4545.3 4587.3 5460.8 5482.5 5495.4 5527.2 5631.0 1232.9 1233.6 1251.9 1301.6 1302.3 723.6 823.6 845.3 874.4 890.9 4454.1 4594.7 5085.3 5169.3 5185.8 5174.5 5628.5 5639.9 5643.2 5651.0 4891.8 5020.8 5041.7 5061.6 5062.5 2710.5 2720.2 2880.3 2919.7 2940.6 5451.3 5709.7 5732.4 5735.7 5767.5 5854.2 5855.9 5910.3 5919.7 5975.4 5008.7 5117.8 5895.5 5949.2 5969.9 5522.6 5706.4 5735.6 5748.3 5764.0 5680.7 5688.4 5698.0 5704.2 5716.7 5495.3 5545.3 5884.6 5894.6 5953.8 3357.7 3703.5 3743.2 3765.1 3818.8 4584.9 5076.0 5732.0 5770.7 5837.8 5097.3 5577.6 5579.6 5617.7 5618.0 5092.3 5137.9 5484.3 5533.9 5561.7 4744.6 5143.8 5180.7 5345.7 5442.2 4414.5 5379.6 5481.8 5524.3 5580.3 4491.6 4735.0 5190.4 5266.9 5408.3 22:07:53 INFO - PROCESS | 1955 | 22:07:53 INFO - PROCESS | 1955 | [#5] yui.html Cycles:180 Average:24536.47 Median:3802.93 stddev:38911.00 (1023.2%) stddev-sans-first:38978.68 22:07:53 INFO - PROCESS | 1955 | Values: 608.5 699.4 729.3 744.4 820.1 14823.7 15084.2 15671.2 15699.7 15725.2 14316.0 14423.1 14437.0 14456.4 14474.3 868.3 902.6 906.4 910.0 912.0 720.9 744.5 898.6 903.2 907.0 781.0 781.1 782.7 784.5 925.7 943.7 943.8 945.0 948.0 949.3 702.8 703.3 703.6 703.8 708.9 12069.1 12528.6 14812.8 15291.2 15327.3 13432.7 13564.6 13602.3 13883.9 14370.6 11682.8 11728.4 11771.2 11803.5 11997.9 3740.5 3826.0 3829.4 3954.8 3956.1 112748.0 113067.0 113765.0 114043.0 114418.0 8551.5 9155.7 9664.7 10780.5 11358.4 20313.7 21902.7 23024.4 23042.0 23457.0 7543.6 7802.5 7899.1 8067.8 8180.1 4276.4 4316.9 4378.0 4388.4 4504.5 3538.1 3777.1 3778.2 3779.6 3779.9 704.9 712.8 712.8 714.3 714.6 528.5 532.4 550.4 554.7 656.6 7493.7 7583.1 8790.2 8909.8 9075.8 543.8 604.8 656.5 689.1 691.8 4377.5 4554.1 4662.4 4765.1 5085.4 105263.0 152530.0 153082.0 153106.0 153443.2 80411.2 80740.0 120587.4 125978.7 127836.0 94991.5 116918.0 117293.4 118239.0 123175.0 8404.2 9472.4 9880.4 9906.0 9943.9 63305.7 64393.0 65851.0 65880.3 66207.7 91117.0 96344.5 99081.0 99167.0 104758.0 12458.0 12875.9 12983.5 13363.2 13374.5 14499.4 14661.4 14845.6 14979.2 15330.9 13316.4 13803.4 13809.4 13827.6 13996.4 13337.0 13547.8 13778.9 14038.2 14216.0 11366.4 13104.1 13361.7 13429.3 13917.9 10675.1 10959.5 11080.2 11479.1 11544.4 10057.5 11499.4 11545.9 11548.5 11612.7 22:07:53 INFO - PROCESS | 1955 | -------- Summary: end -------- 22:07:53 INFO - PROCESS | 1955 | 22:07:54 INFO - TEST-INFO | 1955: exit 0 22:07:54 INFO - TEST-OK | dromaeo_css | took 1119774ms 22:07:54 INFO - TEST-START | kraken 22:07:54 INFO - operating with platform_type : mac_ 22:07:54 INFO - Initialising browser for kraken test... 22:07:54 INFO - TEST-INFO | started process 1959 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/tmpFbjiTp/profile http://localhost:51502/getInfo.html) 22:07:57 INFO - PROCESS | 1959 | __metrics Screen width/height:1600/1200 22:07:57 INFO - PROCESS | 1959 | colorDepth:24 22:07:57 INFO - PROCESS | 1959 | Browser inner width/height: 1024/689 22:07:57 INFO - PROCESS | 1959 | __metrics 22:07:57 INFO - PROCESS | 1959 | 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" 22:07:57 INFO - PROCESS | 1959 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:07:57 INFO - TEST-INFO | 1959: exit 0 22:07:57 INFO - Browser initialized. 22:07:57 INFO - Running cycle 1/1 for kraken test... 22:07:57 INFO - TEST-INFO | started process 1960 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/tmpFbjiTp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:08:09 INFO - PROCESS | 1960 | RSS: Main: 230522880 22:08:09 INFO - PROCESS | 1960 | 22:08:12 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51502/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51502/tests/kraken/driver.html?testName=audio-beat-detection) 22:08:13 INFO - PROCESS | 1960 | RSS: Main: 290340864 22:08:13 INFO - PROCESS | 1960 | 22:08:16 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51502/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51502/tests/kraken/driver.html?testName=audio-dft) 22:08:16 INFO - PROCESS | 1960 | RSS: Main: 290959360 22:08:16 INFO - PROCESS | 1960 | 22:08:20 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51502/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51502/tests/kraken/driver.html?testName=audio-fft) 22:08:20 INFO - PROCESS | 1960 | RSS: Main: 383332352 22:08:20 INFO - PROCESS | 1960 | 22:08:22 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51502/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51502/tests/kraken/driver.html?testName=audio-oscillator) 22:08:23 INFO - PROCESS | 1960 | RSS: Main: 288296960 22:08:23 INFO - PROCESS | 1960 | 22:08:25 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51502/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51502/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:08:26 INFO - PROCESS | 1960 | RSS: Main: 290152448 22:08:26 INFO - PROCESS | 1960 | 22:08:28 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51502/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51502/tests/kraken/driver.html?testName=imaging-darkroom) 22:08:29 INFO - PROCESS | 1960 | RSS: Main: 327036928 22:08:29 INFO - PROCESS | 1960 | 22:08:32 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51502/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51502/tests/kraken/driver.html?testName=imaging-desaturate) 22:08:32 INFO - PROCESS | 1960 | RSS: Main: 328925184 22:08:32 INFO - PROCESS | 1960 | 22:08:35 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51502/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51502/tests/kraken/driver.html?testName=json-parse-financial) 22:08:36 INFO - PROCESS | 1960 | RSS: Main: 326295552 22:08:36 INFO - PROCESS | 1960 | 22:08:37 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51502/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51502/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:08:38 INFO - PROCESS | 1960 | RSS: Main: 302489600 22:08:38 INFO - PROCESS | 1960 | 22:08:40 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51502/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51502/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:08:41 INFO - PROCESS | 1960 | RSS: Main: 306995200 22:08:41 INFO - PROCESS | 1960 | 22:08:43 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51502/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51502/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:08:43 INFO - PROCESS | 1960 | RSS: Main: 309891072 22:08:43 INFO - PROCESS | 1960 | 22:08:46 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51502/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51502/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:08:47 INFO - PROCESS | 1960 | RSS: Main: 315535360 22:08:47 INFO - PROCESS | 1960 | 22:08:50 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51502/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51502/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:08:51 INFO - PROCESS | 1960 | RSS: Main: 316723200 22:08:51 INFO - PROCESS | 1960 | 22:08:52 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51502/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51502/tests/kraken/driver.html?testName=ai-astar) 22:08:53 INFO - PROCESS | 1960 | RSS: Main: 360865792 22:08:53 INFO - PROCESS | 1960 | 22:08:53 INFO - PROCESS | 1960 | __start_tp_report 22:08:53 INFO - PROCESS | 1960 | _x_x_mozilla_page_load 22:08:53 INFO - PROCESS | 1960 | _x_x_mozilla_page_load_details 22:08:53 INFO - PROCESS | 1960 | |i|pagename|runs| 22:08:53 INFO - PROCESS | 1960 | |0;ai-astar;112;115;112;114;115;114;114;113;114;114 22:08:53 INFO - PROCESS | 1960 | |1;audio-beat-detection;148;114;116;180;142;118;113;111;140;113 22:08:53 INFO - PROCESS | 1960 | |2;audio-dft;184;182;183;184;183;186;186;184;179;183 22:08:53 INFO - PROCESS | 1960 | |3;audio-fft;81;60;59;87;62;60;59;58;83;58 22:08:53 INFO - PROCESS | 1960 | |4;audio-oscillator;84;71;96;93;97;99;92;67;68;90 22:08:53 INFO - PROCESS | 1960 | |5;imaging-gaussian-blur;86;85;86;84;86;86;134;84;87;83 22:08:53 INFO - PROCESS | 1960 | |6;imaging-darkroom;95;96;97;96;96;96;94;95;94;96 22:08:53 INFO - PROCESS | 1960 | |7;imaging-desaturate;90;81;79;80;84;81;86;82;92;123 22:08:53 INFO - PROCESS | 1960 | |8;json-parse-financial;56;56;55;53;52;54;53;54;55;55 22:08:53 INFO - PROCESS | 1960 | |9;json-stringify-tinderbox;65;75;77;73;72;71;70;73;71;73 22:08:53 INFO - PROCESS | 1960 | |10;stanford-crypto-aes;90;90;86;89;90;86;89;86;91;102 22:08:53 INFO - PROCESS | 1960 | |11;stanford-crypto-ccm;147;144;145;147;143;144;148;152;183;165 22:08:53 INFO - PROCESS | 1960 | |12;stanford-crypto-pbkdf2;173;166;172;174;170;173;177;171;171;172 22:08:53 INFO - PROCESS | 1960 | |13;stanford-crypto-sha256-iterative;67;71;81;88;85;71;68;68;68;67 22:08:53 INFO - PROCESS | 1960 | __end_tp_report 22:08:53 INFO - PROCESS | 1960 | __start_cc_report 22:08:53 INFO - PROCESS | 1960 | _x_x_mozilla_cycle_collect,1958 22:08:53 INFO - PROCESS | 1960 | __end_cc_report 22:08:53 INFO - PROCESS | 1960 | __startTimestamp1476767333117__endTimestamp 22:08:53 INFO - PROCESS | 1960 | 22:08:53 INFO - PROCESS | 1960 | ------- Summary: start ------- 22:08:53 INFO - PROCESS | 1960 | Number of tests: 14 22:08:53 INFO - PROCESS | 1960 | 22:08:53 INFO - PROCESS | 1960 | [#0] ai-astar Cycles:10 Average:113.70 Median:114.00 stddev:1.06 (0.9%) stddev-sans-first:0.93 22:08:53 INFO - PROCESS | 1960 | Values: 112.0 115.0 112.0 114.0 115.0 114.0 114.0 113.0 114.0 114.0 22:08:53 INFO - PROCESS | 1960 | 22:08:53 INFO - PROCESS | 1960 | [#1] audio-beat-detection Cycles:10 Average:129.50 Median:129.00 stddev:22.61 (17.5%) stddev-sans-first:22.97 22:08:53 INFO - PROCESS | 1960 | Values: 148.0 114.0 116.0 180.0 142.0 118.0 113.0 111.0 140.0 113.0 22:08:53 INFO - PROCESS | 1960 | 22:08:53 INFO - PROCESS | 1960 | [#2] audio-dft Cycles:10 Average:183.40 Median:184.00 stddev:2.01 (1.1%) stddev-sans-first:2.12 22:08:53 INFO - PROCESS | 1960 | Values: 184.0 182.0 183.0 184.0 183.0 186.0 186.0 184.0 179.0 183.0 22:08:53 INFO - PROCESS | 1960 | 22:08:53 INFO - PROCESS | 1960 | [#3] audio-fft Cycles:10 Average:66.70 Median:61.00 stddev:11.85 (19.4%) stddev-sans-first:11.38 22:08:53 INFO - PROCESS | 1960 | Values: 81.0 60.0 59.0 87.0 62.0 60.0 59.0 58.0 83.0 58.0 22:08:53 INFO - PROCESS | 1960 | 22:08:53 INFO - PROCESS | 1960 | [#4] audio-oscillator Cycles:10 Average:85.70 Median:92.50 stddev:12.49 (13.5%) stddev-sans-first:13.23 22:08:53 INFO - PROCESS | 1960 | Values: 84.0 71.0 96.0 93.0 97.0 99.0 92.0 67.0 68.0 90.0 22:08:53 INFO - PROCESS | 1960 | 22:08:53 INFO - PROCESS | 1960 | [#5] imaging-gaussian-blur Cycles:10 Average:90.10 Median:86.00 stddev:15.47 (18.0%) stddev-sans-first:16.34 22:08:53 INFO - PROCESS | 1960 | Values: 86.0 85.0 86.0 84.0 86.0 86.0 134.0 84.0 87.0 83.0 22:08:53 INFO - PROCESS | 1960 | 22:08:53 INFO - PROCESS | 1960 | [#6] imaging-darkroom Cycles:10 Average:95.50 Median:96.00 stddev:0.97 (1.0%) stddev-sans-first:1.01 22:08:53 INFO - PROCESS | 1960 | Values: 95.0 96.0 97.0 96.0 96.0 96.0 94.0 95.0 94.0 96.0 22:08:53 INFO - PROCESS | 1960 | 22:08:53 INFO - PROCESS | 1960 | [#7] imaging-desaturate Cycles:10 Average:87.80 Median:83.00 stddev:13.10 (15.8%) stddev-sans-first:13.87 22:08:53 INFO - PROCESS | 1960 | Values: 90.0 81.0 79.0 80.0 84.0 81.0 86.0 82.0 92.0 123.0 22:08:53 INFO - PROCESS | 1960 | 22:08:53 INFO - PROCESS | 1960 | [#8] json-parse-financial Cycles:10 Average:54.30 Median:55.00 stddev:1.34 (2.4%) stddev-sans-first:1.27 22:08:53 INFO - PROCESS | 1960 | Values: 56.0 56.0 55.0 53.0 52.0 54.0 53.0 54.0 55.0 55.0 22:08:53 INFO - PROCESS | 1960 | 22:08:53 INFO - PROCESS | 1960 | [#9] json-stringify-tinderbox Cycles:10 Average:72.00 Median:73.00 stddev:3.20 (4.4%) stddev-sans-first:2.17 22:08:53 INFO - PROCESS | 1960 | Values: 65.0 75.0 77.0 73.0 72.0 71.0 70.0 73.0 71.0 73.0 22:08:53 INFO - PROCESS | 1960 | 22:08:53 INFO - PROCESS | 1960 | [#10] stanford-crypto-aes Cycles:10 Average:89.90 Median:89.50 stddev:4.65 (5.2%) stddev-sans-first:4.94 22:08:53 INFO - PROCESS | 1960 | Values: 90.0 90.0 86.0 89.0 90.0 86.0 89.0 86.0 91.0 102.0 22:08:53 INFO - PROCESS | 1960 | 22:08:53 INFO - PROCESS | 1960 | [#11] stanford-crypto-ccm Cycles:10 Average:151.80 Median:147.50 stddev:12.71 (8.6%) stddev-sans-first:13.36 22:08:53 INFO - PROCESS | 1960 | Values: 147.0 144.0 145.0 147.0 143.0 144.0 148.0 152.0 183.0 165.0 22:08:53 INFO - PROCESS | 1960 | 22:08:53 INFO - PROCESS | 1960 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.90 Median:172.50 stddev:2.85 (1.6%) stddev-sans-first:2.99 22:08:53 INFO - PROCESS | 1960 | Values: 173.0 166.0 172.0 174.0 170.0 173.0 177.0 171.0 171.0 172.0 22:08:53 INFO - PROCESS | 1960 | 22:08:53 INFO - PROCESS | 1960 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.40 Median:71.00 stddev:8.07 (11.4%) stddev-sans-first:8.22 22:08:53 INFO - PROCESS | 1960 | Values: 67.0 71.0 81.0 88.0 85.0 71.0 68.0 68.0 68.0 67.0 22:08:53 INFO - PROCESS | 1960 | -------- Summary: end -------- 22:08:53 INFO - PROCESS | 1960 | 22:08:53 INFO - TEST-INFO | 1960: exit 0 22:08:54 INFO - TEST-OK | kraken | took 59359ms 22:08:54 INFO - SUITE-END | took 1179s 22:08:54 INFO - Completed test suite (00:19:39) 22:08:54 INFO - javascript benchmark 22:08:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3033.5752994580757, 3160.5417214503896, 3199.536067270246, 3202.08740508955, 3215.710586047255, 5347.304850369452, 5398.838034858951, 5420.756065977031, 5541.833744987647, 5618.410066942974, 5605.943940560594, 5666.178404131399, 5720.856978575538, 5735.627184233022, 5760.884782304352, 5109.770060347293, 5118.539331460168, 5137.280780690707, 5161.0968080585935, 5173.767180476888, 5149.356330458692, 5254.422013578523, 5271.024860400819, 5277.577793776489, 5297.735113244342, 4565.748883811389, 5034.697918124924, 5229.320188375525, 5240.371155461331, 5335.0, 4235.322348424268, 5128.333316668846, 5129.410117836422, 5137.8201762938115, 5144.022635699205, 5072.0, 5091.490850914879, 5122.231665250205, 5123.2059030849905, 5143.0, 5628.015097357987, 5651.491365777099, 5651.7739290428335, 5667.404922483162, 5679.772809087632, 6092.3603021682975, 6092.878142437126, 6092.878142437171, 6094.939050609526, 6102.389761023906, 5176.922346164811, 6109.185657386345, 6146.231721034871, 6155.984262596666, 6164.106204600353, 5598.328200615912, 5686.488216060575, 5688.772449102073, 5694.4590263924865, 5704.515116215168, 5180.352455943007, 5398.676079435168, 6228.252609686776, 6228.377162283736, 6261.0, 6064.3935606439, 6065.909011364833, 6078.240219972504, 6082.513398928075, 6094.969525152346, 6079.452849243589, 6080.179175811298, 6096.073160812928, 6140.478059365004, 6180.845478863064, 5969.820905372846, 6093.405094372345, 6096.42239923716, 6100.641728746782, 6114.289413018248, 6103.84384175518, 6113.563193224698, 6116.153172518083, 6133.003204776275, 6138.265785110703, 4804.711717296974, 4843.975780121077, 4872.537108974641, 4875.219964805614, 4889.779959901813, 3751.4185301278344, 4357.455318085239, 4413.404190434315, 4425.982024134467, 4475.552444755564, 5718.313802343745, 5723.484886360247, 5735.168400581976, 5740.1102829061565, 5742.48317651405, 6095.634261944298, 6171.537134714914, 6199.876002479925, 6200.131981522591, 6231.719572619243, 5571.665700057928, 5601.859953501195, 5641.746121424546, 5655.886882262414, 5720.2849643794525, 5357.1696387060065, 5363.329583802025, 5382.811601593926, 5395.838124856261, 5401.837944861661, 6283.3088360280335, 6284.5600807943, 6302.747890084345, 6305.653189074645, 6307.400796924285, 6053.061775424816, 6108.584917381041, 6140.258857967636, 6201.255849297932, 6211.409916057967, 6147.815565533041, 6166.784162554289, 6221.377862213742, 6267.2165979252595, 6274.905876411766, 6102.572819902742, 6104.084387341934, 6105.877882442415, 6116.449519543263, 6137.693115344304, 6075.362086980804, 6124.908126378019, 6170.197874276495, 6204.410580994799, 6244.937550624436, 5904.645721256738, 5910.0, 5931.051031834886, 5964.194833697396, 6011.27864656244, 5915.0, 5958.19564358806, 5960.165576819162, 5973.880522389615, 5976.163337132892, 5791.305043394819, 5842.503387211965, 5849.64902105875, 5855.882882342244, 5857.970710146422, 5872.148538461814, 5928.051511758098, 5943.346231914573, 5996.940030599638, 6003.189569408074, 5803.129530570448, 5815.331236907809, 5822.0, 5827.621204621686, 5830.4461076197695], "value": 5591.984208414394, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [450.0391663797792, 476.96661233713655, 505.95699365553924, 507.63196682405237, 508.0626244578752, 30477.390452190943, 31085.06744797654, 40670.99999999996, 41751.0, 42018.949526261786, 40648.0, 43159.56840431595, 43266.591676040494, 43395.3490697639, 43450.34824477627, 9410.106039926213, 9579.712608621752, 9602.807943841119, 9606.615735370577, 9620.134187923086, 8547.957260213721, 8758.737237882873, 11411.54353825846, 11707.941460292686, 11711.941440292787, 11497.965183133516, 11586.884131158666, 11608.187426880122, 11608.361540115291, 11679.299242045461, 8662.307015438813, 11508.424578771028, 11568.421578921021, 11579.826302605383, 11583.957443830095, 8559.700410485602, 11154.163437742201, 11456.968872801404, 11474.967252947275, 11616.419179041099, 40269.39595906064, 40424.59575404238, 40435.595644043475, 40437.59562404368, 40477.595224047676, 3318.3363327334628, 3339.699427051577, 3441.3633477806684, 3569.5002699622073, 3569.803660798655, 29797.255068623243, 30305.0, 31647.525287120712, 31650.525242121388, 31686.36627267442, 3161.4309424303615, 3263.3473305339026, 3318.5851768528933, 3349.6482869298616, 3350.676482789309, 503321.0, 505163.0, 507204.0, 507627.0, 514033.9999999963, 62804.68597657074, 63217.0, 63268.05097923535, 63272.683636581525, 63312.366876331565, 55857.720711396185, 66803.0, 66928.99606505907, 67025.65948681, 67040.6647966757, 15938.60153496172, 16183.109928953785, 16202.837971620369, 16223.918880405523, 16234.107124108288, 11079.889201108046, 11257.774844503065, 12303.384830758427, 12351.19717218378, 12361.07291953107, 16630.83369166317, 18880.33918812835, 19240.4227873164, 19356.8064319355, 19549.51126221856, 6508.739650414025, 7323.414126869838, 7432.888506672404, 7943.285104340522, 8032.759017229492, 865.8181781825762, 900.7072701372081, 1009.535613617744, 1024.078329503438, 1083.5936523803573, 28201.71798282032, 30198.84900575483, 30367.848160759055, 30605.02134722472, 30783.8460807699, 1006.6074231049896, 1045.6340280901622, 1053.1627356251797, 1120.78705046041, 1143.5482984221353, 12635.684107897192, 14546.345414456375, 15558.110472381904, 15615.21923903823, 15870.365185392455, 638086.0, 639636.0, 641960.0, 642222.0, 643315.0, 59640.0, 60157.796844062876, 60216.39783602108, 60239.69880150659, 60457.0, 1350.1628990026247, 1377.7451171533098, 1412.5903487988369, 1414.6180531256502, 1419.2336138485516, 28269.71730282671, 29157.0, 29704.0, 30491.542626861057, 30668.693313067477, 54120.45879541154, 58071.709641451525, 58144.0, 58146.41853581579, 58258.0, 591091.0, 599060.0, 599063.0, 600551.0, 601624.0, 35437.46843797397, 42546.787266063475, 43293.3505997404, 43360.48238311644, 43821.0, 37704.43443348407, 40590.0, 45982.77008614936, 47688.0, 49079.509204907496, 37892.62107378891, 38245.0, 39688.60311396849, 39875.800620996706, 40969.59030409658, 39554.20891582095, 39688.60311396964, 39720.00699982524, 40578.594214057484, 41332.79333603313, 38266.426003610526, 38969.41545876757, 38982.80508597439, 38995.635152769515, 39008.0, 32713.509297360084, 33639.0, 34447.82776086104, 35262.29475410426, 35509.0, 25553.9778408869, 25644.487110258062, 25912.74087259103, 26040.218793436226, 26287.0], "value": 20654.636502653237, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.74047594066474, 374.76577139287946, 380.7772453114928, 389.89667738049405, 403.886911664734, 19186.616267674657, 20717.378478645664, 20974.16103355866, 21556.568868622617, 21694.023768930398, 18736.34422795199, 20381.388558343235, 20688.8965555172, 20994.790052099474, 21233.15067397302, 5652.604317697763, 5668.234788303567, 5867.266591676041, 5911.024680927641, 5946.0, 4111.588841115894, 4340.43574335336, 5867.735951882154, 5876.529877609781, 5946.554008449362, 4327.0, 6133.969330153343, 6419.293877673452, 6474.0612611171355, 6534.477241820667, 4676.602488788457, 6335.081413195084, 6510.023496475543, 6537.673116344211, 6544.443722283564, 5680.488756011938, 5688.744006519716, 5695.316562012544, 5698.800541981011, 5699.857503562403, 25690.0, 25859.67262946344, 25922.74077259241, 26079.73920260811, 26320.0, 2598.8570628615607, 2827.363843135299, 2871.8851245950345, 3070.1076084190913, 3184.506401507747, 14581.70836583273, 15002.549923502313, 15410.3835846566, 16230.918845405815, 16398.098104604243, 1836.2654938024898, 1839.0345068838833, 1851.8425933795643, 1861.292708770672, 1867.3557622620174, 123359.0, 124493.08110702244, 125460.0, 126160.36919815434, 127186.36406817999, 8115.4319197655595, 9359.15767580921, 9894.55474503649, 10059.195264378832, 10089.646862359783, 12264.141510094209, 12306.692332691755, 12328.81506777399, 12335.629931102081, 12357.443915023845, 7821.061472623322, 7901.209879012053, 7943.4042446815565, 8084.555349455719, 8110.675572977133, 6860.828479288058, 8120.26917577409, 8192.385571082194, 8206.015278166538, 8395.076541580422, 3246.366958443081, 3820.1977584706965, 3843.615638436134, 3860.749051311656, 3865.55787929552, 1752.8615089590678, 1788.8497618423958, 1877.755891734066, 1921.1354890299422, 1958.0209895052474, 780.9609519524115, 926.6802952981211, 937.9671711490065, 983.8179936711731, 1006.8590397344377, 17080.829191707922, 20788.376348709564, 21756.78243217579, 22002.779972200075, 22011.33965981023, 901.1169054326665, 936.7377134402242, 938.1884669760608, 1012.2559918459926, 1058.1164727452663, 10520.421376824348, 11440.9703126719, 11452.255603385753, 11486.023687986448, 11505.13711471643, 561055.0, 562763.0, 568045.0, 583566.0, 583846.0, 69010.65494672595, 69515.0, 95809.0, 108009.45995269973, 121896.0, 964.5996911281879, 965.2856885904521, 969.3989726369696, 998.770282834952, 999.8550210219478, 14694.926525367304, 19127.52181195481, 19375.612487749884, 20146.39560813178, 20150.496237594176, 50592.74703626458, 50675.864827034005, 51345.22982155196, 51723.741381294356, 52335.47664523306, 68112.0, 69372.3062769386, 70255.64872175606, 70606.64696676483, 71131.0, 6674.632895190811, 8123.268905798506, 8246.0, 8290.37822163328, 8328.916710833057, 11065.723356916142, 12946.546870859474, 13000.804987925, 13418.530351437652, 13447.663808404868, 11077.778444431227, 11199.552017919192, 11417.37204453763, 11455.885441145481, 11533.480993355486, 11073.501692423699, 11141.83287250708, 11192.440377981231, 11501.194916355775, 11587.42062896835, 9965.202783777426, 10298.897011029794, 11518.193726439069, 11647.417629118341, 11877.82183267269, 7938.682452701827, 8246.793830154294, 8278.751637450887, 8288.668453262044, 8487.108853570286, 10185.032421919906, 10646.680599582025, 11131.109511239243, 11250.718732031764, 11291.943540282246], "value": 9460.694272254485, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1537.1545649892557, 1562.2266977845964, 1791.6954117799974, 1885.3495544037319, 1945.5816999345147, 8361.372897032716, 8871.86692199616, 8885.866711999326, 8912.554372281393, 8917.10828917108, 7837.764867053983, 8108.351331893449, 8147.959260203691, 8185.34517238621, 8651.48091114532, 2137.807597316245, 2165.891705414731, 2296.873671948042, 2370.3126093432884, 2371.051579368258, 2370.01644317608, 2375.8455700379504, 2376.940576485584, 2383.84505007174, 2384.701912260967, 2032.6686020656493, 2335.5328934213226, 2387.606045002581, 2388.080588973249, 2391.6173412254043, 2332.4518738096617, 2344.5932440535626, 2356.9882150589137, 2360.2919124262653, 2367.562001029815, 2332.07882886259, 2332.148765700524, 2344.8358614896965, 2361.0791791201445, 2368.289513146049, 8641.395102342838, 8855.822883542292, 8873.023967363584, 8887.955560222223, 8903.421277616999, 5196.974015129938, 5363.919541206885, 5364.973175134139, 5381.219723140122, 5406.324209473816, 3192.1859925718795, 3392.915177120567, 3607.8556857725657, 3650.233450975298, 3686.889393318205, 1024.2267088348249, 1066.807974564578, 1092.431935393591, 1094.0973696700255, 1094.2231015978664, 11549.307041577533, 11594.6521604352, 11636.883631163664, 11641.089727568107, 11661.883381166164, 4412.514623391425, 4562.178807814559, 4760.214564596872, 4763.404574428197, 4769.73766442849, 9871.160951319218, 9879.901200987899, 9885.752856178653, 9895.554700038516, 9903.0, 5358.892822143535, 5381.192821076792, 5386.461353864583, 5388.784448622089, 5392.973035134799, 4777.092352453023, 4829.396325459317, 4832.95167048325, 4835.58897493717, 4867.026594681078, 3583.7670551414076, 3723.6648701616987, 3729.9440508392395, 3732.4028155495535, 3732.850685972585, 1241.3606992398923, 1293.178831442041, 1299.629605562429, 1300.6782892536964, 1303.426492343366, 775.6432041261083, 838.9790255243668, 845.6955496021427, 920.2867777472512, 977.2914636888199, 5734.770609175586, 5815.185873977646, 5881.676967428936, 5908.497777688859, 6095.542834287447, 901.6078006067381, 908.6597269028131, 924.9213816825511, 969.8496733006396, 1006.3056491021172, 3898.278818418545, 3916.1580260244186, 3948.781742879544, 3978.8209530571194, 4002.3396139636634, 10522.947385263025, 10828.296160749525, 10916.727081822859, 10918.0, 10961.561537538568, 10866.293690910066, 10867.29362591429, 10869.945650271698, 10899.29154604948, 10911.83632245517, 7696.345810606049, 8113.797155071053, 8122.106568277485, 8210.876836847452, 8217.383696222687, 4973.751312434436, 5797.753483001175, 5991.970040149771, 6130.938690613037, 6133.754649813957, 7163.641817909188, 9959.0, 10088.546015429176, 10101.595936162765, 10105.696829095139, 9819.36174148678, 10216.948915255376, 10247.0, 10247.128994035591, 10259.58961641526, 7289.271072892669, 7421.851562968818, 7423.665935032828, 7430.888536671846, 7433.851322973402, 7399.445041621792, 8161.387895907949, 8190.50856948585, 8210.671573137246, 8218.465799722999, 6816.591004539743, 7109.82225444368, 7394.33450989392, 7410.666520006721, 7481.66332515049, 7191.352778249982, 7285.23505031964, 7298.087739032621, 7333.779986600197, 7363.521371111075, 6835.453163746789, 6975.9302406975285, 6981.197162326194, 6989.510734248757, 6989.930100698928, 4843.6367272454, 4970.229614409773, 4979.228219625964, 4999.325091112653, 5133.383993920754, 4937.061958227925, 4945.085159245551, 5085.821996230115, 5097.7706003229205, 5178.119719647593], "value": 4569.351410957668, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.4870773561738, 300.2553666906073, 309.6361774914475, 312.3721320146506, 316.1669002179257, 4326.41593384893, 4699.530046995299, 4790.778351520366, 4794.616430685545, 5188.146519414321, 4227.619514243717, 4377.905523619095, 4379.321205213189, 4604.815807367701, 5017.297578339027, 3318.966810331902, 3778.6410291022307, 3914.4324073009466, 3965.6629186519035, 4037.3540233562635, 3499.860005599773, 3850.3839385698293, 3907.4920260366252, 4001.6798656107585, 4003.219372222418, 3113.922151946208, 3643.5627724673077, 3664.8167591620445, 3960.9801950990204, 3968.821403036856, 3214.3089235814414, 3446.5002574626787, 3887.4838812862863, 3954.3079961006997, 4000.539937907134, 3407.6081250656243, 3418.538497302882, 3428.674275943781, 3430.9485357719404, 3435.9312813743836, 4034.415009823559, 4526.275795872678, 5017.613111149959, 5059.241113832954, 5076.517730815566, 3645.8541658333634, 4037.253108174997, 4463.62059224963, 4525.660575456854, 4557.635389168859, 5168.431472538018, 5481.835544933659, 5486.561075114021, 5509.586780991442, 5565.693886836222, 3506.6668666476644, 3751.4185301278344, 3770.5852356240794, 3779.1496913194587, 3811.4092315691114, 5029.346184995927, 5473.698946557938, 5702.0042984954935, 5770.19217309577, 5832.562557808182, 3328.9014625173636, 3354.781939173946, 3773.603771603942, 3779.4708740776314, 3801.5438147421933, 3246.7077962983444, 3825.980870095632, 3886.805659717003, 3936.0946982194255, 3952.1898010907694, 4320.632746216606, 4475.888102797391, 4501.353903338307, 4545.31820226962, 4587.311903214544, 5460.778140896889, 5482.479164479368, 5495.378863235355, 5527.226188333637, 5630.971845140748, 1232.9013678905667, 1233.6052463211865, 1251.8608066659297, 1301.5834932821595, 1302.3423171298623, 723.6417973103347, 823.6334831000147, 845.2815107158989, 874.3617159473492, 890.8708237305555, 4454.086912182995, 4594.748573199176, 5085.288059671649, 5169.276301317818, 5185.766640501187, 5174.534291913746, 5628.521575666033, 5639.943600564024, 5643.209950606918, 5650.954573403934, 4891.774242719554, 5020.799168033311, 5041.747912604355, 5061.5950723942, 5062.519060689228, 2710.539208334588, 2720.1567514070307, 2880.25113470495, 2919.664238612544, 2940.6471223452895, 5451.320358757823, 5709.65742055478, 5732.426757324318, 5735.684537350402, 5767.538596912237, 5854.151148083504, 5855.853603659943, 5910.34986151523, 5919.704014799243, 5975.355033429604, 5008.69947803133, 5117.795288188505, 5895.469407753237, 5949.196858424058, 5969.910451343147, 5522.558195344443, 5706.42935706426, 5735.627184233012, 5748.281464816898, 5764.0, 5680.68756218412, 5688.3742788294085, 5698.0, 5704.201411802268, 5716.65658570244, 5495.313085864267, 5545.306836645419, 5884.6469211847425, 5894.587378883437, 5953.761849526144, 3357.733529867379, 3703.518542589446, 3743.213925075656, 3765.1340191755503, 3818.8281527330782, 4584.908301834012, 5075.974620126875, 5731.971340143273, 5770.740316685843, 5837.824865254049, 5097.311862898461, 5577.637453565505, 5579.553635709215, 5617.691026993554, 5618.016847051668, 5092.261622064854, 5137.89535249923, 5484.2596249507405, 5533.916991245215, 5561.721913904208, 4744.576627011814, 5143.8456846294675, 5180.720482987474, 5345.705986170798, 5442.183672449006, 4414.536473670219, 5379.569634429158, 5481.808136715355, 5524.281843360337, 5580.330360356783, 4491.550844915482, 4735.029318989667, 5190.403103642979, 5266.865510574334, 5408.269883565668], "value": 3979.7329976434617, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [608.4879075497389, 699.4055053204777, 729.27437199986, 744.3970383988969, 820.1101804542071, 14823.703525929488, 15084.245787710597, 15671.216439178053, 15699.686006279868, 15725.213739313045, 14316.0, 14423.134611923291, 14437.0, 14456.421743130288, 14474.2762861857, 868.270652651774, 902.5938327752505, 906.4289497616526, 910.0399078971682, 911.9922485653357, 720.8882623193388, 744.5346658338539, 898.5507246376811, 903.1510380242551, 907.0430695616122, 780.9765707028798, 781.1407451803, 782.7495201535511, 784.4744021505605, 925.6528801699362, 943.6838659049226, 943.7923656795529, 945.0407836046419, 947.9951251673249, 949.3496954586061, 702.8313204830855, 703.3458883238586, 703.6481759120439, 703.8134894253027, 708.8724029674656, 12069.094817888694, 12528.624141275777, 14812.777808332883, 15291.158986255752, 15327.31027103783, 13432.664183395396, 13564.593062208149, 13602.31988400586, 13883.930580347134, 14370.640733981629, 11682.824757628643, 11728.413579321, 11771.234869733526, 11803.527858885634, 11997.94001029998, 3740.532433445819, 3826.0, 3829.425586162098, 3954.782486963216, 3956.0900992771535, 112748.0, 113067.0, 113765.0, 114043.0, 114418.0, 8551.486910785372, 9155.679551215675, 9664.71005869825, 10780.544626475483, 11358.432078396047, 20313.6639286481, 21902.671459928115, 23024.424389390064, 23042.0, 23457.0, 7543.585102819288, 7802.531848089019, 7899.131095579482, 8067.83864322722, 8180.141085186089, 4276.401303817468, 4316.870493885126, 4378.0, 4388.363687265329, 4504.481984571817, 3538.133157376459, 3777.0934975605655, 3778.2254637799183, 3779.5842457329672, 3779.8677046303246, 704.8590281943632, 712.7968528969219, 712.8467379513429, 714.3428046197511, 714.5569746757044, 528.4926470588201, 532.3558494221958, 550.411060165627, 554.6699713670359, 656.579788935082, 7493.737719179802, 7583.084730737299, 8790.208881200595, 8909.777255568533, 9075.773105672411, 543.804230477028, 604.8306474187235, 656.5273003437518, 689.1420181873599, 691.7820886420761, 4377.474703035592, 4554.089182163514, 4662.4405071390975, 4765.142274390643, 5085.415177254589, 105263.0, 152530.0, 153082.0, 153106.0, 153443.23278383535, 80411.19588804155, 80740.0, 120587.39706301587, 125978.74021259854, 127836.0, 94991.52504237572, 116918.0, 117293.4135329335, 118239.0, 123175.0, 8404.20160084791, 9472.384295020802, 9880.40717556949, 9906.0, 9943.900560994298, 63305.68347158235, 64393.0, 65851.0, 65880.34119658743, 66207.7309176706, 91117.0, 96344.51827740816, 99081.0, 99167.0, 104758.0, 12458.0, 12875.871241287841, 12983.480660773841, 13363.198208107544, 13374.465021399035, 14499.420023198954, 14661.41354345857, 14845.554633361016, 14979.176145311676, 15330.923345383202, 13316.386292834892, 13803.447862085806, 13809.378577963815, 13827.585172444842, 13996.37016334247, 13337.0, 13547.79678304846, 13778.931105344409, 14038.1577105374, 14216.0, 11366.374849383032, 13104.082714209913, 13361.66595835112, 13429.328533573087, 13917.860821391656, 10675.092617127631, 10959.506822192861, 11080.224384293022, 11479.139064570023, 11544.365059921785, 10057.547410366406, 11499.425028748696, 11545.942270288931, 11548.538058477567, 11612.709682258012], "value": 7781.960533082759, "unit": "score"}], "name": "dromaeo_css", "value": 7326.425747645607, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 115.0, 112.0, 114.0, 115.0, 114.0, 114.0, 113.0, 114.0, 114.0], "value": 113.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 114.0, 116.0, 180.0, 142.0, 118.0, 113.0, 111.0, 140.0, 113.0], "value": 129.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 182.0, 183.0, 184.0, 183.0, 186.0, 186.0, 184.0, 179.0, 183.0], "value": 183.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 59.0, 87.0, 62.0, 60.0, 59.0, 58.0, 83.0, 58.0], "value": 66.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 71.0, 96.0, 93.0, 97.0, 99.0, 92.0, 67.0, 68.0, 90.0], "value": 85.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 86.0, 84.0, 86.0, 86.0, 134.0, 84.0, 87.0, 83.0], "value": 90.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 97.0, 96.0, 96.0, 96.0, 94.0, 95.0, 94.0, 96.0], "value": 95.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 81.0, 79.0, 80.0, 84.0, 81.0, 86.0, 82.0, 92.0, 123.0], "value": 87.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 55.0, 53.0, 52.0, 54.0, 53.0, 54.0, 55.0, 55.0], "value": 54.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 75.0, 77.0, 73.0, 72.0, 71.0, 70.0, 73.0, 71.0, 73.0], "value": 72.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 86.0, 89.0, 90.0, 86.0, 89.0, 86.0, 91.0, 102.0], "value": 89.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 144.0, 145.0, 147.0, 143.0, 144.0, 148.0, 152.0, 183.0, 165.0], "value": 151.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 166.0, 172.0, 174.0, 170.0, 173.0, 177.0, 171.0, 171.0, 172.0], "value": 171.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 71.0, 81.0, 88.0, 85.0, 71.0, 68.0, 68.0, 68.0, 67.0], "value": 73.4, "unit": "score"}], "name": "kraken", "value": 1465.7, "alertThreshold": 2.0}]} 22:08:54 INFO - Return code: 0 22:08:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:08:54 INFO - # TBPL SUCCESS # 22:08:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:08:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:08:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:08:54 INFO - 2016-10-17 22:08:54.454 screenresolution[1964:17493] starting screenresolution argv=screenresolution get 22:08:54 INFO - 2016-10-17 22:08:54.457 screenresolution[1964:17493] Display 0: 1600x1200x32@60 22:08:54 INFO - 2016-10-17 22:08:54.464 screenresolution[1965:17496] starting screenresolution argv=screenresolution list 22:08:54 INFO - Available Modes on Display 0 22:08:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:08:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:08:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:08:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:08:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:08:54 INFO - Intel Iris: 22:08:54 INFO - Chipset Model: Intel Iris 22:08:54 INFO - Type: GPU 22:08:54 INFO - Bus: Built-In 22:08:54 INFO - VRAM (Dynamic, Max): 1536 MB 22:08:54 INFO - Vendor: Intel (0x8086) 22:08:54 INFO - Device ID: 0x0a2e 22:08:54 INFO - Revision ID: 0x0009 22:08:54 INFO - Displays: 22:08:54 INFO - Display: 22:08:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:08:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:08:54 INFO - Display Serial Number: 1600x1200 60 22:08:54 INFO - Main Display: Yes 22:08:54 INFO - Mirror: Off 22:08:54 INFO - Online: Yes 22:08:54 INFO - Rotation: Supported 22:08:54 INFO - Return code: 0 22:08:54 INFO - Running post-action listener: _resource_record_post_action 22:08:54 INFO - [mozharness: 2016-10-18 05:08:54.698312Z] Finished run-tests step (success) 22:08:54 INFO - Running post-run listener: _resource_record_post_run 22:08:54 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 565248; Write bytes: 708311552; Read time: 5; Write time: 1941 22:08:54 INFO - TinderboxPrint: CPU usage
53.6% 22:08:54 INFO - TinderboxPrint: I/O read bytes / time
565,248 / 5 22:08:54 INFO - TinderboxPrint: I/O write bytes / time
708,311,552 / 1,941 22:08:54 INFO - TinderboxPrint: CPU idle
2,254.1 (46.4%) 22:08:54 INFO - TinderboxPrint: CPU system
974.9 (20.1%) 22:08:54 INFO - TinderboxPrint: CPU user
1,628.7 (33.5%) 22:08:54 INFO - TinderboxPrint: Swap in / out
406,552,576 / 0 22:08:54 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 393116160; Write bytes: 387729408; Read time: 29416; Write time: 767 22:08:54 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 565248; Write bytes: 298193408; Read time: 5; Write time: 1113 22:08:55 INFO - Running post-run listener: _upload_blobber_files 22:08:55 INFO - Blob upload gear active. 22:08:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:08:55 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 22:08:55 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'] 22:08:55 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 22:08:55 INFO - (blobuploader) - INFO - Open directory for files ... 22:08:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:08:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:08:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:08:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:08:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:08:56 INFO - (blobuploader) - INFO - Done attempting. 22:08:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:08:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:08:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:08:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:08:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:08:57 INFO - (blobuploader) - INFO - Done attempting. 22:08:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:08:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:08:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:09:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:09:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:09:00 INFO - (blobuploader) - INFO - Done attempting. 22:09:00 INFO - (blobuploader) - INFO - Iteration through files over. 22:09:00 INFO - Return code: 0 22:09:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:09:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:09:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/af6618b4675574d503f76fd4cbe1f708cadd508308d198f9f1085d4d306ee8a20e80b8540635d7b81bc22ebea2bde4e1fbe32f1a8826e45413470cd16a2478d7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2faf4344d045ec4a62f081c60a639e5e57c02b444cd9090e08725edcc659afade34c017cb3f7a5894994766aaf6dbdf30f69823d70ae5bccc8053848b615c852", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/240820df5c7daabbd1dbf281314559938291f992a51361e90c9a8df7a7e0544fe9f09cf31faa980a389d6c5a8440f35642664d4b1ecca6fe73e2869c4652f310"} 22:09:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:09:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:09:00 INFO - Contents: 22:09:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/af6618b4675574d503f76fd4cbe1f708cadd508308d198f9f1085d4d306ee8a20e80b8540635d7b81bc22ebea2bde4e1fbe32f1a8826e45413470cd16a2478d7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2faf4344d045ec4a62f081c60a639e5e57c02b444cd9090e08725edcc659afade34c017cb3f7a5894994766aaf6dbdf30f69823d70ae5bccc8053848b615c852", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/240820df5c7daabbd1dbf281314559938291f992a51361e90c9a8df7a7e0544fe9f09cf31faa980a389d6c5a8440f35642664d4b1ecca6fe73e2869c4652f310"} 22:09:00 INFO - Running post-run listener: copy_logs_to_upload_dir 22:09:00 INFO - Copying logs to upload dir... 22:09:00 INFO - mkdir: /builds/slave/test/build/upload/logs 22:09:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.576048 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-17 22:09:00.474611) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-17 22:09:00.478126) ========= 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.gZLJ5wubgm/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.zm9NxzVG61/Listeners TMPDIR=/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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/af6618b4675574d503f76fd4cbe1f708cadd508308d198f9f1085d4d306ee8a20e80b8540635d7b81bc22ebea2bde4e1fbe32f1a8826e45413470cd16a2478d7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2faf4344d045ec4a62f081c60a639e5e57c02b444cd9090e08725edcc659afade34c017cb3f7a5894994766aaf6dbdf30f69823d70ae5bccc8053848b615c852", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/240820df5c7daabbd1dbf281314559938291f992a51361e90c9a8df7a7e0544fe9f09cf31faa980a389d6c5a8440f35642664d4b1ecca6fe73e2869c4652f310"} build_url:https://queue.taskcluster.net/v1/task/YQr02MRyTseuoeZopCh-Rw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011293 build_url: 'https://queue.taskcluster.net/v1/task/YQr02MRyTseuoeZopCh-Rw/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/af6618b4675574d503f76fd4cbe1f708cadd508308d198f9f1085d4d306ee8a20e80b8540635d7b81bc22ebea2bde4e1fbe32f1a8826e45413470cd16a2478d7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2faf4344d045ec4a62f081c60a639e5e57c02b444cd9090e08725edcc659afade34c017cb3f7a5894994766aaf6dbdf30f69823d70ae5bccc8053848b615c852", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/240820df5c7daabbd1dbf281314559938291f992a51361e90c9a8df7a7e0544fe9f09cf31faa980a389d6c5a8440f35642664d4b1ecca6fe73e2869c4652f310"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-17 22:09:00.516485) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 22:09:00.517327) ========= 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.gZLJ5wubgm/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.zm9NxzVG61/Listeners TMPDIR=/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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.005188 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 22:09:00.567848) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-17 22:09:00.568193) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-17 22:09:00.568564) ========= ========= Total master_lag: 0.19 =========