builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0349 starttime: 1476488450.74 results: success (0) buildid: 20161014145136 builduid: 98326076381e49b999db3e7bb465e166 revision: 9696d132b1691d15ff845550d13ef9a6cfc78049 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-14 16:40:50.743823) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-14 16:40:50.744297) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-14 16:40:50.744578) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-14 16:40:50.768748) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 16:40:50.769059) ========= 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.dtA442Gpcb/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.qnZMAoedMX/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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-14 16:40:50-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.0M=0.001s 2016-10-14 16:40:50 (19.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.135563 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 16:40:50.926986) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 16:40:50.927365) ========= 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.dtA442Gpcb/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.qnZMAoedMX/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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.023904 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 16:40:50.976419) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-14 16:40:50.976784) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 9696d132b1691d15ff845550d13ef9a6cfc78049 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 9696d132b1691d15ff845550d13ef9a6cfc78049 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dtA442Gpcb/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.qnZMAoedMX/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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-14 16:40:51,060 truncating revision to first 12 chars 2016-10-14 16:40:51,060 Setting DEBUG logging. 2016-10-14 16:40:51,060 attempt 1/10 2016-10-14 16:40:51,060 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/9696d132b169?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-14 16:40:52,537 unpacking tar archive at: fx-team-9696d132b169/testing/mozharness/ program finished with exit code 0 elapsedTime=1.742265 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-14 16:40:52.740692) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-14 16:40:52.741015) ========= script_repo_revision: 9696d132b1691d15ff845550d13ef9a6cfc78049 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-14 16:40:52.741431) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-14 16:40:52.741701) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-14 16:40:52.759891) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-10-14 16:40:52.760294) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dtA442Gpcb/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.qnZMAoedMX/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 16:40:52 INFO - MultiFileLogger online at 20161014 16:40:52 in /builds/slave/test 16:40:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 16:40:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:40:52 INFO - {'append_to_log': False, 16:40:52 INFO - 'base_work_dir': '/builds/slave/test', 16:40:52 INFO - 'blob_upload_branch': 'Fx-Team', 16:40:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:40:52 INFO - 'branch': 'Fx-Team', 16:40:52 INFO - 'buildbot_json_path': 'buildprops.json', 16:40:52 INFO - 'config_files': ('talos/mac_config.py',), 16:40:52 INFO - 'default_actions': ('clobber', 16:40:52 INFO - 'read-buildbot-config', 16:40:52 INFO - 'download-and-extract', 16:40:52 INFO - 'populate-webroot', 16:40:52 INFO - 'create-virtualenv', 16:40:52 INFO - 'install', 16:40:52 INFO - 'run-tests'), 16:40:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:40:52 INFO - 'download_minidump_stackwalk': True, 16:40:52 INFO - 'download_symbols': 'ondemand', 16:40:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:40:52 INFO - 'tooltool.py': '/tools/tooltool.py', 16:40:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:40:52 INFO - '/tools/misc-python/virtualenv.py')}, 16:40:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:40:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:40:52 INFO - 'installer_path': 'installer.exe', 16:40:52 INFO - 'log_level': 'info', 16:40:52 INFO - 'log_name': 'talos', 16:40:52 INFO - 'log_to_console': True, 16:40:52 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:40:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:40:52 INFO - 'opt_config_files': (), 16:40:52 INFO - 'pip_index': False, 16:40:52 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:40:52 INFO - 'cmd': ('bash', 16:40:52 INFO - '-c', 16:40:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:40:52 INFO - 'enabled': True, 16:40:52 INFO - 'halt_on_failure': False, 16:40:52 INFO - 'name': 'check_screen_resolution'},), 16:40:52 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:40:52 INFO - 'cmd': ('bash', 16:40:52 INFO - '-c', 16:40:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:40:52 INFO - 'enabled': True, 16:40:52 INFO - 'halt_on_failure': False, 16:40:52 INFO - 'name': 'check_screen_resolution'},), 16:40:52 INFO - 'run_cmd_checks_enabled': True, 16:40:52 INFO - 'sps_profile': False, 16:40:52 INFO - 'sps_profile_interval': 0, 16:40:52 INFO - 'suite': 'dromaeojs', 16:40:52 INFO - 'system_bits': '32', 16:40:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:40:52 INFO - 'title': 't-yosemite-r7-0349', 16:40:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:40:52 INFO - 'use_talos_json': True, 16:40:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:40:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:40:52 INFO - 'work_dir': 'build'} 16:40:52 INFO - [mozharness: 2016-10-14 23:40:52.966047Z] Running clobber step. 16:40:52 INFO - Running pre-action listener: _resource_record_pre_action 16:40:52 INFO - Running main action method: clobber 16:40:52 INFO - rmtree: /builds/slave/test/build 16:40:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:40:55 INFO - Running post-action listener: _resource_record_post_action 16:40:55 INFO - [mozharness: 2016-10-14 23:40:55.101487Z] Finished clobber step (success) 16:40:55 INFO - [mozharness: 2016-10-14 23:40:55.101744Z] Running read-buildbot-config step. 16:40:55 INFO - Running pre-action listener: _resource_record_pre_action 16:40:55 INFO - Running main action method: read_buildbot_config 16:40:55 INFO - Using buildbot properties: 16:40:55 INFO - { 16:40:55 INFO - "project": "", 16:40:55 INFO - "product": "firefox", 16:40:55 INFO - "script_repo_revision": "production", 16:40:55 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 16:40:55 INFO - "repository": "", 16:40:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 16:40:55 INFO - "buildid": "20161014145136", 16:40:55 INFO - "pgo_build": "False", 16:40:55 INFO - "basedir": "/builds/slave/test", 16:40:55 INFO - "buildnumber": 860, 16:40:55 INFO - "slavename": "t-yosemite-r7-0349", 16:40:55 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 16:40:55 INFO - "platform": "yosemite_r7", 16:40:55 INFO - "scheduler": "tests-fx-team-macosx64-talos", 16:40:55 INFO - "branch": "fx-team", 16:40:55 INFO - "repo_path": "integration/fx-team", 16:40:55 INFO - "revision": "9696d132b1691d15ff845550d13ef9a6cfc78049", 16:40:55 INFO - "stage_platform": "macosx64", 16:40:55 INFO - "builduid": "98326076381e49b999db3e7bb465e166", 16:40:55 INFO - "slavebuilddir": "test" 16:40:55 INFO - } 16:40:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/P105I_29Tpe0ivLowzCZsw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 16:40:55 INFO - Running post-action listener: _resource_record_post_action 16:40:55 INFO - [mozharness: 2016-10-14 23:40:55.107421Z] Finished read-buildbot-config step (success) 16:40:55 INFO - [mozharness: 2016-10-14 23:40:55.107575Z] Running download-and-extract step. 16:40:55 INFO - Running pre-action listener: _resource_record_pre_action 16:40:55 INFO - Running main action method: download_and_extract 16:40:55 INFO - mkdir: /builds/slave/test/build/tests 16:40:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:40:55 INFO - trying https://queue.taskcluster.net/v1/task/P105I_29Tpe0ivLowzCZsw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 16:40:55 INFO - Downloading https://queue.taskcluster.net/v1/task/P105I_29Tpe0ivLowzCZsw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 16:40:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P105I_29Tpe0ivLowzCZsw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 16:40:56 INFO - Downloaded 1199 bytes. 16:40:56 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 16:40:56 INFO - Using the following test package requirements: 16:40:56 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 16:40:56 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:40:56 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 16:40:56 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:40:56 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 16:40:56 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:40:56 INFO - u'jsshell-mac.zip'], 16:40:56 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:40:56 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 16:40:56 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 16:40:56 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:40:56 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 16:40:56 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:40:56 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 16:40:56 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:40:56 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 16:40:56 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:40:56 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 16:40:56 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:40:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P105I_29Tpe0ivLowzCZsw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 16:40:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P105I_29Tpe0ivLowzCZsw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:40:56 INFO - Fetch https://queue.taskcluster.net/v1/task/P105I_29Tpe0ivLowzCZsw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 16:40:56 INFO - Http code: 200 16:40:56 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 16:40:56 INFO - x-amz-cf-id: fy-zUQE6n-98ZJsDF1569OGkFq74xXzA4V99P3_vdT9msWOfL9QLAA== 16:40:56 INFO - x-amz-version-id: .9c8i2aic5rKPzzqA7_9lsfjkM9lvy2P 16:40:57 INFO - Expected file size: 16516718 16:40:57 INFO - Obtained file size: 16516718 16:41:03 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 16:41:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P105I_29Tpe0ivLowzCZsw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 16:41:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P105I_29Tpe0ivLowzCZsw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:41:03 INFO - Fetch https://queue.taskcluster.net/v1/task/P105I_29Tpe0ivLowzCZsw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 16:41:04 INFO - Http code: 200 16:41:04 INFO - via: 1.1 37af14ee951b12568cf1cc160d08abe6.cloudfront.net (CloudFront) 16:41:04 INFO - x-amz-cf-id: fP7-nRb_ZqhxiYaB0td-vmTaRO9g9fa0HxMISQd82PVqPR-f4uflMQ== 16:41:04 INFO - x-amz-version-id: .9c8i2aic5rKPzzqA7_9lsfjkM9lvy2P 16:41:04 INFO - Expected file size: 16516718 16:41:04 INFO - Obtained file size: 16516718 16:41:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P105I_29Tpe0ivLowzCZsw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 16:41:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P105I_29Tpe0ivLowzCZsw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:41:11 INFO - Fetch https://queue.taskcluster.net/v1/task/P105I_29Tpe0ivLowzCZsw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 16:41:12 INFO - Http code: 200 16:41:12 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 16:41:12 INFO - x-amz-cf-id: LnVHAyRsATL9B7_N0wPH80_rY5_G52SGpvpEovC9lColOT9EGx5O0Q== 16:41:12 INFO - x-amz-version-id: wDnPXlrhTFS_._YwEoJth0WnC3RPEi1Z 16:41:12 INFO - Expected file size: 14409028 16:41:12 INFO - Obtained file size: 14409028 16:41:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:41:12 INFO - trying https://queue.taskcluster.net/v1/task/P105I_29Tpe0ivLowzCZsw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:41:12 INFO - Downloading https://queue.taskcluster.net/v1/task/P105I_29Tpe0ivLowzCZsw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 16:41:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P105I_29Tpe0ivLowzCZsw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 16:41:15 INFO - Downloaded 111959330 bytes. 16:41:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/P105I_29Tpe0ivLowzCZsw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:41:15 INFO - mkdir: /builds/slave/test/properties 16:41:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:41:15 INFO - Writing to file /builds/slave/test/properties/build_url 16:41:15 INFO - Contents: 16:41:15 INFO - build_url:https://queue.taskcluster.net/v1/task/P105I_29Tpe0ivLowzCZsw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:41:16 INFO - Running post-action listener: _resource_record_post_action 16:41:16 INFO - Running post-action listener: set_extra_try_arguments 16:41:16 INFO - [mozharness: 2016-10-14 23:41:16.219569Z] Finished download-and-extract step (success) 16:41:16 INFO - [mozharness: 2016-10-14 23:41:16.219693Z] Running populate-webroot step. 16:41:16 INFO - Running pre-action listener: _resource_record_pre_action 16:41:16 INFO - Running main action method: populate_webroot 16:41:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:41:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:41:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:41:16 INFO - '--tppagecycles', 16:41:16 INFO - '7'], 16:41:16 INFO - 'tests': ['tsvgm']}}, 16:41:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:41:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:41:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:41:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:41:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:41:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:41:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:41:16 INFO - 'talos_options': ['--disable-e10s'], 16:41:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:41:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:41:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:41:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:41:16 INFO - 'talos_options': ['--disable-e10s'], 16:41:16 INFO - 'tests': ['damp', 'tps']}, 16:41:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:41:16 INFO - 'tests': ['damp', 'tps']}, 16:41:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:41:16 INFO - 'tests': ['dromaeo_dom']}, 16:41:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:41:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:41:16 INFO - 'tests': ['basic_compositor_video']}, 16:41:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:41:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:41:16 INFO - 'tests': ['a11yr', 16:41:16 INFO - 'ts_paint', 16:41:16 INFO - 'tpaint', 16:41:16 INFO - 'sessionrestore', 16:41:16 INFO - 'sessionrestore_no_auto_restore', 16:41:16 INFO - 'tabpaint']}, 16:41:16 INFO - 'other-e10s': {'tests': ['a11yr', 16:41:16 INFO - 'ts_paint', 16:41:16 INFO - 'tpaint', 16:41:16 INFO - 'sessionrestore', 16:41:16 INFO - 'sessionrestore_no_auto_restore', 16:41:16 INFO - 'tabpaint']}, 16:41:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:41:16 INFO - 'ts_paint', 16:41:16 INFO - 'tpaint', 16:41:16 INFO - 'sessionrestore', 16:41:16 INFO - 'sessionrestore_no_auto_restore', 16:41:16 INFO - 'tabpaint']}, 16:41:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:41:16 INFO - 'ts_paint', 16:41:16 INFO - 'tpaint', 16:41:16 INFO - 'sessionrestore', 16:41:16 INFO - 'sessionrestore_no_auto_restore', 16:41:16 INFO - 'tabpaint']}, 16:41:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:41:16 INFO - 'tests': ['a11yr', 16:41:16 INFO - 'ts_paint', 16:41:16 INFO - 'tpaint', 16:41:16 INFO - 'sessionrestore', 16:41:16 INFO - 'sessionrestore_no_auto_restore', 16:41:16 INFO - 'tabpaint']}, 16:41:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:41:16 INFO - 'tests': ['a11yr', 16:41:16 INFO - 'ts_paint', 16:41:16 INFO - 'tpaint', 16:41:16 INFO - 'sessionrestore', 16:41:16 INFO - 'sessionrestore_no_auto_restore', 16:41:16 INFO - 'tabpaint']}, 16:41:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:41:16 INFO - 'tests': ['tsvgx', 16:41:16 INFO - 'tsvgr_opacity', 16:41:16 INFO - 'tart', 16:41:16 INFO - 'tscrollx', 16:41:16 INFO - 'cart']}, 16:41:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:41:16 INFO - 'tsvgr_opacity', 16:41:16 INFO - 'tart', 16:41:16 INFO - 'tscrollx', 16:41:16 INFO - 'cart']}, 16:41:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:41:16 INFO - 'talos_options': ['--disable-e10s'], 16:41:16 INFO - 'tests': ['tp5o']}, 16:41:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:41:16 INFO - 'tests': ['tp5o']}, 16:41:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:41:16 INFO - 'talos_options': ['--disable-e10s', 16:41:16 INFO - '--xperf_path', 16:41:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:41:16 INFO - 'tests': ['tp5n']}, 16:41:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:41:16 INFO - 'talos_options': ['--xperf_path', 16:41:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:41:16 INFO - 'tests': ['tp5n']}}, 16:41:16 INFO - 'talos.zip': {'path': '', 16:41:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:41:16 INFO - Running post-action listener: _resource_record_post_action 16:41:16 INFO - [mozharness: 2016-10-14 23:41:16.237846Z] Finished populate-webroot step (success) 16:41:16 INFO - [mozharness: 2016-10-14 23:41:16.237965Z] Running create-virtualenv step. 16:41:16 INFO - Running pre-action listener: _resource_record_pre_action 16:41:16 INFO - Running main action method: create_virtualenv 16:41:16 INFO - Creating virtualenv /builds/slave/test/build/venv 16:41:16 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:41:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:41:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:41:16 INFO - Using real prefix '/tools/python27' 16:41:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:41:17 INFO - Installing distribute.............................................................................................................................................................................................done. 16:41:19 INFO - Installing pip.................done. 16:41:19 INFO - Return code: 0 16:41:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:41:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:41:20 INFO - Reading from file tmpfile_stdout 16:41:20 INFO - Output received: 16:41:20 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 16:41:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:41: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')]} 16:41:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41: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')]} 16:41:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10802c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107fa2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa48a53c270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e40d30>, '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 0x107c66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e38b20>, '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.dtA442Gpcb/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.qnZMAoedMX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:41: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', 'pip>=1.5'] in /builds/slave/test/build 16:41: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 pip>=1.5 16:41:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dtA442Gpcb/Render', 16:41:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:41:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:41:20 INFO - 'HOME': '/Users/cltbld', 16:41:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:41:20 INFO - 'LOGNAME': 'cltbld', 16:41:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:20 INFO - 'MOZ_NO_REMOTE': '1', 16:41:20 INFO - 'NO_EM_RESTART': '1', 16:41:20 INFO - 'PAGER': '/bin/cat', 16:41:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:41:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:20 INFO - 'PWD': '/builds/slave/test', 16:41:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:41:20 INFO - 'SHELL': '/bin/bash', 16:41:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qnZMAoedMX/Listeners', 16:41:20 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 16:41:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:41:20 INFO - 'USER': 'cltbld', 16:41:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:41:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:41:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:20 INFO - 'XPC_FLAGS': '0x0', 16:41:20 INFO - 'XPC_SERVICE_NAME': '0', 16:41:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:41:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:20 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:41:20 INFO - Cleaning up... 16:41:20 INFO - Return code: 0 16:41:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:41: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')]} 16:41:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41: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')]} 16:41:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41: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', '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 0x10802c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107fa2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa48a53c270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e40d30>, '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 0x107c66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e38b20>, '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.dtA442Gpcb/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.qnZMAoedMX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:41: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', 'psutil>=3.1.1'] in /builds/slave/test/build 16:41: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 psutil>=3.1.1 16:41:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dtA442Gpcb/Render', 16:41:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:41:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:41:20 INFO - 'HOME': '/Users/cltbld', 16:41:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:41:20 INFO - 'LOGNAME': 'cltbld', 16:41:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:20 INFO - 'MOZ_NO_REMOTE': '1', 16:41:20 INFO - 'NO_EM_RESTART': '1', 16:41:20 INFO - 'PAGER': '/bin/cat', 16:41:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:41:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:20 INFO - 'PWD': '/builds/slave/test', 16:41:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:41:20 INFO - 'SHELL': '/bin/bash', 16:41:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qnZMAoedMX/Listeners', 16:41:20 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 16:41:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:41:20 INFO - 'USER': 'cltbld', 16:41:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:41:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:41:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:20 INFO - 'XPC_FLAGS': '0x0', 16:41:20 INFO - 'XPC_SERVICE_NAME': '0', 16:41:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:41:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:20 INFO - Downloading/unpacking psutil>=3.1.1 16:41: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 16:41: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 16:41: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 16:41: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 16:41: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 16:41: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 16:41:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:41:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:41:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:41:24 INFO - Installing collected packages: psutil 16:41:24 INFO - Running setup.py install for psutil 16:41:24 INFO - building 'psutil._psutil_osx' extension 16:41:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 16:41:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 16:41:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 16:41:25 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 16:41:25 INFO - building 'psutil._psutil_posix' extension 16:41:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 16:41:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:41:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:41:25 INFO - ^ 16:41:25 INFO - 1 warning generated. 16:41:25 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 16:41:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:41:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:41:25 INFO - Successfully installed psutil 16:41:25 INFO - Cleaning up... 16:41:25 INFO - Return code: 0 16:41:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:41:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:41:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:41:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:25 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 0x10802c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107fa2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa48a53c270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e40d30>, '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 0x107c66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e38b20>, '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.dtA442Gpcb/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.qnZMAoedMX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:41:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:41:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:41:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dtA442Gpcb/Render', 16:41:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:41:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:41:25 INFO - 'HOME': '/Users/cltbld', 16:41:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:41:25 INFO - 'LOGNAME': 'cltbld', 16:41:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:25 INFO - 'MOZ_NO_REMOTE': '1', 16:41:25 INFO - 'NO_EM_RESTART': '1', 16:41:25 INFO - 'PAGER': '/bin/cat', 16:41:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:41:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:25 INFO - 'PWD': '/builds/slave/test', 16:41:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:41:25 INFO - 'SHELL': '/bin/bash', 16:41:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qnZMAoedMX/Listeners', 16:41:25 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 16:41:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:41:25 INFO - 'USER': 'cltbld', 16:41:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:41:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:41:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:25 INFO - 'XPC_FLAGS': '0x0', 16:41:25 INFO - 'XPC_SERVICE_NAME': '0', 16:41:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:41:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:41:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:41:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:41:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:41:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:41:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:41:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:41:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:41:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:41:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:41:28 INFO - Installing collected packages: mozsystemmonitor 16:41:28 INFO - Running setup.py install for mozsystemmonitor 16:41:28 INFO - Successfully installed mozsystemmonitor 16:41:28 INFO - Cleaning up... 16:41:28 INFO - Return code: 0 16:41:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:41:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:41:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:41:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10802c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107fa2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa48a53c270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e40d30>, '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 0x107c66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e38b20>, '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.dtA442Gpcb/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.qnZMAoedMX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:41:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:41:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:41:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dtA442Gpcb/Render', 16:41:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:41:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:41:28 INFO - 'HOME': '/Users/cltbld', 16:41:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:41:28 INFO - 'LOGNAME': 'cltbld', 16:41:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:28 INFO - 'MOZ_NO_REMOTE': '1', 16:41:28 INFO - 'NO_EM_RESTART': '1', 16:41:28 INFO - 'PAGER': '/bin/cat', 16:41:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:41:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:28 INFO - 'PWD': '/builds/slave/test', 16:41:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:41:28 INFO - 'SHELL': '/bin/bash', 16:41:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qnZMAoedMX/Listeners', 16:41:28 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 16:41:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:41:28 INFO - 'USER': 'cltbld', 16:41:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:41:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:41:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:28 INFO - 'XPC_FLAGS': '0x0', 16:41:28 INFO - 'XPC_SERVICE_NAME': '0', 16:41:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:41:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:28 INFO - Downloading/unpacking jsonschema==2.5.1 16:41:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:41:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:41:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:41:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:41:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:41:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:41:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:41:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:41:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:41:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:41:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:41:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:41:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:41:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:41:31 INFO - Downloading functools32-3.2.3-2.tar.gz 16:41:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:41:31 INFO - warning: no files found matching '*.txt' 16:41:31 INFO - no previously-included directories found matching 'build' 16:41:31 INFO - no previously-included directories found matching 'dist' 16:41:31 INFO - no previously-included directories found matching '.git*' 16:41:31 INFO - Installing collected packages: jsonschema, functools32 16:41:31 INFO - Running setup.py install for functools32 16:41:32 INFO - warning: no files found matching '*.txt' 16:41:32 INFO - no previously-included directories found matching 'build' 16:41:32 INFO - no previously-included directories found matching 'dist' 16:41:32 INFO - no previously-included directories found matching '.git*' 16:41:32 INFO - Successfully installed jsonschema functools32 16:41:32 INFO - Cleaning up... 16:41:32 INFO - Return code: 0 16:41:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:41:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:41:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:41:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:32 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 0x10802c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107fa2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa48a53c270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e40d30>, '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 0x107c66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e38b20>, '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.dtA442Gpcb/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.qnZMAoedMX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:41:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:41:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:41:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dtA442Gpcb/Render', 16:41:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:41:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:41:32 INFO - 'HOME': '/Users/cltbld', 16:41:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:41:32 INFO - 'LOGNAME': 'cltbld', 16:41:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:32 INFO - 'MOZ_NO_REMOTE': '1', 16:41:32 INFO - 'NO_EM_RESTART': '1', 16:41:32 INFO - 'PAGER': '/bin/cat', 16:41:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:41:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:32 INFO - 'PWD': '/builds/slave/test', 16:41:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:41:32 INFO - 'SHELL': '/bin/bash', 16:41:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qnZMAoedMX/Listeners', 16:41:32 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 16:41:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:41:32 INFO - 'USER': 'cltbld', 16:41:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:41:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:41:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:32 INFO - 'XPC_FLAGS': '0x0', 16:41:32 INFO - 'XPC_SERVICE_NAME': '0', 16:41:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:41:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:41:32 INFO - Cleaning up... 16:41:32 INFO - Return code: 0 16:41:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:41:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:41:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:41:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:32 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 0x10802c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107fa2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa48a53c270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e40d30>, '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 0x107c66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e38b20>, '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.dtA442Gpcb/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.qnZMAoedMX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:41:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:41:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:41:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dtA442Gpcb/Render', 16:41:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:41:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:41:32 INFO - 'HOME': '/Users/cltbld', 16:41:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:41:32 INFO - 'LOGNAME': 'cltbld', 16:41:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:32 INFO - 'MOZ_NO_REMOTE': '1', 16:41:32 INFO - 'NO_EM_RESTART': '1', 16:41:32 INFO - 'PAGER': '/bin/cat', 16:41:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:41:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:32 INFO - 'PWD': '/builds/slave/test', 16:41:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:41:32 INFO - 'SHELL': '/bin/bash', 16:41:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qnZMAoedMX/Listeners', 16:41:32 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 16:41:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:41:32 INFO - 'USER': 'cltbld', 16:41:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:41:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:41:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:32 INFO - 'XPC_FLAGS': '0x0', 16:41:32 INFO - 'XPC_SERVICE_NAME': '0', 16:41:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:41:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:32 INFO - Downloading/unpacking blobuploader==1.2.4 16:41:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:41:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:41:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:41:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:41:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:41:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:41:35 INFO - Downloading blobuploader-1.2.4.tar.gz 16:41:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:41:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:41: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 16:41: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 16:41: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 16:41: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 16:41: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 16:41: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 16:41:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:41:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:41:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:41:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:41:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:41:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:41:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:41:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:41:36 INFO - Downloading docopt-0.6.1.tar.gz 16:41:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:41:36 INFO - Installing collected packages: blobuploader, requests, docopt 16:41:36 INFO - Running setup.py install for blobuploader 16:41:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:41:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:41:36 INFO - Running setup.py install for requests 16:41:37 INFO - Running setup.py install for docopt 16:41:37 INFO - Successfully installed blobuploader requests docopt 16:41:37 INFO - Cleaning up... 16:41:37 INFO - Return code: 0 16:41:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:41:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:41:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:41:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:37 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 0x10802c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107fa2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa48a53c270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e40d30>, '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 0x107c66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e38b20>, '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.dtA442Gpcb/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.qnZMAoedMX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:41:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:41:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:41:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dtA442Gpcb/Render', 16:41:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:41:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:41:37 INFO - 'HOME': '/Users/cltbld', 16:41:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:41:37 INFO - 'LOGNAME': 'cltbld', 16:41:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:37 INFO - 'MOZ_NO_REMOTE': '1', 16:41:37 INFO - 'NO_EM_RESTART': '1', 16:41:37 INFO - 'PAGER': '/bin/cat', 16:41:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:41:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:37 INFO - 'PWD': '/builds/slave/test', 16:41:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:41:37 INFO - 'SHELL': '/bin/bash', 16:41:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qnZMAoedMX/Listeners', 16:41:37 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 16:41:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:41:37 INFO - 'USER': 'cltbld', 16:41:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:41:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:41:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:37 INFO - 'XPC_FLAGS': '0x0', 16:41:37 INFO - 'XPC_SERVICE_NAME': '0', 16:41:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:41:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:41:37 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-1Z2wea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:41:38 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-hllTLX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:41:38 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-AxSF06-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:41:38 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-cx2OWU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:41:38 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-HDxlXl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:41:38 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-rmA1TC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:41:38 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-DVtlZA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:41:38 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-rjeZDe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:41:38 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-W7hrNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:41:38 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-b0qGcB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:41:38 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-TkR0t7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:41:39 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-h6owUM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:41:39 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-xnOtW7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:41:39 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-ccYFJZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:41:39 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-lIyYIj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:41:39 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-wFPNxR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:41:39 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-Xu5WzJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:41:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:41:39 INFO - Running setup.py install for manifestparser 16:41:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:41:39 INFO - Running setup.py install for mozcrash 16:41:39 INFO - Running setup.py install for mozdebug 16:41:40 INFO - Running setup.py install for mozdevice 16:41:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:41:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:41:40 INFO - Running setup.py install for mozfile 16:41:40 INFO - Running setup.py install for mozhttpd 16:41:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:41:40 INFO - Running setup.py install for mozinfo 16:41:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:41:40 INFO - Running setup.py install for mozInstall 16:41:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:41:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:41:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:41:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:41:40 INFO - Running setup.py install for mozleak 16:41:41 INFO - Running setup.py install for mozlog 16:41:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:41:41 INFO - Running setup.py install for moznetwork 16:41:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:41:41 INFO - Running setup.py install for mozprocess 16:41:41 INFO - Running setup.py install for mozprofile 16:41:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:41:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:41:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:41:41 INFO - Running setup.py install for mozrunner 16:41:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:41:41 INFO - Running setup.py install for mozscreenshot 16:41:42 INFO - Running setup.py install for moztest 16:41:42 INFO - Running setup.py install for mozversion 16:41:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:41:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:41:42 INFO - Cleaning up... 16:41:42 INFO - Return code: 0 16:41:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:41:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:41:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:41:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:42 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 0x10802c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107fa2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa48a53c270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e40d30>, '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 0x107c66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e38b20>, '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.dtA442Gpcb/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.qnZMAoedMX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:41:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:41:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:41:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dtA442Gpcb/Render', 16:41:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:41:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:41:42 INFO - 'HOME': '/Users/cltbld', 16:41:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:41:42 INFO - 'LOGNAME': 'cltbld', 16:41:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:42 INFO - 'MOZ_NO_REMOTE': '1', 16:41:42 INFO - 'NO_EM_RESTART': '1', 16:41:42 INFO - 'PAGER': '/bin/cat', 16:41:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:41:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:42 INFO - 'PWD': '/builds/slave/test', 16:41:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:41:42 INFO - 'SHELL': '/bin/bash', 16:41:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qnZMAoedMX/Listeners', 16:41:42 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 16:41:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:41:42 INFO - 'USER': 'cltbld', 16:41:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:41:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:41:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:42 INFO - 'XPC_FLAGS': '0x0', 16:41:42 INFO - 'XPC_SERVICE_NAME': '0', 16:41:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:41:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:41:42 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-fML8Tv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:41:42 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:41:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:41:42 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-6NNP6s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:41:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:41:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:41:42 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-LT9DG8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:41:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:41:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:41:42 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-KWm9QL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:41:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:41:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:41:43 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-dzzj80-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:41:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:41:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:41:43 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-VQ7B1x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:41:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:41:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:41:43 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-CDD5BD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:41:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:41:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:41:43 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-VRHpzk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:41:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:41:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:41:43 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-S3OkhP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:41:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:41:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:41:43 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-B9s8Ws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:41:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:41:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:41:43 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-gJFRHQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:41:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:41:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:41:43 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-1Bz6s5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:41:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:41:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:41:43 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-U87lnb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:41:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:41:44 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-1eYIHt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:41:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:41:44 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-g08ZSw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:41:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:41:44 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-sToDvU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:41:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:41:44 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-R_I4cD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:41:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:41:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:41:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:41:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:41:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:41:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:41:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:41:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:41:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:41:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:41:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:41:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:41:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:41:46 INFO - Downloading blessings-1.6.tar.gz 16:41:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:41:47 INFO - Installing collected packages: blessings 16:41:47 INFO - Running setup.py install for blessings 16:41:47 INFO - Successfully installed blessings 16:41:47 INFO - Cleaning up... 16:41:47 INFO - Return code: 0 16:41:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:41:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:41:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:41:47 INFO - Reading from file tmpfile_stdout 16:41:47 INFO - Current package versions: 16:41:47 INFO - blessings == 1.6 16:41:47 INFO - blobuploader == 1.2.4 16:41:47 INFO - docopt == 0.6.1 16:41:47 INFO - functools32 == 3.2.3-2 16:41:47 INFO - jsonschema == 2.5.1 16:41:47 INFO - manifestparser == 1.1 16:41:47 INFO - mozInstall == 1.12 16:41:47 INFO - mozcrash == 0.17 16:41:47 INFO - mozdebug == 0.1 16:41:47 INFO - mozdevice == 0.48 16:41:47 INFO - mozfile == 1.2 16:41:47 INFO - mozhttpd == 0.7 16:41:47 INFO - mozinfo == 0.9 16:41:47 INFO - mozleak == 0.1 16:41:47 INFO - mozlog == 3.4 16:41:47 INFO - moznetwork == 0.27 16:41:47 INFO - mozprocess == 0.23 16:41:47 INFO - mozprofile == 0.28 16:41:47 INFO - mozrunner == 6.12 16:41:47 INFO - mozscreenshot == 0.1 16:41:47 INFO - mozsystemmonitor == 0.3 16:41:47 INFO - moztest == 0.7 16:41:47 INFO - mozversion == 1.4 16:41:47 INFO - psutil == 3.1.1 16:41:47 INFO - requests == 1.2.3 16:41:47 INFO - wsgiref == 0.1.2 16:41:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:41:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:41:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:41:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:48 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 0x10802c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107fa2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa48a53c270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e40d30>, '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 0x107c66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e38b20>, '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.dtA442Gpcb/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.qnZMAoedMX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:41:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:41:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:41:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dtA442Gpcb/Render', 16:41:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:41:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:41:48 INFO - 'HOME': '/Users/cltbld', 16:41:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:41:48 INFO - 'LOGNAME': 'cltbld', 16:41:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:48 INFO - 'MOZ_NO_REMOTE': '1', 16:41:48 INFO - 'NO_EM_RESTART': '1', 16:41:48 INFO - 'PAGER': '/bin/cat', 16:41:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:41:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:48 INFO - 'PWD': '/builds/slave/test', 16:41:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:41:48 INFO - 'SHELL': '/bin/bash', 16:41:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qnZMAoedMX/Listeners', 16:41:48 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 16:41:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:41:48 INFO - 'USER': 'cltbld', 16:41:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:41:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:41:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:48 INFO - 'XPC_FLAGS': '0x0', 16:41:48 INFO - 'XPC_SERVICE_NAME': '0', 16:41:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:41:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:41:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:41:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:41:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:41:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:41:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:41:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:41:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:41:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:41:48 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:41:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:41:48 INFO - Cleaning up... 16:41:48 INFO - Return code: 0 16:41:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:41:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:41:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:41:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:41:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:41:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:41:48 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 0x10802c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107fa2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa48a53c270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e40d30>, '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 0x107c66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e38b20>, '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.dtA442Gpcb/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.qnZMAoedMX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:41:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:41:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:41:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dtA442Gpcb/Render', 16:41:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:41:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:41:48 INFO - 'HOME': '/Users/cltbld', 16:41:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:41:48 INFO - 'LOGNAME': 'cltbld', 16:41:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:48 INFO - 'MOZ_NO_REMOTE': '1', 16:41:48 INFO - 'NO_EM_RESTART': '1', 16:41:48 INFO - 'PAGER': '/bin/cat', 16:41:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:41:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:48 INFO - 'PWD': '/builds/slave/test', 16:41:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:41:48 INFO - 'SHELL': '/bin/bash', 16:41:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qnZMAoedMX/Listeners', 16:41:48 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 16:41:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:41:48 INFO - 'USER': 'cltbld', 16:41:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:41:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:41:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:48 INFO - 'XPC_FLAGS': '0x0', 16:41:48 INFO - 'XPC_SERVICE_NAME': '0', 16:41:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:41:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:41:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:41:49 INFO - Cleaning up... 16:41:49 INFO - Return code: 0 16:41:49 INFO - Running post-action listener: _resource_record_post_action 16:41:49 INFO - Running post-action listener: _start_resource_monitoring 16:41:49 INFO - Starting resource monitoring. 16:41:49 INFO - [mozharness: 2016-10-14 23:41:49.289925Z] Finished create-virtualenv step (success) 16:41:49 INFO - [mozharness: 2016-10-14 23:41:49.290877Z] Running install step. 16:41:49 INFO - Running pre-action listener: _resource_record_pre_action 16:41:49 INFO - Running main action method: install 16:41:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:41:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:41:49 INFO - Reading from file tmpfile_stdout 16:41:49 INFO - Detecting whether we're running mozinstall >=1.0... 16:41:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:41:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:41:49 INFO - Reading from file tmpfile_stdout 16:41:49 INFO - Output received: 16:41:49 INFO - Usage: mozinstall [options] installer 16:41:49 INFO - Options: 16:41:49 INFO - -h, --help show this help message and exit 16:41:49 INFO - -d DEST, --destination=DEST 16:41:49 INFO - Directory to install application into. [default: 16:41:49 INFO - "/builds/slave/test"] 16:41:49 INFO - --app=APP Application being installed. [default: firefox] 16:41:49 INFO - mkdir: /builds/slave/test/build/application 16:41:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:41:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 16:42:24 INFO - Reading from file tmpfile_stdout 16:42:24 INFO - Output received: 16:42:24 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:42:24 INFO - Running post-action listener: _resource_record_post_action 16:42:24 INFO - [mozharness: 2016-10-14 23:42:24.200472Z] Finished install step (success) 16:42:24 INFO - [mozharness: 2016-10-14 23:42:24.200590Z] Running run-tests step. 16:42:24 INFO - Running pre-action listener: _resource_record_pre_action 16:42:24 INFO - Running main action method: run_tests 16:42:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:42:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:42:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:42:24 INFO - 2016-10-14 16:42:24.215 screenresolution[1639:7411] starting screenresolution argv=screenresolution get 16:42:24 INFO - 2016-10-14 16:42:24.217 screenresolution[1639:7411] Display 0: 1600x1200x32@60 16:42:24 INFO - 2016-10-14 16:42:24.223 screenresolution[1640:7413] starting screenresolution argv=screenresolution list 16:42:24 INFO - Available Modes on Display 0 16:42:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:42:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:42:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:42:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:42:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:42:24 INFO - Intel Iris: 16:42:24 INFO - Chipset Model: Intel Iris 16:42:24 INFO - Type: GPU 16:42:24 INFO - Bus: Built-In 16:42:24 INFO - VRAM (Dynamic, Max): 1536 MB 16:42:24 INFO - Vendor: Intel (0x8086) 16:42:24 INFO - Device ID: 0x0a2e 16:42:24 INFO - Revision ID: 0x0009 16:42:24 INFO - Displays: 16:42:24 INFO - Display: 16:42:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:42:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:42:24 INFO - Display Serial Number: 1600x1200 60 16:42:24 INFO - Main Display: Yes 16:42:24 INFO - Mirror: Off 16:42:24 INFO - Online: Yes 16:42:24 INFO - Rotation: Supported 16:42:24 INFO - Return code: 0 16:42:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:42:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:42:24 INFO - Python 2.7.3 16:42:24 INFO - Return code: 0 16:42:24 INFO - grabbing minidump binary from tooltool 16:42: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')]} 16:42:24 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 0x107e40d30>, '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 0x107c66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e38b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:42:24 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:42:24 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 16:42:24 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 16:42:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:42:24 INFO - Return code: 0 16:42:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:42:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:42:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:42:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:42:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:42:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/P105I_29Tpe0ivLowzCZsw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0349', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:42:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/P105I_29Tpe0ivLowzCZsw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0349 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:42:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dtA442Gpcb/Render', 16:42:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:42:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:42:24 INFO - 'HOME': '/Users/cltbld', 16:42:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:42:24 INFO - 'LOGNAME': 'cltbld', 16:42:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:42:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:42:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:42:24 INFO - 'MOZ_NO_REMOTE': '1', 16:42:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:42:24 INFO - 'NO_EM_RESTART': '1', 16:42:24 INFO - 'PAGER': '/bin/cat', 16:42:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:42:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:42:24 INFO - 'PWD': '/builds/slave/test', 16:42:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:42:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:42:24 INFO - 'SHELL': '/bin/bash', 16:42:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qnZMAoedMX/Listeners', 16:42:24 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 16:42:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:42:24 INFO - 'USER': 'cltbld', 16:42:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:42:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:42:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:42:24 INFO - 'XPC_FLAGS': '0x0', 16:42:24 INFO - 'XPC_SERVICE_NAME': '0', 16:42:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:42:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/P105I_29Tpe0ivLowzCZsw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0349', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:42:24 INFO - mozversion application_buildid: 20161014145136 16:42:24 INFO - mozversion application_changeset: 9696d132b1691d15ff845550d13ef9a6cfc78049 16:42:24 INFO - mozversion application_display_name: Nightly 16:42:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:42:24 INFO - mozversion application_name: Firefox 16:42:24 INFO - mozversion application_remotingname: firefox 16:42:24 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 16:42:24 INFO - mozversion application_vendor: Mozilla 16:42:24 INFO - mozversion application_version: 52.0a1 16:42:24 INFO - mozversion platform_buildid: 20161014145136 16:42:24 INFO - mozversion platform_changeset: 9696d132b1691d15ff845550d13ef9a6cfc78049 16:42:24 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 16:42:24 INFO - mozversion platform_version: 52.0a1 16:42:24 INFO - using testdate: 1476488544 16:42:24 INFO - actual date: 1476488544 16:42:24 INFO - starting webserver on 'localhost:49273' 16:42:24 INFO - SUITE-START | Running 2 tests 16:42:24 INFO - TEST-START | dromaeo_css 16:42:24 INFO - operating with platform_type : mac_ 16:42:24 INFO - Initialising browser for dromaeo_css test... 16:42:24 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/tmpJm3kjz/profile http://localhost:49273/getInfo.html) 16:42:27 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 16:42:27 INFO - PROCESS | 1650 | colorDepth:24 16:42:27 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 16:42:27 INFO - PROCESS | 1650 | __metrics 16:42:27 INFO - PROCESS | 1650 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:42:27 INFO - PROCESS | 1650 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:42:27 INFO - TEST-INFO | 1650: exit 0 16:42:27 INFO - Browser initialized. 16:42:27 INFO - Running cycle 1/1 for dromaeo_css test... 16:42:27 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/tmpJm3kjz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:42:39 INFO - PROCESS | 1652 | RSS: Main: 239644672 16:42:39 INFO - PROCESS | 1652 | 16:43:31 INFO - PROCESS | 1652 | 1476488611568 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 16:45:44 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 16:45:46 INFO - PROCESS | 1652 | RSS: Main: 395120640 16:45:46 INFO - PROCESS | 1652 | 16:48:49 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 16:48:50 INFO - PROCESS | 1652 | RSS: Main: 367210496 16:48:50 INFO - PROCESS | 1652 | 16:51:52 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 16:51:53 INFO - PROCESS | 1652 | RSS: Main: 338661376 16:51:53 INFO - PROCESS | 1652 | 16:54:55 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 16:54:56 INFO - PROCESS | 1652 | RSS: Main: 345579520 16:54:56 INFO - PROCESS | 1652 | 16:57:59 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 16:58:00 INFO - PROCESS | 1652 | RSS: Main: 331206656 16:58:00 INFO - PROCESS | 1652 | 17:01:03 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 17:01:03 INFO - PROCESS | 1652 | RSS: Main: 387977216 17:01:03 INFO - PROCESS | 1652 | 17:01:03 INFO - PROCESS | 1652 | __start_tp_report 17:01:03 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 17:01:03 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 17:01:03 INFO - PROCESS | 1652 | |i|pagename|runs| 17:01:03 INFO - PROCESS | 1652 | |0;dojo.html;3181.347823696142;3314.1051915982684;3331.3004269103485;3336.5662463879694;3362.4028586421455;5480.6437581557175;5581.804636837712;5606.439356064391;5653.943460565393;5707.5148612368;5568.16477528371;5591.4688104630095;5629.296337957755;5653.265075540185;5704.258446401972;5093.847184584469;5141.537261646451;5176.404713457944;5205.687658740469;5217.139172036609;5117.513836185556;5235.188545775411;5243.737813109348;5255.316808814849;5263.815766448175;5227.660202086852;5254.080535906221;5258;5266.605004624668;5277.683338999653;5093.490650934914;5131.358580177478;5208.5051920067535;5211.114110601188;5214.869628259324;5088.033273677989;5092.261622064817;5110.28456016158;5120.436751957282;5154.819581314673;5098.209777484496;5193.597728613253;5470.671759694431;5492.121260598325;5514.117741161395;6153.661548614825;6164.106204600308;6168.814935551941;6172.506199504029;6180.042093475518;4898.853034408973;6049.304330002062;6110.572259941806;6131.65643559416;6198.659073750942;5715.685637289906;5725.341585717612;5736.569757268222;5736.856578585569;5743.913841292384;6311.368863113744;6312.400321969406;6315;6327.873442531216;6334.968325158345;5839.766409343579;5887.411258874078;5907.1139329100115;6064.120702498113;6083.787067452707;6090.36051214625;6109.205803245639;6178.474829639531;6190.195274614362;6213.378662133841;6087.017013833646;6103.701349646989;6139.807300203869;6144.662043587646;6150.985087460519;6079.546018234662;6138.610893211376;6160.1375807387;6162.784302549389;6169.691515424211;4115.73247738896;4782.68912520692;4792.0895029944195;4834.468208497063;4910.238912968495;4308.18144552534;4325.199838029975;4326.718763280377;4351.651867850627;4389.956100438954;5584.022796010682;5590.273264475673;5601.60788744784;5605.495505404533;5608.383077861432;5981.970090149522;6096.512279017668;6131.601445906091;6163.691815409212;6164.784232551929;5615.298087739033;5660.264165658438;5672.177534257592;5707.429257074259;5749.310082790092;5308.0710875596615;5357.142857142839;5357.919631205535;5370.4629537045985;5380.24676545284;6065.3934606539;6248.875022499525;6274.6548939808745;6280;6305.432511074026;5290.841274761763;6106.2179897963915;6122.755089796358;6145.477634400947;6165.907511387243;6259.906101408574;6265.843353916006;6285.842853928689;6291.1192433058495;6295.779647712308;6067.635941843503;6074.36219196978;6141.9078713818435;6150.10823430609;6161.630302181883;6060.393960603904;6131.448169664752;6216.658083805434;6225.097360882737;6246.937530624818;5917.201177841109;5956.731947062306;5989.550783691154;6059.484943779652;6098.817035488943;5796.362400135898;6014.127951447103;6022.036474164288;6034.366391529002;6039.4262545058145;5680.147977803363;5780.826575202751;5787.710614469344;5805.680687562224;5858.912116318344;5848.853778655568;5889.823305300848;5919.31927828288;5923.348431672599;6025.126356678345;5057.468965758542;5625.971870140623;5680.801171958961;5790.826275211751;5838.211841401527 17:01:03 INFO - PROCESS | 1652 | |1;ext.html;454.6658206218429;476.44256220222337;479.238011561617;510.40282869043216;556.6187161794169;30228.395432091373;30717.539236911463;40042.399364009565;41016.79491602538;43206.91982700426;42199;42624.57375426245;42634.14731705364;42691.932701682475;42779.78610106953;9355.812883742321;9509.524523773818;9525.237980961507;9530.952345238264;9538.284628652844;9425.764355891124;9528.237740980705;9529;9545.713628591153;9556.23550115989;9501.429914205135;9563.282753793459;9566.617335306613;9569.760755981086;9582.802149731284;9374.765630859249;9406.200472959807;9423.48170850603;9431.66989155383;9432.669856554987;9391.389559678599;9450.621975121056;9457.243420526342;9465.100815422524;9470.763230919214;40000.799996000125;40398.59601403978;40445.797771011254;40450;40525.79737101325;3363.5459213006175;3392;3396.677315655009;3463.774854634466;3870.806459677033;30570.38859222803;30831.383372332653;31234.53148202756;31393.52909706356;32024.359512809613;3155.7159855612877;3182.268078341994;3183.586133802614;3222.180219255483;3240.1575590346442;471084;473012;473268;473757;473848;59504.70247648821;59944.400555994755;60032.39967600268;60075.09887351693;60124.69937650371;34610.82694586511;34998.47502287468;35206.64793352085;64358.356416436174;64662;16688.499345019667;16825.074620895968;16847.578810529834;16858.241379137722;16861.2412441438;12751.42618582166;12802.9359853202;12821.35893205336;12828;12842.101052926206;17653.646927061385;17787.911060444614;19327.96032238066;19400.8059919399;19436.4169074928;7826.647800848976;7939.593525593235;7964.8805267921025;8015.238552337519;8268;848.6181218451721;850.2602735620051;1009.4347165587294;1023.9709092362277;1068.9144868410665;24615.982695547493;27633.585496217172;28558.000469983865;29591.556126658117;29688.703112969026;1028.5782829040206;1037.994810025945;1045.038564520643;1056.963006294776;1201.1831954271179;14682.853171468149;15133.243337833064;15282.31229594671;15472.922635386976;15598.064116153068;630094;635000;635454;636313;637081;57863.553411165056;58460.707696462094;58509;58548;58577;1230.2126638951079;1231.2243286729483;1378.7587172245017;1380.2615600653603;1400.0759498730993;26925.596116057885;27117.730977583255;31110.377792444477;31428.52857207098;31961.68038319587;45177.54822451865;49837.25244121269;50666;50820;50842.23736643879;466546;566607;567066.305358621;567218;568805;42029.78985105055;42497.78751106225;43146.784266078466;43382.698519044476;43405.914852128946;47261;47443.81390465266;48167;48255.51744482651;48546.75726621344;38022;39192.41211381774;39704.00739981524;40539.594604053585;40658.1868362637;38988.830335089995;39138.608613914635;39879.601203987586;39974.60025399709;40120.1975960485;37526;37564;37994.20057994178;38901.416478752275;38914;31594.842025789723;34337.79817706368;34570.50805452292;34729.47905781466;34742.610295587896;25500.617490738026;25608.8719556401;25704.61443078318;25915.481690366465;26175 17:01:03 INFO - PROCESS | 1652 | |2;jquery.html;349.4600841699574;367.8050633164423;370.6238168259217;375.24201113750223;399.6343345838558;14435.494757683484;15213.315400806963;20284.436001899565;20298.594028119427;21067;10185.643502477415;11991.400429978488;15156.090634561933;20963.895180524076;21248.36254912355;4148;5193.688378697281;5303.920441193366;5353.223782551528;5379.65032272901;3934.9803250983705;4139.503259608836;4143.8190115817;4183.393407955845;5426.67439953604;4146.834126634946;4190.5809419058005;4191.853285135021;4192.895177620553;4655.20861453552;4389.978050109745;6688.264290927994;6739.090222819906;6778.525503214777;6781.11845460092;4417.734935903856;5276.762545685453;5324.520793128637;5889.558283128739;5981.192539007266;12006.69983250417;12206.450709718085;12207.694807629792;12282.14025018249;21538.892305538528;1986.2948653228184;2008.2368699893948;2143.1213202586864;2241.8766967816764;2610.7258737832635;9847.16299114576;10283.742906427324;10496.685099446953;12270.38648067602;14867.275989404003;1704.4630941253465;1711.0589175953176;1899.3162461513843;1903.2196799312353;1933.419974007806;101425.49287253516;114611;117839.4108029463;119615.60768784663;120314;10068.949655251823;11073.300653104916;11420.086393088533;11496.42517874103;11568.88431115695;12596.307203103734;13239.66900827487;13273.66815829612;13356.732865342834;14160;6919.930800692029;8342.624581893708;8566.657333706706;8692.826143477096;8726.425704417212;7685.077790665156;7772.18392068836;8640.846091176676;8669.523176225368;8815.250703690259;4392.978035109804;4475.1049790042125;4478.484974228006;4554.7039442436135;4565.908681826345;1849.8797578157646;1897.383350411122;1899.7435346228358;1905.8951757653463;1907.3515004898125;791.6620910660964;808.7614153824636;883.2051153961486;943.4669411782321;944.5938246554048;17006.829931700773;20102.296419625534;21009.579808403745;21598.920053997237;21605.35183944484;846.3356265331685;916.7433118726767;954.8233576788316;960.2462067277154;978.5889926231;10898.727531811768;11271.154663400113;11494.94252528732;11516.769664606662;11622.94188529052;478475.60762195964;488970;490105;582538;590582;126019.91215518932;141072;141863.58136418503;144931;148425;420.98316067357575;445.1097804391218;445.6301269945937;446.0521392041911;447.2620176708457;10881.238313318308;11001.449927503752;11146.108311335236;11550.075993920298;11556.94221528887;53028.73485632701;54082.45917540932;56192.71903640619;56385.71807140938;57117.42882571121;75486.62256688681;77286;80336.99157521108;81444.77832832394;84781.57609211914;6304.085907543289;7558.470907036454;7905.881411778713;8325.25072743456;8383.329333653415;11853.822192667289;12606.873931260938;12636.620901372606;13074.477020919057;13140;10814.080803131506;11189.496472658588;11274.379909105079;11477.426128693698;11509.424528773361;8449.915500844912;10717.035466808025;10882.347059176158;11243.66269011931;11461.025812806005;8474.533900635524;11151.386673733023;11570.652880413601;11572.900574445413;11707.707307317385;7915.970923780102;8154.306883914934;8166.02007759049;8308.376871734765;8351.081381047958;9214;10094.848577271494;10121.746956325856;10785;11520.308781473139 17:01:03 INFO - PROCESS | 1652 | |3;mootools.html;1384.2109997301543;1541.5144229567686;1567.858892699657;1804.4406234067433;1960.0689672405608;7645.388368930486;8048.074471435787;8402.873956890644;8611.827763444728;8636.56817159141;8417.947756530433;8470.618822153003;8497.405181637288;8498.532580708074;8507.404481686284;2307.619242824932;2338.4037070546988;2338.485533182697;2340.637201233803;2343.484433424644;2296.7588403217674;2323.326235391743;2337.4623836517612;2339.263132113387;2341.2859077981293;2102.127617038927;2179.487820362214;2327.674125622406;2338.8362814602983;2339.6313156803208;2289.439087423575;2307.6423154411095;2308.7691230876944;2313.5372925414986;2321.5124823787023;2303.49323148907;2307.1578873710973;2307.5384923015463;2315.6642286868478;2318.5594736999915;8476.74569762902;8479.788005299855;8555.95722021392;8557.400981931267;8583.313334933253;5168.095583272912;5347.839564813062;5359.705216213107;5367.329083864517;5370.355557333106;3419.5212670226188;3591.2099338145567;3620.855165793365;3644.6355364463607;3648.854045838163;1039.9844002339971;1075.9462026898702;1077.7036315013434;1094.5020015892749;1097.7255686078481;11289.322640641587;11342.773144537063;11417.257878237888;11429.542818287342;11433.028192603639;4342.761148136819;4390.978045109818;4578.115787054201;4578.313253012075;4581.037982023746;8204.794880128045;8482.533460659599;8968.192862642394;8973.775655608531;8977.192052715287;5196.090684130338;5219.817306394258;5220.530152286236;5227.869303267449;5237.240600112962;4157.64660003897;4344.1311737652595;4520.434945631796;4534;4887.975560122177;3673.412254039341;3689.2805902849223;3739.700823934079;3762.360398732222;3766.773993560395;1151.2631915574038;1155.1163360029584;1156.4506859241792;1164.0745607242263;1167.2412931594363;779.3375630713848;804.1194891593696;897.0849733272026;899.8245342158216;900.0864122915116;5097.1844504879045;5520.309961254843;5787.479126878517;5843.2695913010875;5863.149843272787;408.6587699271142;417.9435776170239;420.36524847479916;423.1368009261073;424.11360257062887;3885.339492286318;3920.0591857953887;3947.585503522146;3956.6834653227675;4005.9799701001307;9508.524573771283;9851.704448866545;10138.341007834468;10488.73778155537;10505.789884202426;10092.293539452316;10435.791284174275;10443.11233545142;10460.424676642857;10506.422146782;7977.401694872908;7993.760187194393;8084.959575202204;8085.151059138823;8101.513909165469;5612.579056570692;5670.659760414388;5742.85142971399;5746.13807928814;5776.826695199151;9682.177014953808;9779.85330220033;9781.462019588991;9793.755156121155;9812.65655702047;8569.914300856912;9060.501672408082;9502.524873756147;9745.512724363613;9852.458114803754;6834.965825170842;7001.984712216802;7003.404710599656;7010.579365238102;7031.40233080194;7963.641636126273;7988.121306656147;8104.311133553714;8163.591820409075;8402.327813775006;6155.938440615537;7347.19180890091;7390.593517356597;7444.478886478111;7548.698052077855;7168;7246.6376681166785;7259.745908893164;7290.234525374972;7300.890486642811;6831;6948.9305106950305;6952.478564107611;6966.825829354307;6978.930210697828;4550.408446901882;4808.0624278266805;4975.875603109951;5033.2701758245585;5048.141815891234;4177.561356057692;4670.673052886265;4675.4155730533685;4779.617630589614;5001.624878134227 17:01:03 INFO - PROCESS | 1652 | |4;prototype.html;297.35919094351664;299.75719667069677;308.76071044940176;311.4083241840503;313.9905802825916;5152.33019707438;5251.763670634821;5257.7108259045735;5259.344031264216;5259.658122222053;4270.402143699885;4926.3842019747535;5002.549770520653;5012.674176178555;5241.947580524194;2968.079895232474;3633.8364773585254;3852.325842977482;3985.4021896715435;3987.3819557968563;3610.638936106395;3705.5923848376656;3958.663513601333;3960.4059391091278;3997.9800100999455;3078.9076327710204;3257.641659417462;3545.9113522161892;3960.861369852041;3972.6623237024887;3946.8223929923224;3955.764992779071;3960.1683646434285;3983.0042489377656;3993.4242413643037;2954.4091181763733;3029.242689327668;3359;3411.9317613647586;3422.6235114137426;4333.341657717934;5049.949500504985;5141.38303403594;5181.948180518184;5183.766730497136;4026.7382620129893;4353.91152211947;4446.265956277054;4487.0352874131895;4519.819207231707;4456.77716114193;5443.727813609344;5451.700156491392;5471.890562188773;5509.421510741355;3084.275195329096;3186.283086305586;3740.4576336431337;3764.8682296119778;3773.4528493368584;5006.994404476404;5410.377806552218;5480.546855878387;5655.228937820439;5740.478773125125;3168.3999580211844;3755.572882304761;3772.4718539404507;3794.449804778347;3794.658480736747;3080.86136123875;3121.859516321771;3725.7950812705562;3879.2241551689776;3894.571597124314;3809.8379994102065;4464.799084041226;4520.50274469808;4528.615067719281;4535.614472769786;4748.501407352247;5133.948660513422;5234.973825130926;5428.4842939920645;5486.780528778804;1108.2076315434504;1323.3383308345826;1324.059917458596;1324.9599064734136;1328.9401976911063;759.2786852490157;819.4837252530987;845.6617353058703;845.8815765792793;870.9433886797337;4949.109160351171;5082.491750824888;5148.639595228298;5152.124138896396;5164.406093299243;4297.978510107492;5482.506574408322;5556.86107847299;5598.1322894951345;5689.800856969986;4629.125204292267;4904.705717656952;4969.074431199312;5061.392632884003;5077.299104799933;2352.3060697094397;2631.6841978962266;2895.609092772491;2911.6706634692164;2919.7810164237767;4512.548745125461;5658.528782516575;5681.204631351614;5725.484706376446;5730;4606.286025666027;4929.2359684248995;5006.724630145304;5049.924251136236;5082.872928176826;4771.33201351811;5126.307948426989;5255.948810237967;5700.9429905701245;5706.288113565996;5148.639595228298;5440.564754819684;5481.780728770805;5493.423190564933;5518.641288316247;4753.453352864465;5405.729653531495;5471.316085489314;5482.342118945752;5495.527012844156;4972.676776009548;5003.599712023102;5666.9716651416475;5686;5723.3418156912485;2922.444735500248;3707.814609269472;3725.2735716535017;3726.2859084821284;3735.6451137141926;4997.600191984559;5338.919916201183;5551.583631227593;5625.324961004542;5653.943460565506;5471.671699698031;5479.780808767764;5489.286392768914;5491.670499770026;5502.394736578893;4787.8563643090765;5243.397009343974;5431.2667789847865;5449.7002664852225;5490.80782172622;4400.777607308289;4751.928721069117;5069.31564238823;5211.71335576547;5248.212768084818;4543.54564543543;5389.649672771257;5398.244245805512;5500.257465242301;5507.944920550904;4481.798319075584;5345.9465405347;5358.919616205838;5374.167004114369;5377.300950876361 17:01:03 INFO - PROCESS | 1652 | |5;yui.html;712.5973824788994;732.6263605561162;780.391294790064;789.4118881433333;836.6736972580691;12873.806892896602;15359.923200383997;15530.611734706637;15612.60968475786;15700.136492492908;14158.50445234417;14296.714065718705;14297.9991400602;14304.71390572188;14332.785008224859;620.2990620598717;693.8959156126583;711.2993701204309;716.8494616130619;717.5120917775911;861.5261606116642;882.3602887906248;883.2977782662766;885.4687187687368;889.4040992534984;922.8431166701677;933.7898972731149;938.3759799733169;939.0187254319253;939.0234156477255;931.5481991234246;933.351320832022;933.5705575435308;933.8272419602396;934.7569631895689;541.3098299667915;557.2866730584858;684.322520704499;684.7808701215613;685.8388278754488;11542;14616.123032618078;14659.63350916225;14963.476278330316;14965.952383333171;14138.50515231962;14765.335559899722;14847.109173449628;14885;14910.70178596422;11758.17692761507;11762.470688819023;11849.94075029628;11993.16047876649;12160.148789584733;3165.366926614663;3732.42147467143;3807.562130355017;3854.6338097880657;3910.667593254577;114317;115397;118337;119735;121134;10871.402072885909;10902.727431814268;10911.127109831194;11047.226694131494;11327.830082548768;22947.885260573923;22989.080436782675;23039.659249409076;23043.654345184837;23049.654255186186;6019.849503762441;7934.299956932626;8006.759797206093;8083.434159608889;8127.512349259064;4309.827606895752;4404.185225733249;4418.7127836690515;4502.077074199781;4554.294084416921;3586.9103272418397;3710.239400922804;3712.4431335299923;3715.869944551928;3716.572594151653;640.4172203294979;643.5881036136876;655.4756195043946;655.7082098466137;671.2750229751856;612.497751843484;673.5756473421811;686.6105951289541;691.3432239372617;706.4480701303054;7091.964540177266;8790.516521591373;8802.647894084164;9342.57958391874;9509.476978766234;585.5579037826413;587.9235699359056;611.7063809371438;675.8023302848231;703.2931903437013;4417.60241578253;4528.366028755977;4657.464391594942;4674.555917187861;4689.62483001359;141626.87559686613;162749.3725062758;165760.17119914564;169019.1549042247;170169;86649.5667521671;86837.13162868416;102668;103035.48482257691;103860.44209336866;69682.65895666076;71123;72479;102565;103698;9487.715368538955;9549.283803714889;9662.985386534312;9802.26483013791;9936.552855121392;50202;50439;55421.759016269236;59214;61919.452013700015;90731;92274;94560;96270.51864740631;97056.51471742596;11716.414179291172;11729.885660862205;12935;13196.60410187696;13329.933350333185;14708.779368309699;14870.628234294229;14904.701905962036;15283.235838208268;15347.693046139239;13927.512537061155;13981.930090349482;14344.784828227794;14576.416943322149;14630.85369146295;9975.700728978143;13942.721145577234;13962.720745585235;14222.288885555889;14275;12971.22172669605;13487.190768553526;13522.729545409233;13667.17996920188;14074.311082670145;11364.090872729996;11482.368469734245;11652.766944661229;11689.123315751183;11916.285022898308;10871.801016485222;11000.834987475035;11459.427028648368;11521.308721476738;11565.190436669352 17:01:03 INFO - PROCESS | 1652 | __end_tp_report 17:01:03 INFO - PROCESS | 1652 | __start_cc_report 17:01:03 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,1878 17:01:03 INFO - PROCESS | 1652 | __end_cc_report 17:01:03 INFO - PROCESS | 1652 | __startTimestamp1476489663936__endTimestamp 17:01:03 INFO - PROCESS | 1652 | 17:01:03 INFO - PROCESS | 1652 | ------- Summary: start ------- 17:01:03 INFO - PROCESS | 1652 | Number of tests: 6 17:01:03 INFO - PROCESS | 1652 | 17:01:03 INFO - PROCESS | 1652 | [#0] dojo.html Cycles:165 Average:5640.51 Median:5787.71 stddev:641.54 (11.1%) stddev-sans-first:613.82 17:01:03 INFO - PROCESS | 1652 | Values: 3181.3 3314.1 3331.3 3336.6 3362.4 5480.6 5581.8 5606.4 5653.9 5707.5 5568.2 5591.5 5629.3 5653.3 5704.3 5093.8 5141.5 5176.4 5205.7 5217.1 5117.5 5235.2 5243.7 5255.3 5263.8 5227.7 5254.1 5258.0 5266.6 5277.7 5093.5 5131.4 5208.5 5211.1 5214.9 5088.0 5092.3 5110.3 5120.4 5154.8 5098.2 5193.6 5470.7 5492.1 5514.1 6153.7 6164.1 6168.8 6172.5 6180.0 4898.9 6049.3 6110.6 6131.7 6198.7 5715.7 5725.3 5736.6 5736.9 5743.9 6311.4 6312.4 6315.0 6327.9 6335.0 5839.8 5887.4 5907.1 6064.1 6083.8 6090.4 6109.2 6178.5 6190.2 6213.4 6087.0 6103.7 6139.8 6144.7 6151.0 6079.5 6138.6 6160.1 6162.8 6169.7 4115.7 4782.7 4792.1 4834.5 4910.2 4308.2 4325.2 4326.7 4351.7 4390.0 5584.0 5590.3 5601.6 5605.5 5608.4 5982.0 6096.5 6131.6 6163.7 6164.8 5615.3 5660.3 5672.2 5707.4 5749.3 5308.1 5357.1 5357.9 5370.5 5380.2 6065.4 6248.9 6274.7 6280.0 6305.4 5290.8 6106.2 6122.8 6145.5 6165.9 6259.9 6265.8 6285.8 6291.1 6295.8 6067.6 6074.4 6141.9 6150.1 6161.6 6060.4 6131.4 6216.7 6225.1 6246.9 5917.2 5956.7 5989.6 6059.5 6098.8 5796.4 6014.1 6022.0 6034.4 6039.4 5680.1 5780.8 5787.7 5805.7 5858.9 5848.9 5889.8 5919.3 5923.3 6025.1 5057.5 5626.0 5680.8 5790.8 5838.2 17:01:03 INFO - PROCESS | 1652 | 17:01:03 INFO - PROCESS | 1652 | [#1] ext.html Cycles:180 Average:68780.80 Median:40487.90 stddev:148452.28 (366.7%) stddev-sans-first:148780.09 17:01:03 INFO - PROCESS | 1652 | Values: 454.7 476.4 479.2 510.4 556.6 30228.4 30717.5 40042.4 41016.8 43206.9 42199.0 42624.6 42634.1 42691.9 42779.8 9355.8 9509.5 9525.2 9531.0 9538.3 9425.8 9528.2 9529.0 9545.7 9556.2 9501.4 9563.3 9566.6 9569.8 9582.8 9374.8 9406.2 9423.5 9431.7 9432.7 9391.4 9450.6 9457.2 9465.1 9470.8 40000.8 40398.6 40445.8 40450.0 40525.8 3363.5 3392.0 3396.7 3463.8 3870.8 30570.4 30831.4 31234.5 31393.5 32024.4 3155.7 3182.3 3183.6 3222.2 3240.2 471084.0 473012.0 473268.0 473757.0 473848.0 59504.7 59944.4 60032.4 60075.1 60124.7 34610.8 34998.5 35206.6 64358.4 64662.0 16688.5 16825.1 16847.6 16858.2 16861.2 12751.4 12802.9 12821.4 12828.0 12842.1 17653.6 17787.9 19328.0 19400.8 19436.4 7826.6 7939.6 7964.9 8015.2 8268.0 848.6 850.3 1009.4 1024.0 1068.9 24616.0 27633.6 28558.0 29591.6 29688.7 1028.6 1038.0 1045.0 1057.0 1201.2 14682.9 15133.2 15282.3 15472.9 15598.1 630094.0 635000.0 635454.0 636313.0 637081.0 57863.6 58460.7 58509.0 58548.0 58577.0 1230.2 1231.2 1378.8 1380.3 1400.1 26925.6 27117.7 31110.4 31428.5 31961.7 45177.5 49837.3 50666.0 50820.0 50842.2 466546.0 566607.0 567066.3 567218.0 568805.0 42029.8 42497.8 43146.8 43382.7 43405.9 47261.0 47443.8 48167.0 48255.5 48546.8 38022.0 39192.4 39704.0 40539.6 40658.2 38988.8 39138.6 39879.6 39974.6 40120.2 37526.0 37564.0 37994.2 38901.4 38914.0 31594.8 34337.8 34570.5 34729.5 34742.6 25500.6 25608.9 25704.6 25915.5 26175.0 17:01:03 INFO - PROCESS | 1652 | 17:01:03 INFO - PROCESS | 1652 | [#2] jquery.html Cycles:180 Average:32505.91 Median:1480.09 stddev:89488.37 (6046.1%) stddev-sans-first:89706.84 17:01:03 INFO - PROCESS | 1652 | Values: 349.5 367.8 370.6 375.2 399.6 14435.5 15213.3 20284.4 20298.6 21067.0 10185.6 11991.4 15156.1 20963.9 21248.4 4148.0 5193.7 5303.9 5353.2 5379.7 3935.0 4139.5 4143.8 4183.4 5426.7 4146.8 4190.6 4191.9 4192.9 4655.2 4390.0 6688.3 6739.1 6778.5 6781.1 4417.7 5276.8 5324.5 5889.6 5981.2 12006.7 12206.5 12207.7 12282.1 21538.9 1986.3 2008.2 2143.1 2241.9 2610.7 9847.2 10283.7 10496.7 12270.4 14867.3 1704.5 1711.1 1899.3 1903.2 1933.4 101425.5 114611.0 117839.4 119615.6 120314.0 10068.9 11073.3 11420.1 11496.4 11568.9 12596.3 13239.7 13273.7 13356.7 14160.0 6919.9 8342.6 8566.7 8692.8 8726.4 7685.1 7772.2 8640.8 8669.5 8815.3 4393.0 4475.1 4478.5 4554.7 4565.9 1849.9 1897.4 1899.7 1905.9 1907.4 791.7 808.8 883.2 943.5 944.6 17006.8 20102.3 21009.6 21598.9 21605.4 846.3 916.7 954.8 960.2 978.6 10898.7 11271.2 11494.9 11516.8 11622.9 478475.6 488970.0 490105.0 582538.0 590582.0 126019.9 141072.0 141863.6 144931.0 148425.0 421.0 445.1 445.6 446.1 447.3 10881.2 11001.4 11146.1 11550.1 11556.9 53028.7 54082.5 56192.7 56385.7 57117.4 75486.6 77286.0 80337.0 81444.8 84781.6 6304.1 7558.5 7905.9 8325.3 8383.3 11853.8 12606.9 12636.6 13074.5 13140.0 10814.1 11189.5 11274.4 11477.4 11509.4 8449.9 10717.0 10882.3 11243.7 11461.0 8474.5 11151.4 11570.7 11572.9 11707.7 7916.0 8154.3 8166.0 8308.4 8351.1 9214.0 10094.8 10121.7 10785.0 11520.3 17:01:03 INFO - PROCESS | 1652 | 17:01:03 INFO - PROCESS | 1652 | [#3] mootools.html Cycles:180 Average:5496.01 Median:5017.45 stddev:3071.08 (61.2%) stddev-sans-first:3064.15 17:01:03 INFO - PROCESS | 1652 | Values: 1384.2 1541.5 1567.9 1804.4 1960.1 7645.4 8048.1 8402.9 8611.8 8636.6 8417.9 8470.6 8497.4 8498.5 8507.4 2307.6 2338.4 2338.5 2340.6 2343.5 2296.8 2323.3 2337.5 2339.3 2341.3 2102.1 2179.5 2327.7 2338.8 2339.6 2289.4 2307.6 2308.8 2313.5 2321.5 2303.5 2307.2 2307.5 2315.7 2318.6 8476.7 8479.8 8556.0 8557.4 8583.3 5168.1 5347.8 5359.7 5367.3 5370.4 3419.5 3591.2 3620.9 3644.6 3648.9 1040.0 1075.9 1077.7 1094.5 1097.7 11289.3 11342.8 11417.3 11429.5 11433.0 4342.8 4391.0 4578.1 4578.3 4581.0 8204.8 8482.5 8968.2 8973.8 8977.2 5196.1 5219.8 5220.5 5227.9 5237.2 4157.6 4344.1 4520.4 4534.0 4888.0 3673.4 3689.3 3739.7 3762.4 3766.8 1151.3 1155.1 1156.5 1164.1 1167.2 779.3 804.1 897.1 899.8 900.1 5097.2 5520.3 5787.5 5843.3 5863.1 408.7 417.9 420.4 423.1 424.1 3885.3 3920.1 3947.6 3956.7 4006.0 9508.5 9851.7 10138.3 10488.7 10505.8 10092.3 10435.8 10443.1 10460.4 10506.4 7977.4 7993.8 8085.0 8085.2 8101.5 5612.6 5670.7 5742.9 5746.1 5776.8 9682.2 9779.9 9781.5 9793.8 9812.7 8569.9 9060.5 9502.5 9745.5 9852.5 6835.0 7002.0 7003.4 7010.6 7031.4 7963.6 7988.1 8104.3 8163.6 8402.3 6155.9 7347.2 7390.6 7444.5 7548.7 7168.0 7246.6 7259.7 7290.2 7300.9 6831.0 6948.9 6952.5 6966.8 6978.9 4550.4 4808.1 4975.9 5033.3 5048.1 4177.6 4670.7 4675.4 4779.6 5001.6 17:01:03 INFO - PROCESS | 1652 | 17:01:03 INFO - PROCESS | 1652 | [#4] prototype.html Cycles:180 Average:4341.86 Median:4985.14 stddev:1355.45 (27.2%) stddev-sans-first:1324.82 17:01:03 INFO - PROCESS | 1652 | Values: 297.4 299.8 308.8 311.4 314.0 5152.3 5251.8 5257.7 5259.3 5259.7 4270.4 4926.4 5002.5 5012.7 5241.9 2968.1 3633.8 3852.3 3985.4 3987.4 3610.6 3705.6 3958.7 3960.4 3998.0 3078.9 3257.6 3545.9 3960.9 3972.7 3946.8 3955.8 3960.2 3983.0 3993.4 2954.4 3029.2 3359.0 3411.9 3422.6 4333.3 5049.9 5141.4 5181.9 5183.8 4026.7 4353.9 4446.3 4487.0 4519.8 4456.8 5443.7 5451.7 5471.9 5509.4 3084.3 3186.3 3740.5 3764.9 3773.5 5007.0 5410.4 5480.5 5655.2 5740.5 3168.4 3755.6 3772.5 3794.4 3794.7 3080.9 3121.9 3725.8 3879.2 3894.6 3809.8 4464.8 4520.5 4528.6 4535.6 4748.5 5133.9 5235.0 5428.5 5486.8 1108.2 1323.3 1324.1 1325.0 1328.9 759.3 819.5 845.7 845.9 870.9 4949.1 5082.5 5148.6 5152.1 5164.4 4298.0 5482.5 5556.9 5598.1 5689.8 4629.1 4904.7 4969.1 5061.4 5077.3 2352.3 2631.7 2895.6 2911.7 2919.8 4512.5 5658.5 5681.2 5725.5 5730.0 4606.3 4929.2 5006.7 5049.9 5082.9 4771.3 5126.3 5255.9 5700.9 5706.3 5148.6 5440.6 5481.8 5493.4 5518.6 4753.5 5405.7 5471.3 5482.3 5495.5 4972.7 5003.6 5667.0 5686.0 5723.3 2922.4 3707.8 3725.3 3726.3 3735.6 4997.6 5338.9 5551.6 5625.3 5653.9 5471.7 5479.8 5489.3 5491.7 5502.4 4787.9 5243.4 5431.3 5449.7 5490.8 4400.8 4751.9 5069.3 5211.7 5248.2 4543.5 5389.6 5398.2 5500.3 5507.9 4481.8 5345.9 5358.9 5374.2 5377.3 17:01:03 INFO - PROCESS | 1652 | 17:01:03 INFO - PROCESS | 1652 | [#5] yui.html Cycles:180 Average:23742.67 Median:3769.99 stddev:38005.71 (1008.1%) stddev-sans-first:38072.99 17:01:03 INFO - PROCESS | 1652 | Values: 712.6 732.6 780.4 789.4 836.7 12873.8 15359.9 15530.6 15612.6 15700.1 14158.5 14296.7 14298.0 14304.7 14332.8 620.3 693.9 711.3 716.8 717.5 861.5 882.4 883.3 885.5 889.4 922.8 933.8 938.4 939.0 939.0 931.5 933.4 933.6 933.8 934.8 541.3 557.3 684.3 684.8 685.8 11542.0 14616.1 14659.6 14963.5 14966.0 14138.5 14765.3 14847.1 14885.0 14910.7 11758.2 11762.5 11849.9 11993.2 12160.1 3165.4 3732.4 3807.6 3854.6 3910.7 114317.0 115397.0 118337.0 119735.0 121134.0 10871.4 10902.7 10911.1 11047.2 11327.8 22947.9 22989.1 23039.7 23043.7 23049.7 6019.8 7934.3 8006.8 8083.4 8127.5 4309.8 4404.2 4418.7 4502.1 4554.3 3586.9 3710.2 3712.4 3715.9 3716.6 640.4 643.6 655.5 655.7 671.3 612.5 673.6 686.6 691.3 706.4 7092.0 8790.5 8802.6 9342.6 9509.5 585.6 587.9 611.7 675.8 703.3 4417.6 4528.4 4657.5 4674.6 4689.6 141626.9 162749.4 165760.2 169019.2 170169.0 86649.6 86837.1 102668.0 103035.5 103860.4 69682.7 71123.0 72479.0 102565.0 103698.0 9487.7 9549.3 9663.0 9802.3 9936.6 50202.0 50439.0 55421.8 59214.0 61919.5 90731.0 92274.0 94560.0 96270.5 97056.5 11716.4 11729.9 12935.0 13196.6 13329.9 14708.8 14870.6 14904.7 15283.2 15347.7 13927.5 13981.9 14344.8 14576.4 14630.9 9975.7 13942.7 13962.7 14222.3 14275.0 12971.2 13487.2 13522.7 13667.2 14074.3 11364.1 11482.4 11652.8 11689.1 11916.3 10871.8 11000.8 11459.4 11521.3 11565.2 17:01:03 INFO - PROCESS | 1652 | -------- Summary: end -------- 17:01:03 INFO - PROCESS | 1652 | 17:01:04 INFO - TEST-INFO | 1652: exit 0 17:01:04 INFO - TEST-OK | dromaeo_css | took 1119789ms 17:01:04 INFO - TEST-START | kraken 17:01:04 INFO - operating with platform_type : mac_ 17:01:04 INFO - Initialising browser for kraken test... 17:01:04 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/tmp4GsJEv/profile http://localhost:49273/getInfo.html) 17:01:06 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 17:01:06 INFO - PROCESS | 1661 | colorDepth:24 17:01:06 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 17:01:06 INFO - PROCESS | 1661 | __metrics 17:01:07 INFO - PROCESS | 1661 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:01:07 INFO - PROCESS | 1661 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:01:07 INFO - TEST-INFO | 1661: exit 0 17:01:07 INFO - Browser initialized. 17:01:07 INFO - Running cycle 1/1 for kraken test... 17:01:07 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/tmp4GsJEv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:01:19 INFO - PROCESS | 1662 | RSS: Main: 232275968 17:01:19 INFO - PROCESS | 1662 | 17:01:22 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 17:01:23 INFO - PROCESS | 1662 | RSS: Main: 291180544 17:01:23 INFO - PROCESS | 1662 | 17:01:25 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 17:01:26 INFO - PROCESS | 1662 | RSS: Main: 292036608 17:01:26 INFO - PROCESS | 1662 | 17:01:30 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 17:01:30 INFO - PROCESS | 1662 | RSS: Main: 386166784 17:01:30 INFO - PROCESS | 1662 | 17:01:32 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 17:01:33 INFO - PROCESS | 1662 | RSS: Main: 286744576 17:01:33 INFO - PROCESS | 1662 | 17:01:35 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:01:35 INFO - PROCESS | 1662 | RSS: Main: 291385344 17:01:35 INFO - PROCESS | 1662 | 17:01:38 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 17:01:39 INFO - PROCESS | 1662 | RSS: Main: 327823360 17:01:39 INFO - PROCESS | 1662 | 17:01:42 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 17:01:42 INFO - PROCESS | 1662 | RSS: Main: 329609216 17:01:42 INFO - PROCESS | 1662 | 17:01:45 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 17:01:46 INFO - PROCESS | 1662 | RSS: Main: 328474624 17:01:46 INFO - PROCESS | 1662 | 17:01:48 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:01:48 INFO - PROCESS | 1662 | RSS: Main: 303755264 17:01:48 INFO - PROCESS | 1662 | 17:01:50 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:01:51 INFO - PROCESS | 1662 | RSS: Main: 304824320 17:01:51 INFO - PROCESS | 1662 | 17:01:53 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:01:54 INFO - PROCESS | 1662 | RSS: Main: 308359168 17:01:54 INFO - PROCESS | 1662 | 17:01:57 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:01:57 INFO - PROCESS | 1662 | RSS: Main: 322945024 17:01:57 INFO - PROCESS | 1662 | 17:02:01 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:02:01 INFO - PROCESS | 1662 | RSS: Main: 316510208 17:02:01 INFO - PROCESS | 1662 | 17:02:03 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 17:02:03 INFO - PROCESS | 1662 | RSS: Main: 360701952 17:02:03 INFO - PROCESS | 1662 | 17:02:03 INFO - PROCESS | 1662 | __start_tp_report 17:02:03 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 17:02:03 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 17:02:03 INFO - PROCESS | 1662 | |i|pagename|runs| 17:02:03 INFO - PROCESS | 1662 | |0;ai-astar;102;113;114;112;112;114;112;112;112;113 17:02:03 INFO - PROCESS | 1662 | |1;audio-beat-detection;154;143;139;149;114;112;113;115;171;139 17:02:03 INFO - PROCESS | 1662 | |2;audio-dft;182;186;182;182;183;185;179;187;183;183 17:02:03 INFO - PROCESS | 1662 | |3;audio-fft;80;59;58;86;62;58;59;58;83;73 17:02:03 INFO - PROCESS | 1662 | |4;audio-oscillator;86;71;92;70;68;69;92;68;69;91 17:02:03 INFO - PROCESS | 1662 | |5;imaging-gaussian-blur;89;86;93;130;117;87;90;87;89;128 17:02:03 INFO - PROCESS | 1662 | |6;imaging-darkroom;95;95;95;95;94;94;95;95;106;150 17:02:03 INFO - PROCESS | 1662 | |7;imaging-desaturate;92;82;94;81;81;81;90;111;134;126 17:02:03 INFO - PROCESS | 1662 | |8;json-parse-financial;57;56;55;56;54;54;55;55;54;54 17:02:03 INFO - PROCESS | 1662 | |9;json-stringify-tinderbox;64;71;80;75;72;67;69;59;67;69 17:02:03 INFO - PROCESS | 1662 | |10;stanford-crypto-aes;87;89;85;86;87;98;109;106;109;91 17:02:03 INFO - PROCESS | 1662 | |11;stanford-crypto-ccm;146;146;148;152;145;138;149;156;189;147 17:02:03 INFO - PROCESS | 1662 | |12;stanford-crypto-pbkdf2;171;225;172;170;172;172;172;192;232;220 17:02:03 INFO - PROCESS | 1662 | |13;stanford-crypto-sha256-iterative;71;67;69;68;70;69;67;69;68;68 17:02:03 INFO - PROCESS | 1662 | __end_tp_report 17:02:03 INFO - PROCESS | 1662 | __start_cc_report 17:02:03 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,2145 17:02:03 INFO - PROCESS | 1662 | __end_cc_report 17:02:03 INFO - PROCESS | 1662 | __startTimestamp1476489723598__endTimestamp 17:02:03 INFO - PROCESS | 1662 | 17:02:03 INFO - PROCESS | 1662 | ------- Summary: start ------- 17:02:03 INFO - PROCESS | 1662 | Number of tests: 14 17:02:03 INFO - PROCESS | 1662 | 17:02:03 INFO - PROCESS | 1662 | [#0] ai-astar Cycles:10 Average:111.60 Median:112.50 stddev:3.47 (3.1%) stddev-sans-first:0.87 17:02:03 INFO - PROCESS | 1662 | Values: 102.0 113.0 114.0 112.0 112.0 114.0 112.0 112.0 112.0 113.0 17:02:03 INFO - PROCESS | 1662 | 17:02:03 INFO - PROCESS | 1662 | [#1] audio-beat-detection Cycles:10 Average:134.90 Median:141.00 stddev:20.56 (14.6%) stddev-sans-first:20.61 17:02:03 INFO - PROCESS | 1662 | Values: 154.0 143.0 139.0 149.0 114.0 112.0 113.0 115.0 171.0 139.0 17:02:03 INFO - PROCESS | 1662 | 17:02:03 INFO - PROCESS | 1662 | [#2] audio-dft Cycles:10 Average:183.20 Median:183.00 stddev:2.30 (1.3%) stddev-sans-first:2.40 17:02:03 INFO - PROCESS | 1662 | Values: 182.0 186.0 182.0 182.0 183.0 185.0 179.0 187.0 183.0 183.0 17:02:03 INFO - PROCESS | 1662 | 17:02:03 INFO - PROCESS | 1662 | [#3] audio-fft Cycles:10 Average:67.60 Median:67.50 stddev:11.62 (17.2%) stddev-sans-first:11.42 17:02:03 INFO - PROCESS | 1662 | Values: 80.0 59.0 58.0 86.0 62.0 58.0 59.0 58.0 83.0 73.0 17:02:03 INFO - PROCESS | 1662 | 17:02:03 INFO - PROCESS | 1662 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:78.50 stddev:11.05 (14.1%) stddev-sans-first:11.29 17:02:03 INFO - PROCESS | 1662 | Values: 86.0 71.0 92.0 70.0 68.0 69.0 92.0 68.0 69.0 91.0 17:02:03 INFO - PROCESS | 1662 | 17:02:03 INFO - PROCESS | 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:99.60 Median:88.00 stddev:17.94 (20.4%) stddev-sans-first:18.61 17:02:03 INFO - PROCESS | 1662 | Values: 89.0 86.0 93.0 130.0 117.0 87.0 90.0 87.0 89.0 128.0 17:02:03 INFO - PROCESS | 1662 | 17:02:03 INFO - PROCESS | 1662 | [#6] imaging-darkroom Cycles:10 Average:101.40 Median:95.00 stddev:17.44 (18.4%) stddev-sans-first:18.35 17:02:03 INFO - PROCESS | 1662 | Values: 95.0 95.0 95.0 95.0 94.0 94.0 95.0 95.0 106.0 150.0 17:02:03 INFO - PROCESS | 1662 | 17:02:03 INFO - PROCESS | 1662 | [#7] imaging-desaturate Cycles:10 Average:97.20 Median:81.50 stddev:19.67 (24.1%) stddev-sans-first:20.77 17:02:03 INFO - PROCESS | 1662 | Values: 92.0 82.0 94.0 81.0 81.0 81.0 90.0 111.0 134.0 126.0 17:02:03 INFO - PROCESS | 1662 | 17:02:03 INFO - PROCESS | 1662 | [#8] json-parse-financial Cycles:10 Average:55.00 Median:55.00 stddev:1.05 (1.9%) stddev-sans-first:0.83 17:02:03 INFO - PROCESS | 1662 | Values: 57.0 56.0 55.0 56.0 54.0 54.0 55.0 55.0 54.0 54.0 17:02:03 INFO - PROCESS | 1662 | 17:02:03 INFO - PROCESS | 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:69.30 Median:70.00 stddev:5.79 (8.3%) stddev-sans-first:5.82 17:02:03 INFO - PROCESS | 1662 | Values: 64.0 71.0 80.0 75.0 72.0 67.0 69.0 59.0 67.0 69.0 17:02:03 INFO - PROCESS | 1662 | 17:02:03 INFO - PROCESS | 1662 | [#10] stanford-crypto-aes Cycles:10 Average:94.70 Median:87.00 stddev:9.90 (11.4%) stddev-sans-first:10.10 17:02:03 INFO - PROCESS | 1662 | Values: 87.0 89.0 85.0 86.0 87.0 98.0 109.0 106.0 109.0 91.0 17:02:03 INFO - PROCESS | 1662 | 17:02:03 INFO - PROCESS | 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:151.60 Median:148.50 stddev:13.95 (9.4%) stddev-sans-first:14.64 17:02:03 INFO - PROCESS | 1662 | Values: 146.0 146.0 148.0 152.0 145.0 138.0 149.0 156.0 189.0 147.0 17:02:03 INFO - PROCESS | 1662 | 17:02:03 INFO - PROCESS | 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.80 Median:182.00 stddev:25.71 (14.1%) stddev-sans-first:26.36 17:02:03 INFO - PROCESS | 1662 | Values: 171.0 225.0 172.0 170.0 172.0 172.0 172.0 192.0 232.0 220.0 17:02:03 INFO - PROCESS | 1662 | 17:02:03 INFO - PROCESS | 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.60 Median:69.00 stddev:1.26 (1.8%) stddev-sans-first:1.00 17:02:03 INFO - PROCESS | 1662 | Values: 71.0 67.0 69.0 68.0 70.0 69.0 67.0 69.0 68.0 68.0 17:02:03 INFO - PROCESS | 1662 | -------- Summary: end -------- 17:02:03 INFO - PROCESS | 1662 | 17:02:04 INFO - TEST-INFO | 1662: exit 0 17:02:04 INFO - TEST-OK | kraken | took 59926ms 17:02:04 INFO - SUITE-END | took 1179s 17:02:04 INFO - Completed test suite (00:19:39) 17:02:04 INFO - javascript benchmark 17:02:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3181.347823696142, 3314.1051915982684, 3331.3004269103485, 3336.5662463879694, 3362.4028586421455, 5480.6437581557175, 5581.804636837712, 5606.439356064391, 5653.943460565393, 5707.5148612368, 5568.16477528371, 5591.4688104630095, 5629.296337957755, 5653.265075540185, 5704.258446401972, 5093.847184584469, 5141.537261646451, 5176.404713457944, 5205.687658740469, 5217.139172036609, 5117.513836185556, 5235.188545775411, 5243.737813109348, 5255.316808814849, 5263.815766448175, 5227.660202086852, 5254.080535906221, 5258.0, 5266.605004624668, 5277.683338999653, 5093.490650934914, 5131.358580177478, 5208.5051920067535, 5211.114110601188, 5214.869628259324, 5088.033273677989, 5092.261622064817, 5110.28456016158, 5120.436751957282, 5154.819581314673, 5098.209777484496, 5193.597728613253, 5470.671759694431, 5492.121260598325, 5514.117741161395, 6153.661548614825, 6164.106204600308, 6168.814935551941, 6172.506199504029, 6180.042093475518, 4898.853034408973, 6049.304330002062, 6110.572259941806, 6131.65643559416, 6198.659073750942, 5715.685637289906, 5725.341585717612, 5736.569757268222, 5736.856578585569, 5743.913841292384, 6311.368863113744, 6312.400321969406, 6315.0, 6327.873442531216, 6334.968325158345, 5839.766409343579, 5887.411258874078, 5907.1139329100115, 6064.120702498113, 6083.787067452707, 6090.36051214625, 6109.205803245639, 6178.474829639531, 6190.195274614362, 6213.378662133841, 6087.017013833646, 6103.701349646989, 6139.807300203869, 6144.662043587646, 6150.985087460519, 6079.546018234662, 6138.610893211376, 6160.1375807387, 6162.784302549389, 6169.691515424211, 4115.73247738896, 4782.68912520692, 4792.0895029944195, 4834.468208497063, 4910.238912968495, 4308.18144552534, 4325.199838029975, 4326.718763280377, 4351.651867850627, 4389.956100438954, 5584.022796010682, 5590.273264475673, 5601.60788744784, 5605.495505404533, 5608.383077861432, 5981.970090149522, 6096.512279017668, 6131.601445906091, 6163.691815409212, 6164.784232551929, 5615.298087739033, 5660.264165658438, 5672.177534257592, 5707.429257074259, 5749.310082790092, 5308.0710875596615, 5357.142857142839, 5357.919631205535, 5370.4629537045985, 5380.24676545284, 6065.3934606539, 6248.875022499525, 6274.6548939808745, 6280.0, 6305.432511074026, 5290.841274761763, 6106.2179897963915, 6122.755089796358, 6145.477634400947, 6165.907511387243, 6259.906101408574, 6265.843353916006, 6285.842853928689, 6291.1192433058495, 6295.779647712308, 6067.635941843503, 6074.36219196978, 6141.9078713818435, 6150.10823430609, 6161.630302181883, 6060.393960603904, 6131.448169664752, 6216.658083805434, 6225.097360882737, 6246.937530624818, 5917.201177841109, 5956.731947062306, 5989.550783691154, 6059.484943779652, 6098.817035488943, 5796.362400135898, 6014.127951447103, 6022.036474164288, 6034.366391529002, 6039.4262545058145, 5680.147977803363, 5780.826575202751, 5787.710614469344, 5805.680687562224, 5858.912116318344, 5848.853778655568, 5889.823305300848, 5919.31927828288, 5923.348431672599, 6025.126356678345, 5057.468965758542, 5625.971870140623, 5680.801171958961, 5790.826275211751, 5838.211841401527], "value": 5599.36161496788, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [454.6658206218429, 476.44256220222337, 479.238011561617, 510.40282869043216, 556.6187161794169, 30228.395432091373, 30717.539236911463, 40042.399364009565, 41016.79491602538, 43206.91982700426, 42199.0, 42624.57375426245, 42634.14731705364, 42691.932701682475, 42779.78610106953, 9355.812883742321, 9509.524523773818, 9525.237980961507, 9530.952345238264, 9538.284628652844, 9425.764355891124, 9528.237740980705, 9529.0, 9545.713628591153, 9556.23550115989, 9501.429914205135, 9563.282753793459, 9566.617335306613, 9569.760755981086, 9582.802149731284, 9374.765630859249, 9406.200472959807, 9423.48170850603, 9431.66989155383, 9432.669856554987, 9391.389559678599, 9450.621975121056, 9457.243420526342, 9465.100815422524, 9470.763230919214, 40000.799996000125, 40398.59601403978, 40445.797771011254, 40450.0, 40525.79737101325, 3363.5459213006175, 3392.0, 3396.677315655009, 3463.774854634466, 3870.806459677033, 30570.38859222803, 30831.383372332653, 31234.53148202756, 31393.52909706356, 32024.359512809613, 3155.7159855612877, 3182.268078341994, 3183.586133802614, 3222.180219255483, 3240.1575590346442, 471084.0, 473012.0, 473268.0, 473757.0, 473848.0, 59504.70247648821, 59944.400555994755, 60032.39967600268, 60075.09887351693, 60124.69937650371, 34610.82694586511, 34998.47502287468, 35206.64793352085, 64358.356416436174, 64662.0, 16688.499345019667, 16825.074620895968, 16847.578810529834, 16858.241379137722, 16861.2412441438, 12751.42618582166, 12802.9359853202, 12821.35893205336, 12828.0, 12842.101052926206, 17653.646927061385, 17787.911060444614, 19327.96032238066, 19400.8059919399, 19436.4169074928, 7826.647800848976, 7939.593525593235, 7964.8805267921025, 8015.238552337519, 8268.0, 848.6181218451721, 850.2602735620051, 1009.4347165587294, 1023.9709092362277, 1068.9144868410665, 24615.982695547493, 27633.585496217172, 28558.000469983865, 29591.556126658117, 29688.703112969026, 1028.5782829040206, 1037.994810025945, 1045.038564520643, 1056.963006294776, 1201.1831954271179, 14682.853171468149, 15133.243337833064, 15282.31229594671, 15472.922635386976, 15598.064116153068, 630094.0, 635000.0, 635454.0, 636313.0, 637081.0, 57863.553411165056, 58460.707696462094, 58509.0, 58548.0, 58577.0, 1230.2126638951079, 1231.2243286729483, 1378.7587172245017, 1380.2615600653603, 1400.0759498730993, 26925.596116057885, 27117.730977583255, 31110.377792444477, 31428.52857207098, 31961.68038319587, 45177.54822451865, 49837.25244121269, 50666.0, 50820.0, 50842.23736643879, 466546.0, 566607.0, 567066.305358621, 567218.0, 568805.0, 42029.78985105055, 42497.78751106225, 43146.784266078466, 43382.698519044476, 43405.914852128946, 47261.0, 47443.81390465266, 48167.0, 48255.51744482651, 48546.75726621344, 38022.0, 39192.41211381774, 39704.00739981524, 40539.594604053585, 40658.1868362637, 38988.830335089995, 39138.608613914635, 39879.601203987586, 39974.60025399709, 40120.1975960485, 37526.0, 37564.0, 37994.20057994178, 38901.416478752275, 38914.0, 31594.842025789723, 34337.79817706368, 34570.50805452292, 34729.47905781466, 34742.610295587896, 25500.617490738026, 25608.8719556401, 25704.61443078318, 25915.481690366465, 26175.0], "value": 19995.7627201842, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.4600841699574, 367.8050633164423, 370.6238168259217, 375.24201113750223, 399.6343345838558, 14435.494757683484, 15213.315400806963, 20284.436001899565, 20298.594028119427, 21067.0, 10185.643502477415, 11991.400429978488, 15156.090634561933, 20963.895180524076, 21248.36254912355, 4148.0, 5193.688378697281, 5303.920441193366, 5353.223782551528, 5379.65032272901, 3934.9803250983705, 4139.503259608836, 4143.8190115817, 4183.393407955845, 5426.67439953604, 4146.834126634946, 4190.5809419058005, 4191.853285135021, 4192.895177620553, 4655.20861453552, 4389.978050109745, 6688.264290927994, 6739.090222819906, 6778.525503214777, 6781.11845460092, 4417.734935903856, 5276.762545685453, 5324.520793128637, 5889.558283128739, 5981.192539007266, 12006.69983250417, 12206.450709718085, 12207.694807629792, 12282.14025018249, 21538.892305538528, 1986.2948653228184, 2008.2368699893948, 2143.1213202586864, 2241.8766967816764, 2610.7258737832635, 9847.16299114576, 10283.742906427324, 10496.685099446953, 12270.38648067602, 14867.275989404003, 1704.4630941253465, 1711.0589175953176, 1899.3162461513843, 1903.2196799312353, 1933.419974007806, 101425.49287253516, 114611.0, 117839.4108029463, 119615.60768784663, 120314.0, 10068.949655251823, 11073.300653104916, 11420.086393088533, 11496.42517874103, 11568.88431115695, 12596.307203103734, 13239.66900827487, 13273.66815829612, 13356.732865342834, 14160.0, 6919.930800692029, 8342.624581893708, 8566.657333706706, 8692.826143477096, 8726.425704417212, 7685.077790665156, 7772.18392068836, 8640.846091176676, 8669.523176225368, 8815.250703690259, 4392.978035109804, 4475.1049790042125, 4478.484974228006, 4554.7039442436135, 4565.908681826345, 1849.8797578157646, 1897.383350411122, 1899.7435346228358, 1905.8951757653463, 1907.3515004898125, 791.6620910660964, 808.7614153824636, 883.2051153961486, 943.4669411782321, 944.5938246554048, 17006.829931700773, 20102.296419625534, 21009.579808403745, 21598.920053997237, 21605.35183944484, 846.3356265331685, 916.7433118726767, 954.8233576788316, 960.2462067277154, 978.5889926231, 10898.727531811768, 11271.154663400113, 11494.94252528732, 11516.769664606662, 11622.94188529052, 478475.60762195964, 488970.0, 490105.0, 582538.0, 590582.0, 126019.91215518932, 141072.0, 141863.58136418503, 144931.0, 148425.0, 420.98316067357575, 445.1097804391218, 445.6301269945937, 446.0521392041911, 447.2620176708457, 10881.238313318308, 11001.449927503752, 11146.108311335236, 11550.075993920298, 11556.94221528887, 53028.73485632701, 54082.45917540932, 56192.71903640619, 56385.71807140938, 57117.42882571121, 75486.62256688681, 77286.0, 80336.99157521108, 81444.77832832394, 84781.57609211914, 6304.085907543289, 7558.470907036454, 7905.881411778713, 8325.25072743456, 8383.329333653415, 11853.822192667289, 12606.873931260938, 12636.620901372606, 13074.477020919057, 13140.0, 10814.080803131506, 11189.496472658588, 11274.379909105079, 11477.426128693698, 11509.424528773361, 8449.915500844912, 10717.035466808025, 10882.347059176158, 11243.66269011931, 11461.025812806005, 8474.533900635524, 11151.386673733023, 11570.652880413601, 11572.900574445413, 11707.707307317385, 7915.970923780102, 8154.306883914934, 8166.02007759049, 8308.376871734765, 8351.081381047958, 9214.0, 10094.848577271494, 10121.746956325856, 10785.0, 11520.308781473139], "value": 8722.951238876083, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1384.2109997301543, 1541.5144229567686, 1567.858892699657, 1804.4406234067433, 1960.0689672405608, 7645.388368930486, 8048.074471435787, 8402.873956890644, 8611.827763444728, 8636.56817159141, 8417.947756530433, 8470.618822153003, 8497.405181637288, 8498.532580708074, 8507.404481686284, 2307.619242824932, 2338.4037070546988, 2338.485533182697, 2340.637201233803, 2343.484433424644, 2296.7588403217674, 2323.326235391743, 2337.4623836517612, 2339.263132113387, 2341.2859077981293, 2102.127617038927, 2179.487820362214, 2327.674125622406, 2338.8362814602983, 2339.6313156803208, 2289.439087423575, 2307.6423154411095, 2308.7691230876944, 2313.5372925414986, 2321.5124823787023, 2303.49323148907, 2307.1578873710973, 2307.5384923015463, 2315.6642286868478, 2318.5594736999915, 8476.74569762902, 8479.788005299855, 8555.95722021392, 8557.400981931267, 8583.313334933253, 5168.095583272912, 5347.839564813062, 5359.705216213107, 5367.329083864517, 5370.355557333106, 3419.5212670226188, 3591.2099338145567, 3620.855165793365, 3644.6355364463607, 3648.854045838163, 1039.9844002339971, 1075.9462026898702, 1077.7036315013434, 1094.5020015892749, 1097.7255686078481, 11289.322640641587, 11342.773144537063, 11417.257878237888, 11429.542818287342, 11433.028192603639, 4342.761148136819, 4390.978045109818, 4578.115787054201, 4578.313253012075, 4581.037982023746, 8204.794880128045, 8482.533460659599, 8968.192862642394, 8973.775655608531, 8977.192052715287, 5196.090684130338, 5219.817306394258, 5220.530152286236, 5227.869303267449, 5237.240600112962, 4157.64660003897, 4344.1311737652595, 4520.434945631796, 4534.0, 4887.975560122177, 3673.412254039341, 3689.2805902849223, 3739.700823934079, 3762.360398732222, 3766.773993560395, 1151.2631915574038, 1155.1163360029584, 1156.4506859241792, 1164.0745607242263, 1167.2412931594363, 779.3375630713848, 804.1194891593696, 897.0849733272026, 899.8245342158216, 900.0864122915116, 5097.1844504879045, 5520.309961254843, 5787.479126878517, 5843.2695913010875, 5863.149843272787, 408.6587699271142, 417.9435776170239, 420.36524847479916, 423.1368009261073, 424.11360257062887, 3885.339492286318, 3920.0591857953887, 3947.585503522146, 3956.6834653227675, 4005.9799701001307, 9508.524573771283, 9851.704448866545, 10138.341007834468, 10488.73778155537, 10505.789884202426, 10092.293539452316, 10435.791284174275, 10443.11233545142, 10460.424676642857, 10506.422146782, 7977.401694872908, 7993.760187194393, 8084.959575202204, 8085.151059138823, 8101.513909165469, 5612.579056570692, 5670.659760414388, 5742.85142971399, 5746.13807928814, 5776.826695199151, 9682.177014953808, 9779.85330220033, 9781.462019588991, 9793.755156121155, 9812.65655702047, 8569.914300856912, 9060.501672408082, 9502.524873756147, 9745.512724363613, 9852.458114803754, 6834.965825170842, 7001.984712216802, 7003.404710599656, 7010.579365238102, 7031.40233080194, 7963.641636126273, 7988.121306656147, 8104.311133553714, 8163.591820409075, 8402.327813775006, 6155.938440615537, 7347.19180890091, 7390.593517356597, 7444.478886478111, 7548.698052077855, 7168.0, 7246.6376681166785, 7259.745908893164, 7290.234525374972, 7300.890486642811, 6831.0, 6948.9305106950305, 6952.478564107611, 6966.825829354307, 6978.930210697828, 4550.408446901882, 4808.0624278266805, 4975.875603109951, 5033.2701758245585, 5048.141815891234, 4177.561356057692, 4670.673052886265, 4675.4155730533685, 4779.617630589614, 5001.624878134227], "value": 4351.672950967323, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.35919094351664, 299.75719667069677, 308.76071044940176, 311.4083241840503, 313.9905802825916, 5152.33019707438, 5251.763670634821, 5257.7108259045735, 5259.344031264216, 5259.658122222053, 4270.402143699885, 4926.3842019747535, 5002.549770520653, 5012.674176178555, 5241.947580524194, 2968.079895232474, 3633.8364773585254, 3852.325842977482, 3985.4021896715435, 3987.3819557968563, 3610.638936106395, 3705.5923848376656, 3958.663513601333, 3960.4059391091278, 3997.9800100999455, 3078.9076327710204, 3257.641659417462, 3545.9113522161892, 3960.861369852041, 3972.6623237024887, 3946.8223929923224, 3955.764992779071, 3960.1683646434285, 3983.0042489377656, 3993.4242413643037, 2954.4091181763733, 3029.242689327668, 3359.0, 3411.9317613647586, 3422.6235114137426, 4333.341657717934, 5049.949500504985, 5141.38303403594, 5181.948180518184, 5183.766730497136, 4026.7382620129893, 4353.91152211947, 4446.265956277054, 4487.0352874131895, 4519.819207231707, 4456.77716114193, 5443.727813609344, 5451.700156491392, 5471.890562188773, 5509.421510741355, 3084.275195329096, 3186.283086305586, 3740.4576336431337, 3764.8682296119778, 3773.4528493368584, 5006.994404476404, 5410.377806552218, 5480.546855878387, 5655.228937820439, 5740.478773125125, 3168.3999580211844, 3755.572882304761, 3772.4718539404507, 3794.449804778347, 3794.658480736747, 3080.86136123875, 3121.859516321771, 3725.7950812705562, 3879.2241551689776, 3894.571597124314, 3809.8379994102065, 4464.799084041226, 4520.50274469808, 4528.615067719281, 4535.614472769786, 4748.501407352247, 5133.948660513422, 5234.973825130926, 5428.4842939920645, 5486.780528778804, 1108.2076315434504, 1323.3383308345826, 1324.059917458596, 1324.9599064734136, 1328.9401976911063, 759.2786852490157, 819.4837252530987, 845.6617353058703, 845.8815765792793, 870.9433886797337, 4949.109160351171, 5082.491750824888, 5148.639595228298, 5152.124138896396, 5164.406093299243, 4297.978510107492, 5482.506574408322, 5556.86107847299, 5598.1322894951345, 5689.800856969986, 4629.125204292267, 4904.705717656952, 4969.074431199312, 5061.392632884003, 5077.299104799933, 2352.3060697094397, 2631.6841978962266, 2895.609092772491, 2911.6706634692164, 2919.7810164237767, 4512.548745125461, 5658.528782516575, 5681.204631351614, 5725.484706376446, 5730.0, 4606.286025666027, 4929.2359684248995, 5006.724630145304, 5049.924251136236, 5082.872928176826, 4771.33201351811, 5126.307948426989, 5255.948810237967, 5700.9429905701245, 5706.288113565996, 5148.639595228298, 5440.564754819684, 5481.780728770805, 5493.423190564933, 5518.641288316247, 4753.453352864465, 5405.729653531495, 5471.316085489314, 5482.342118945752, 5495.527012844156, 4972.676776009548, 5003.599712023102, 5666.9716651416475, 5686.0, 5723.3418156912485, 2922.444735500248, 3707.814609269472, 3725.2735716535017, 3726.2859084821284, 3735.6451137141926, 4997.600191984559, 5338.919916201183, 5551.583631227593, 5625.324961004542, 5653.943460565506, 5471.671699698031, 5479.780808767764, 5489.286392768914, 5491.670499770026, 5502.394736578893, 4787.8563643090765, 5243.397009343974, 5431.2667789847865, 5449.7002664852225, 5490.80782172622, 4400.777607308289, 4751.928721069117, 5069.31564238823, 5211.71335576547, 5248.212768084818, 4543.54564543543, 5389.649672771257, 5398.244245805512, 5500.257465242301, 5507.944920550904, 4481.798319075584, 5345.9465405347, 5358.919616205838, 5374.167004114369, 5377.300950876361], "value": 3914.6462536838276, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.5973824788994, 732.6263605561162, 780.391294790064, 789.4118881433333, 836.6736972580691, 12873.806892896602, 15359.923200383997, 15530.611734706637, 15612.60968475786, 15700.136492492908, 14158.50445234417, 14296.714065718705, 14297.9991400602, 14304.71390572188, 14332.785008224859, 620.2990620598717, 693.8959156126583, 711.2993701204309, 716.8494616130619, 717.5120917775911, 861.5261606116642, 882.3602887906248, 883.2977782662766, 885.4687187687368, 889.4040992534984, 922.8431166701677, 933.7898972731149, 938.3759799733169, 939.0187254319253, 939.0234156477255, 931.5481991234246, 933.351320832022, 933.5705575435308, 933.8272419602396, 934.7569631895689, 541.3098299667915, 557.2866730584858, 684.322520704499, 684.7808701215613, 685.8388278754488, 11542.0, 14616.123032618078, 14659.63350916225, 14963.476278330316, 14965.952383333171, 14138.50515231962, 14765.335559899722, 14847.109173449628, 14885.0, 14910.70178596422, 11758.17692761507, 11762.470688819023, 11849.94075029628, 11993.16047876649, 12160.148789584733, 3165.366926614663, 3732.42147467143, 3807.562130355017, 3854.6338097880657, 3910.667593254577, 114317.0, 115397.0, 118337.0, 119735.0, 121134.0, 10871.402072885909, 10902.727431814268, 10911.127109831194, 11047.226694131494, 11327.830082548768, 22947.885260573923, 22989.080436782675, 23039.659249409076, 23043.654345184837, 23049.654255186186, 6019.849503762441, 7934.299956932626, 8006.759797206093, 8083.434159608889, 8127.512349259064, 4309.827606895752, 4404.185225733249, 4418.7127836690515, 4502.077074199781, 4554.294084416921, 3586.9103272418397, 3710.239400922804, 3712.4431335299923, 3715.869944551928, 3716.572594151653, 640.4172203294979, 643.5881036136876, 655.4756195043946, 655.7082098466137, 671.2750229751856, 612.497751843484, 673.5756473421811, 686.6105951289541, 691.3432239372617, 706.4480701303054, 7091.964540177266, 8790.516521591373, 8802.647894084164, 9342.57958391874, 9509.476978766234, 585.5579037826413, 587.9235699359056, 611.7063809371438, 675.8023302848231, 703.2931903437013, 4417.60241578253, 4528.366028755977, 4657.464391594942, 4674.555917187861, 4689.62483001359, 141626.87559686613, 162749.3725062758, 165760.17119914564, 169019.1549042247, 170169.0, 86649.5667521671, 86837.13162868416, 102668.0, 103035.48482257691, 103860.44209336866, 69682.65895666076, 71123.0, 72479.0, 102565.0, 103698.0, 9487.715368538955, 9549.283803714889, 9662.985386534312, 9802.26483013791, 9936.552855121392, 50202.0, 50439.0, 55421.759016269236, 59214.0, 61919.452013700015, 90731.0, 92274.0, 94560.0, 96270.51864740631, 97056.51471742596, 11716.414179291172, 11729.885660862205, 12935.0, 13196.60410187696, 13329.933350333185, 14708.779368309699, 14870.628234294229, 14904.701905962036, 15283.235838208268, 15347.693046139239, 13927.512537061155, 13981.930090349482, 14344.784828227794, 14576.416943322149, 14630.85369146295, 9975.700728978143, 13942.721145577234, 13962.720745585235, 14222.288885555889, 14275.0, 12971.22172669605, 13487.190768553526, 13522.729545409233, 13667.17996920188, 14074.311082670145, 11364.090872729996, 11482.368469734245, 11652.766944661229, 11689.123315751183, 11916.285022898308, 10871.801016485222, 11000.834987475035, 11459.427028648368, 11521.308721476738, 11565.190436669352], "value": 7719.926511709144, "unit": "score"}], "name": "dromaeo_css", "value": 7103.273127152469, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 113.0, 114.0, 112.0, 112.0, 114.0, 112.0, 112.0, 112.0, 113.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 143.0, 139.0, 149.0, 114.0, 112.0, 113.0, 115.0, 171.0, 139.0], "value": 134.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 186.0, 182.0, 182.0, 183.0, 185.0, 179.0, 187.0, 183.0, 183.0], "value": 183.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 58.0, 86.0, 62.0, 58.0, 59.0, 58.0, 83.0, 73.0], "value": 67.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 71.0, 92.0, 70.0, 68.0, 69.0, 92.0, 68.0, 69.0, 91.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 93.0, 130.0, 117.0, 87.0, 90.0, 87.0, 89.0, 128.0], "value": 99.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 106.0, 150.0], "value": 101.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 82.0, 94.0, 81.0, 81.0, 81.0, 90.0, 111.0, 134.0, 126.0], "value": 97.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 55.0, 56.0, 54.0, 54.0, 55.0, 55.0, 54.0, 54.0], "value": 55.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 71.0, 80.0, 75.0, 72.0, 67.0, 69.0, 59.0, 67.0, 69.0], "value": 69.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 89.0, 85.0, 86.0, 87.0, 98.0, 109.0, 106.0, 109.0, 91.0], "value": 94.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 148.0, 152.0, 145.0, 138.0, 149.0, 156.0, 189.0, 147.0], "value": 151.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 225.0, 172.0, 170.0, 172.0, 172.0, 172.0, 192.0, 232.0, 220.0], "value": 189.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 67.0, 69.0, 68.0, 70.0, 69.0, 67.0, 69.0, 68.0, 68.0], "value": 68.6, "unit": "score"}], "name": "kraken", "value": 1502.0999999999997, "alertThreshold": 2.0}]} 17:02:04 INFO - Return code: 0 17:02:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:02:04 INFO - # TBPL SUCCESS # 17:02:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:02:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:02:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:02:04 INFO - 2016-10-14 17:02:04.927 screenresolution[1666:9704] starting screenresolution argv=screenresolution get 17:02:04 INFO - 2016-10-14 17:02:04.930 screenresolution[1666:9704] Display 0: 1600x1200x32@60 17:02:04 INFO - 2016-10-14 17:02:04.936 screenresolution[1667:9708] starting screenresolution argv=screenresolution list 17:02:04 INFO - Available Modes on Display 0 17:02:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:02:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:02:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:02:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:02:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:02:05 INFO - Intel Iris: 17:02:05 INFO - Chipset Model: Intel Iris 17:02:05 INFO - Type: GPU 17:02:05 INFO - Bus: Built-In 17:02:05 INFO - VRAM (Dynamic, Max): 1536 MB 17:02:05 INFO - Vendor: Intel (0x8086) 17:02:05 INFO - Device ID: 0x0a2e 17:02:05 INFO - Revision ID: 0x0009 17:02:05 INFO - Displays: 17:02:05 INFO - Display: 17:02:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:02:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:02:05 INFO - Display Serial Number: 1600x1200 60 17:02:05 INFO - Main Display: Yes 17:02:05 INFO - Mirror: Off 17:02:05 INFO - Online: Yes 17:02:05 INFO - Rotation: Supported 17:02:05 INFO - Return code: 0 17:02:05 INFO - Running post-action listener: _resource_record_post_action 17:02:05 INFO - [mozharness: 2016-10-15 00:02:05.171144Z] Finished run-tests step (success) 17:02:05 INFO - Running post-run listener: _resource_record_post_run 17:02:05 INFO - Total resource usage - Wall time: 1215s; CPU: 53.0%; Read bytes: 37389312; Write bytes: 707879936; Read time: 240; Write time: 1904 17:02:05 INFO - TinderboxPrint: CPU usage
53.3% 17:02:05 INFO - TinderboxPrint: I/O read bytes / time
37,389,312 / 240 17:02:05 INFO - TinderboxPrint: I/O write bytes / time
707,879,936 / 1,904 17:02:05 INFO - TinderboxPrint: CPU idle
2,271.4 (46.7%) 17:02:05 INFO - TinderboxPrint: CPU system
970.1 (20.0%) 17:02:05 INFO - TinderboxPrint: CPU user
1,620.2 (33.3%) 17:02:05 INFO - TinderboxPrint: Swap in / out
445,530,112 / 0 17:02:05 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 388786688; Write bytes: 383449088; Read time: 29082; Write time: 756 17:02:05 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 36414464; Write bytes: 296438784; Read time: 224; Write time: 1078 17:02:05 INFO - Running post-run listener: _upload_blobber_files 17:02:05 INFO - Blob upload gear active. 17:02:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:02:05 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 17:02:05 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:02:05 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:02:06 INFO - (blobuploader) - INFO - Open directory for files ... 17:02:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:02:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:02:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:02:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:02:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:02:06 INFO - (blobuploader) - INFO - Done attempting. 17:02:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:02:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:02:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:02:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:02:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:02:07 INFO - (blobuploader) - INFO - Done attempting. 17:02:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:02:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:02:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:02:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:02:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:02:09 INFO - (blobuploader) - INFO - Done attempting. 17:02:09 INFO - (blobuploader) - INFO - Iteration through files over. 17:02:09 INFO - Return code: 0 17:02:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:02:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:02:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b877d3b58cb04e0352f303855f81654060c43c8986ad81b120d15d40cfcfc9ed57f65fb61b3a16ac2d195254ca8a4c49d4384f8bbdb2dfad1c3b1ece667a0b8d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ce0f0d1be7d793a93b9d46f6bcd0eb7a2a2999922d4d8af6819758c49dce2849b7fb096f9e770e619366ae6971bf44b72af4cf10ffd39c882c69d78abefd67ab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c8b015690c7ab4e65ebbb61183a3a6fddc3716f6651682ff8567c9dba0a06e8abc56ab21ad54b54c81810d00e8837c0ed065a5b175390a45cbd0bf574f750c16"} 17:02:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:02:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:02:09 INFO - Contents: 17:02:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b877d3b58cb04e0352f303855f81654060c43c8986ad81b120d15d40cfcfc9ed57f65fb61b3a16ac2d195254ca8a4c49d4384f8bbdb2dfad1c3b1ece667a0b8d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ce0f0d1be7d793a93b9d46f6bcd0eb7a2a2999922d4d8af6819758c49dce2849b7fb096f9e770e619366ae6971bf44b72af4cf10ffd39c882c69d78abefd67ab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c8b015690c7ab4e65ebbb61183a3a6fddc3716f6651682ff8567c9dba0a06e8abc56ab21ad54b54c81810d00e8837c0ed065a5b175390a45cbd0bf574f750c16"} 17:02:09 INFO - Running post-run listener: copy_logs_to_upload_dir 17:02:09 INFO - Copying logs to upload dir... 17:02:09 INFO - mkdir: /builds/slave/test/build/upload/logs 17:02:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.187961 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-10-14 17:02:09.996596) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-14 17:02:10.000897) ========= 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.dtA442Gpcb/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.qnZMAoedMX/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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/Fx-Team/sha512/b877d3b58cb04e0352f303855f81654060c43c8986ad81b120d15d40cfcfc9ed57f65fb61b3a16ac2d195254ca8a4c49d4384f8bbdb2dfad1c3b1ece667a0b8d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ce0f0d1be7d793a93b9d46f6bcd0eb7a2a2999922d4d8af6819758c49dce2849b7fb096f9e770e619366ae6971bf44b72af4cf10ffd39c882c69d78abefd67ab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c8b015690c7ab4e65ebbb61183a3a6fddc3716f6651682ff8567c9dba0a06e8abc56ab21ad54b54c81810d00e8837c0ed065a5b175390a45cbd0bf574f750c16"} build_url:https://queue.taskcluster.net/v1/task/P105I_29Tpe0ivLowzCZsw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011003 build_url: 'https://queue.taskcluster.net/v1/task/P105I_29Tpe0ivLowzCZsw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b877d3b58cb04e0352f303855f81654060c43c8986ad81b120d15d40cfcfc9ed57f65fb61b3a16ac2d195254ca8a4c49d4384f8bbdb2dfad1c3b1ece667a0b8d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ce0f0d1be7d793a93b9d46f6bcd0eb7a2a2999922d4d8af6819758c49dce2849b7fb096f9e770e619366ae6971bf44b72af4cf10ffd39c882c69d78abefd67ab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c8b015690c7ab4e65ebbb61183a3a6fddc3716f6651682ff8567c9dba0a06e8abc56ab21ad54b54c81810d00e8837c0ed065a5b175390a45cbd0bf574f750c16"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-14 17:02:10.035540) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 17:02:10.035941) ========= 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.dtA442Gpcb/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.qnZMAoedMX/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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.004491 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 17:02:10.084087) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-14 17:02:10.084419) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-14 17:02:10.084748) ========= ========= Total master_lag: 0.18 =========